2013-08-13T21:55:02.139Z,1376430902.139 [Supervisor](DEBUG): Initializing supervisor. 2013-08-13T21:55:02.142Z,1376430902.142 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2013-08-13T21:55:02.143Z,1376430902.143 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2013-08-13T21:55:02.144Z,1376430902.144 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2013-08-13T21:55:02.148Z,1376430902.148 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2013-08-13T21:55:02.160Z,1376430902.160 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2013-08-13T21:55:02.161Z,1376430902.161 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2013-08-13T21:55:02.162Z,1376430902.162 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2013-08-13T21:55:02.163Z,1376430902.163 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0 2013-08-13T21:55:02.163Z,1376430902.163 [Supervisor](INFO): Looking for Config files in directory: Config/ 2013-08-13T21:55:02.165Z,1376430902.165 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2013-08-13T21:55:02.602Z,1376430902.602 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2013-08-13T21:55:02.602Z,1376430902.602 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2013-08-13T21:55:02.791Z,1376430902.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2013-08-13T21:55:02.791Z,1376430902.791 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2013-08-13T21:55:02.875Z,1376430902.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2013-08-13T21:55:02.876Z,1376430902.876 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2013-08-13T21:55:02.986Z,1376430902.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2013-08-13T21:55:02.986Z,1376430902.986 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2013-08-13T21:55:03.122Z,1376430903.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2013-08-13T21:55:03.123Z,1376430903.123 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2013-08-13T21:55:03.357Z,1376430903.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2013-08-13T21:55:03.357Z,1376430903.357 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2013-08-13T21:55:03.521Z,1376430903.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2013-08-13T21:55:03.522Z,1376430903.522 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2013-08-13T21:55:03.779Z,1376430903.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2013-08-13T21:55:03.780Z,1376430903.780 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2013-08-13T21:55:03.881Z,1376430903.881 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2013-08-13T21:55:03.881Z,1376430903.881 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2013-08-13T21:55:04.304Z,1376430904.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2013-08-13T21:55:04.304Z,1376430904.304 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2013-08-13T21:55:04.413Z,1376430904.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2013-08-13T21:55:04.414Z,1376430904.414 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2013-08-13T21:55:04.499Z,1376430904.499 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2013-08-13T21:55:04.500Z,1376430904.500 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2013-08-13T21:55:04.598Z,1376430904.598 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2013-08-13T21:55:04.723Z,1376430904.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2013-08-13T21:55:04.815Z,1376430904.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2013-08-13T21:55:04.919Z,1376430904.919 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2013-08-13T21:55:05.013Z,1376430905.013 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2013-08-13T21:55:05.126Z,1376430905.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2013-08-13T21:55:05.220Z,1376430905.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2013-08-13T21:55:05.305Z,1376430905.305 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/LOGIN/ 2013-08-13T21:55:05.306Z,1376430905.306 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2013-08-13T21:55:05.308Z,1376430905.308 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2013-08-13T21:55:05.470Z,1376430905.470 [InternalSim] Loaded 2013-08-13T21:55:05.470Z,1376430905.470 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2013-08-13T21:55:05.471Z,1376430905.471 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2013-08-13T21:55:05.472Z,1376430905.472 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2013-08-13T21:55:05.556Z,1376430905.556 [SBIT](DEBUG): Construct Startup Built In Test. 2013-08-13T21:55:05.584Z,1376430905.584 [SBIT] Loaded 2013-08-13T21:55:05.585Z,1376430905.585 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2013-08-13T21:55:05.585Z,1376430905.585 [IBIT](DEBUG): Construct Initiated Built In Test. 2013-08-13T21:55:05.614Z,1376430905.614 [IBIT] Loaded 2013-08-13T21:55:05.614Z,1376430905.614 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2013-08-13T21:55:05.616Z,1376430905.616 [CBIT](DEBUG): Construct CBIT Built In Test. 2013-08-13T21:55:05.729Z,1376430905.729 [CBIT] Loaded 2013-08-13T21:55:05.729Z,1376430905.729 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2013-08-13T21:55:05.729Z,1376430905.729 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2013-08-13T21:55:05.730Z,1376430905.730 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2013-08-13T21:55:05.897Z,1376430905.897 [BuoyancyServo] Loaded 2013-08-13T21:55:05.897Z,1376430905.897 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2013-08-13T21:55:05.909Z,1376430905.909 [ElevatorServo] Loaded 2013-08-13T21:55:05.909Z,1376430905.909 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2013-08-13T21:55:05.920Z,1376430905.920 [MassServo] Loaded 2013-08-13T21:55:05.920Z,1376430905.920 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2013-08-13T21:55:05.932Z,1376430905.932 [RudderServo] Loaded 2013-08-13T21:55:05.932Z,1376430905.932 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2013-08-13T21:55:05.943Z,1376430905.943 [ThrusterServo] Loaded 2013-08-13T21:55:05.943Z,1376430905.943 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2013-08-13T21:55:05.943Z,1376430905.943 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2013-08-13T21:55:05.944Z,1376430905.944 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2013-08-13T21:55:05.987Z,1376430905.987 [DepthRateCalculator] Loaded 2013-08-13T21:55:05.987Z,1376430905.987 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2013-08-13T21:55:11.017Z,1376430911.017 [HFRadarModelCalc] Loaded 2013-08-13T21:55:11.018Z,1376430911.018 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2013-08-13T21:55:11.033Z,1376430911.033 [NavChart] Loaded 2013-08-13T21:55:11.034Z,1376430911.034 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2013-08-13T21:55:11.039Z,1376430911.039 [PitchRateCalculator] Loaded 2013-08-13T21:55:11.039Z,1376430911.039 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2013-08-13T21:55:11.050Z,1376430911.050 [SpeedCalculator] Loaded 2013-08-13T21:55:11.050Z,1376430911.050 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2013-08-13T21:55:11.066Z,1376430911.066 [TempGradientCalculator] Loaded 2013-08-13T21:55:11.067Z,1376430911.067 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2013-08-13T21:55:11.072Z,1376430911.072 [YawRateCalculator] Loaded 2013-08-13T21:55:11.072Z,1376430911.072 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2013-08-13T21:55:11.118Z,1376430911.118 [Navigation] Loaded 2013-08-13T21:55:11.118Z,1376430911.118 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2013-08-13T21:55:11.118Z,1376430911.118 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2013-08-13T21:55:11.119Z,1376430911.119 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2013-08-13T21:55:11.351Z,1376430911.351 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2013-08-13T21:55:11.352Z,1376430911.352 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2013-08-13T21:55:11.375Z,1376430911.375 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2013-08-13T21:55:11.376Z,1376430911.376 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2013-08-13T21:55:11.422Z,1376430911.422 [VerticalControl](DEBUG): Construct VerticalControl. 2013-08-13T21:55:11.513Z,1376430911.513 [VerticalControl] Loaded 2013-08-13T21:55:11.513Z,1376430911.513 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2013-08-13T21:55:11.514Z,1376430911.514 [HorizontalControl](DEBUG): Construct HorizontalControl. 2013-08-13T21:55:11.571Z,1376430911.571 [HorizontalControl] Loaded 2013-08-13T21:55:11.571Z,1376430911.571 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2013-08-13T21:55:11.572Z,1376430911.572 [SpeedControl](DEBUG): Construct SpeedControl. 2013-08-13T21:55:11.574Z,1376430911.574 [SpeedControl] Loaded 2013-08-13T21:55:11.574Z,1376430911.574 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2013-08-13T21:55:11.574Z,1376430911.574 [LoopControl](DEBUG): Construct LoopControl. 2013-08-13T21:55:11.575Z,1376430911.575 [LoopControl] Loaded 2013-08-13T21:55:11.575Z,1376430911.575 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2013-08-13T21:55:11.576Z,1376430911.576 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2013-08-13T21:55:11.576Z,1376430911.576 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2013-08-13T21:55:11.582Z,1376430911.582 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2013-08-13T21:55:11.587Z,1376430911.587 [AsyncPiEstimator] Loaded 2013-08-13T21:55:11.587Z,1376430911.587 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2013-08-13T21:55:11.590Z,1376430911.590 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406474E0 2013-08-13T21:55:11.590Z,1376430911.590 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2013-08-13T21:55:11.591Z,1376430911.591 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2013-08-13T21:55:11.786Z,1376430911.786 [AHRS_sp3003D] Loaded 2013-08-13T21:55:11.786Z,1376430911.786 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2013-08-13T21:55:12.032Z,1376430912.032 [Batt_Ocean_Server] Loaded 2013-08-13T21:55:12.033Z,1376430912.033 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2013-08-13T21:55:12.046Z,1376430912.046 [Depth_Keller] Loaded 2013-08-13T21:55:12.046Z,1376430912.046 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2013-08-13T21:55:12.051Z,1376430912.051 [DropWeight] Loaded 2013-08-13T21:55:12.052Z,1376430912.052 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2013-08-13T21:55:12.230Z,1376430912.230 [DVL_micro] Loaded 2013-08-13T21:55:12.231Z,1376430912.231 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2013-08-13T21:55:12.232Z,1376430912.232 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406D74E0 2013-08-13T21:55:12.317Z,1376430912.317 [NAL9602] Loaded 2013-08-13T21:55:12.317Z,1376430912.317 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2013-08-13T21:55:12.358Z,1376430912.358 [Onboard] Loaded 2013-08-13T21:55:12.359Z,1376430912.359 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2013-08-13T21:55:12.365Z,1376430912.365 [Radio_Freewave] Loaded 2013-08-13T21:55:12.366Z,1376430912.366 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2013-08-13T21:55:12.523Z,1376430912.523 [DAT] Loaded 2013-08-13T21:55:12.524Z,1376430912.524 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2013-08-13T21:55:12.524Z,1376430912.524 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2013-08-13T21:55:12.525Z,1376430912.525 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2013-08-13T21:55:12.609Z,1376430912.609 [Aanderaa_O2] Loaded 2013-08-13T21:55:12.609Z,1376430912.609 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2013-08-13T21:55:12.610Z,1376430912.610 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2013-08-13T21:55:12.612Z,1376430912.612 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2013-08-13T21:55:12.613Z,1376430912.613 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2013-08-13T21:55:12.620Z,1376430912.620 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2013-08-13T21:55:12.621Z,1376430912.621 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407344E0 2013-08-13T21:55:12.626Z,1376430912.626 [Supervisor](DEBUG): Running supervisor. 2013-08-13T21:55:12.627Z,1376430912.627 [CommandLine](INFO): Thread ID is 894 2013-08-13T21:55:12.629Z,1376430912.629 [controlThread](INFO): Thread ID is 893 2013-08-13T21:55:12.629Z,1376430912.629 [controlThread](DEBUG): Initializing ControlThread 2013-08-13T21:55:12.630Z,1376430912.630 [CycleStarter](INFO): Thread ID is 892 2013-08-13T21:55:12.630Z,1376430912.630 [InternalSim](DEBUG): InternalSim initializing... 2013-08-13T21:55:12.663Z,1376430912.663 [logger](INFO): Thread ID is 895 2013-08-13T21:55:12.690Z,1376430912.690 [SBIT](INFO): Initialize SBIT Component. 2013-08-13T21:55:12.691Z,1376430912.691 [SBIT](IMPORTANT): Tethys CM Info: $Rev:10508 2013-08-13T21:55:12.691Z,1376430912.691 [SBIT](IMPORTANT): Kernel Release:2.6.27.8 2013-08-13T21:55:12.691Z,1376430912.691 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013 2013-08-13T21:55:12.692Z,1376430912.692 [IBIT](INFO): Initialize IBIT Component. 2013-08-13T21:55:12.693Z,1376430912.693 [CBIT](DEBUG): Initialize CBIT Component. 2013-08-13T21:55:12.693Z,1376430912.693 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2013-08-13T21:55:12.694Z,1376430912.694 [AsyncPiEstimator](INFO): Thread ID is 956 2013-08-13T21:55:12.694Z,1376430912.694 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2013-08-13T21:55:12.711Z,1376430912.711 [DVL_micro](INFO): Thread ID is 957 2013-08-13T21:55:12.720Z,1376430912.720 [DVL_micro](INFO): Initializing 2013-08-13T21:55:12.720Z,1376430912.720 [DVL_micro](INFO): start:Powering up 2013-08-13T21:55:12.721Z,1376430912.721 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-08-13T21:55:12.722Z,1376430912.722 [DVL_micro](INFO): Cycling power to configure device. 2013-08-13T21:55:12.723Z,1376430912.723 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2013-08-13T21:55:12.724Z,1376430912.724 [NavChart](DEBUG): Initialize NavChart Derivation. 2013-08-13T21:55:12.724Z,1376430912.724 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2013-08-13T21:55:12.725Z,1376430912.725 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2013-08-13T21:55:12.725Z,1376430912.725 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2013-08-13T21:55:12.726Z,1376430912.726 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2013-08-13T21:55:12.726Z,1376430912.726 [Navigation](DEBUG): Initializing Navigation. 2013-08-13T21:55:12.727Z,1376430912.727 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2013-08-13T21:55:12.728Z,1376430912.728 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2013-08-13T21:55:12.729Z,1376430912.729 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2013-08-13T21:55:12.730Z,1376430912.730 [LoopControl](DEBUG): Initialize LoopControlComponent. 2013-08-13T21:55:12.751Z,1376430912.751 [NavChartDb](INFO): Thread ID is 958 2013-08-13T21:55:12.754Z,1376430912.754 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2013-08-13T21:55:12.755Z,1376430912.755 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2013-08-13T21:55:12.755Z,1376430912.755 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2013-08-13T21:55:12.755Z,1376430912.755 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2013-08-13T21:55:12.756Z,1376430912.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2013-08-13T21:55:12.756Z,1376430912.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2013-08-13T21:55:12.756Z,1376430912.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2013-08-13T21:55:12.756Z,1376430912.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2013-08-13T21:55:12.757Z,1376430912.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2013-08-13T21:55:14.225Z,1376430914.225 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2013-08-13T21:55:14.256Z,1376430914.256 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2013-08-13T21:55:14.283Z,1376430914.283 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2013-08-13T21:55:14.322Z,1376430914.322 [MissionManager](DEBUG): 2013-08-13T21:55:14.323Z,1376430914.323 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2013-08-13T21:55:14.401Z,1376430914.401 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2013-08-13T21:55:14.403Z,1376430914.403 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2013-08-13T21:55:14.406Z,1376430914.406 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-08-13T21:55:14.427Z,1376430914.427 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2013-08-13T21:55:14.442Z,1376430914.442 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-08-13T21:55:14.448Z,1376430914.448 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2013-08-13T21:55:14.492Z,1376430914.492 [Default:D.SetSpeed](DEBUG): Construct. 2013-08-13T21:55:14.496Z,1376430914.496 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2013-08-13T21:55:14.516Z,1376430914.516 [Default:F.Wait](DEBUG): Construct Wait. 2013-08-13T21:55:14.520Z,1376430914.520 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2013-08-13T21:55:14.533Z,1376430914.533 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,DAT,Aanderaa_O2,Depth_Keller,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter, 2013-08-13T21:55:14.595Z,1376430914.595 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2013-08-13T21:55:14.626Z,1376430914.626 [Depth_Keller](ERROR): Pressure reading out of range: 1831.078247 decibar 2013-08-13T21:55:14.715Z,1376430914.715 [Radio_Freewave](INFO): Powering up 2013-08-13T21:55:14.722Z,1376430914.722 [DAT](INFO): Powering up 2013-08-13T21:55:14.722Z,1376430914.722 [DAT](DEBUG): Initializing DAT. 2013-08-13T21:55:14.802Z,1376430914.802 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-08-13T21:55:14.802Z,1376430914.802 [DVL_micro](INFO): Querying output modes 2013-08-13T21:55:14.803Z,1376430914.803 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2013-08-13T21:55:14.814Z,1376430914.814 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2013-08-13T21:55:14.814Z,1376430914.814 [DVL_micro](INFO): NQ1 output enabled 2013-08-13T21:55:14.814Z,1376430914.814 [DVL_micro](INFO): RSSI output enabled 2013-08-13T21:55:14.814Z,1376430914.814 [DVL_micro](INFO): ADCP output enabled 2013-08-13T21:55:14.814Z,1376430914.814 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2013-08-13T21:55:14.826Z,1376430914.826 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2013-08-13T21:55:14.831Z,1376430914.831 [DVL_micro](INFO): pause:Powering down 2013-08-13T21:55:14.982Z,1376430914.982 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2013-08-13T21:55:14.990Z,1376430914.990 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2013-08-13T21:55:15.012Z,1376430915.012 [ElevatorServo](DEBUG): Initializing EZServoServo. 2013-08-13T21:55:15.017Z,1376430915.017 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2013-08-13T21:55:15.031Z,1376430915.031 [MassServo](DEBUG): Initializing EZServoServo. 2013-08-13T21:55:15.040Z,1376430915.040 [MassServo](DEBUG): Initializing MassServo. 2013-08-13T21:55:15.058Z,1376430915.058 [RudderServo](DEBUG): Initializing EZServoServo. 2013-08-13T21:55:15.090Z,1376430915.090 [RudderServo](DEBUG): Initializing RudderServo. 2013-08-13T21:55:15.095Z,1376430915.095 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-08-13T21:55:15.102Z,1376430915.102 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-08-13T21:55:15.386Z,1376430915.386 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout 2013-08-13T21:55:15.387Z,1376430915.387 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0x42360D0A 2013-08-13T21:55:15.387Z,1376430915.387 [AHRS_sp3003D](ERROR): SP3003D failed to initialize 2013-08-13T21:55:15.387Z,1376430915.387 [AHRS_sp3003D] Hardware Fault, FailCount= 1 2013-08-13T21:55:15.387Z,1376430915.387 [AHRS_sp3003D](ERROR): Hardware Fault 2013-08-13T21:55:15.635Z,1376430915.635 [DVL_micro](INFO): RSSI unrequested 2013-08-13T21:55:15.635Z,1376430915.635 [DVL_micro](INFO): ADCP unrequested 2013-08-13T21:55:15.636Z,1376430915.636 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-08-13T21:55:15.636Z,1376430915.636 [DVL_micro](INFO): resume:Powering up 2013-08-13T21:55:15.636Z,1376430915.636 [DVL_micro](INFO): Cycling power to configure device. 2013-08-13T21:55:16.095Z,1376430916.095 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2013-08-13T21:55:16.096Z,1376430916.096 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2013-08-13T21:55:16.096Z,1376430916.096 [BuoyancyServo] Communications Fault, FailCount= 1 2013-08-13T21:55:16.096Z,1376430916.096 [BuoyancyServo](ERROR): Communications Fault 2013-08-13T21:55:16.298Z,1376430916.298 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2013-08-13T21:55:16.298Z,1376430916.298 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D 2013-08-13T21:55:16.328Z,1376430916.328 [AHRS_sp3003D](INFO): Powering down 2013-08-13T21:55:16.456Z,1376430916.456 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2013-08-13T21:55:16.457Z,1376430916.457 [BuoyancyServo](INFO): Powering down 2013-08-13T21:55:17.642Z,1376430917.642 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-08-13T21:55:17.642Z,1376430917.642 [DVL_micro](INFO): Querying output modes 2013-08-13T21:55:17.643Z,1376430917.643 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2013-08-13T21:55:17.654Z,1376430917.654 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2013-08-13T21:55:17.654Z,1376430917.654 [DVL_micro](INFO): NQ1 output enabled 2013-08-13T21:55:17.654Z,1376430917.654 [DVL_micro](INFO): RSSI output enabled 2013-08-13T21:55:17.654Z,1376430917.654 [DVL_micro](INFO): ADCP output enabled 2013-08-13T21:55:17.655Z,1376430917.655 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2013-08-13T21:55:17.666Z,1376430917.666 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2013-08-13T21:55:17.668Z,1376430917.668 [DVL_micro](INFO): RSSI unrequested 2013-08-13T21:55:17.668Z,1376430917.668 [DVL_micro](INFO): ADCP unrequested 2013-08-13T21:55:17.668Z,1376430917.668 [DVL_micro](INFO): Cycling power to configure device. 2013-08-13T21:55:17.730Z,1376430917.730 [Aanderaa_O2](INFO): Powering down 2013-08-13T21:55:17.906Z,1376430917.906 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D 2013-08-13T21:55:17.906Z,1376430917.906 [AHRS_sp3003D] No Fault, FailCount= 1 2013-08-13T21:55:17.927Z,1376430917.927 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2013-08-13T21:55:19.071Z,1376430919.071 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2013-08-13T21:55:19.072Z,1376430919.072 [BuoyancyServo] No Fault, FailCount= 1 2013-08-13T21:55:19.453Z,1376430919.453 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2013-08-13T21:55:19.577Z,1376430919.577 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2013-08-13T21:55:19.673Z,1376430919.673 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-08-13T21:55:19.674Z,1376430919.674 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0 2013-08-13T21:55:19.687Z,1376430919.687 [DVL_micro](DEBUG): cmdResponse: „tâZâjâgâcâ]âQâWâWâ‘âØâ³âŽâˆâ—â|â}âdâ\â<âGâKâ@âTâTâUâWâtâqââ‰âaâ_âpâlâ[âeâjâ‰âwâZâ^âGâiâ}â‚ânâyâf 2013-08-13T21:55:19.687Z,1376430919.687 [DVL_micro](INFO): Enabling NQ1 output 2013-08-13T21:55:19.688Z,1376430919.688 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1 2013-08-13T21:55:19.704Z,1376430919.704 [DVL_micro](DEBUG): cmdResponse: â€â€â{âyâYâHâaâdâxâ}âeâ¾âðâ¿ââoâEâSâTâFâgâœâ‹â^âhâ{âmâyâ‚â`âZâfâ_â¬â§â™âŽâ_ârâXâ_â¨â£âŸâ§â†â~â’â­ 2013-08-13T21:55:19.704Z,1376430919.704 [DVL_micro](INFO): Querying output modes 2013-08-13T21:55:19.704Z,1376430919.704 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2013-08-13T21:55:19.720Z,1376430919.720 [DVL_micro](DEBUG): cmdResponse: â¢âªâÁâƒâlâqâ]âMââ›â}â{âkâyâ{âoâsââ«â±âšâ‡âhâkâ„â•â£â§â|âvâˆâ‰âqâ¢âyâ[âhâˆâ¡â¬ââtâ“â½âƒâ}âcâMâQ 2013-08-13T21:55:19.720Z,1376430919.720 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2013-08-13T21:55:19.736Z,1376430919.736 [DVL_micro](DEBUG): cmdResponse: â…â–â™âuâaâWâ•ââtââmâLâ]â—â±â€â^âÁâ 2013-08-13T21:55:21.742Z,1376430921.742 [DVL_micro](INFO): pause:Powering down 2013-08-13T21:55:26.728Z,1376430926.728 [CommandLine](IMPORTANT): got command failComponent 2013-08-13T21:55:26.730Z,1376430926.730 [CommandLine](IMPORTANT): Failed components: 2013-08-13T21:55:26.730Z,1376430926.730 [CommandLine](IMPORTANT): No failed Components. 2013-08-13T21:55:28.297Z,1376430928.297 [SBIT](IMPORTANT): Beginning Startup BIT 2013-08-13T21:55:28.300Z,1376430928.300 [CBIT](IMPORTANT): Beginning GF scan 2013-08-13T21:55:34.296Z,1376430934.296 [DAT](INFO): Init failed - response: 2013-08-13T21:55:34.296Z,1376430934.296 [DAT](FAULT): DAT failed to initialize 2013-08-13T21:55:34.296Z,1376430934.296 [DAT] Communications Fault, FailCount= 1 2013-08-13T21:55:34.296Z,1376430934.296 [DAT](ERROR): Communications Fault 2013-08-13T21:55:34.352Z,1376430934.352 [CBIT](ERROR): Communications Fault in component: DAT 2013-08-13T21:55:34.577Z,1376430934.577 [DAT](INFO): Powering down 2013-08-13T21:55:35.487Z,1376430935.487 [CBIT](INFO): Clearing failed state for component DAT 2013-08-13T21:55:35.487Z,1376430935.487 [DAT] No Fault, FailCount= 1 2013-08-13T21:55:37.840Z,1376430937.840 [DAT](INFO): Powering up 2013-08-13T21:55:37.840Z,1376430937.840 [DAT](DEBUG): Initializing DAT. 2013-08-13T21:55:45.061Z,1376430945.061 [CommandLine](IMPORTANT): got command failComponent 2013-08-13T21:55:45.061Z,1376430945.061 [CommandLine](IMPORTANT): Failed components: 2013-08-13T21:55:45.061Z,1376430945.061 [CommandLine](IMPORTANT): No failed Components. 2013-08-13T21:55:55.039Z,1376430955.039 [CBIT](IMPORTANT): No ground fault detected 2013-08-13T21:55:57.784Z,1376430957.784 [DAT](INFO): Powering down 2013-08-13T21:56:10.921Z,1376430970.921 [CommandLine](IMPORTANT): got command show variable aand 2013-08-13T21:56:10.931Z,1376430970.931 [CommandLine](INFO): Aanderaa_O2.loadControl (none) 2013-08-13T21:56:10.932Z,1376430970.932 [CommandLine](INFO): Aanderaa_O2.uart (none) 2013-08-13T21:56:10.932Z,1376430970.932 [CommandLine](INFO): Aanderaa_O2.baud (bit_per_second) 2013-08-13T21:56:10.944Z,1376430970.944 [CommandLine](INFO): Aanderaa_O2.loadAtStartup (bool) 2013-08-13T21:56:10.945Z,1376430970.945 [CommandLine](INFO): Aanderaa_O2.simulateHardware (bool) 2013-08-13T21:56:10.945Z,1376430970.945 [CommandLine](INFO): Aanderaa_O2.power (watt) 2013-08-13T21:56:10.945Z,1376430970.945 [CommandLine](INFO): Aanderaa_O2.model (none) 2013-08-13T21:56:11.001Z,1376430971.001 [CommandLine](INFO): Aanderaa_O2.temperature (celsius) 2013-08-13T21:56:11.002Z,1376430971.002 [CommandLine](INFO): Aanderaa_O2.airSaturation (percent) 2013-08-13T21:56:11.002Z,1376430971.002 [CommandLine](INFO): Aanderaa_O2.mass_concentration_of_oxygen_in_sea_water (microgram_per_liter) 2013-08-13T21:56:11.004Z,1376430971.004 [CommandLine](INFO): Aanderaa_O2.durationOfLastRun (second) 2013-08-13T21:56:11.007Z,1376430971.007 [CommandLine](INFO): Aanderaa_O2.component_voltage (volt) 2013-08-13T21:56:11.007Z,1376430971.007 [CommandLine](INFO): Aanderaa_O2.component_avgVoltage (volt) 2013-08-13T21:56:11.008Z,1376430971.008 [CommandLine](INFO): Aanderaa_O2.component_current (milliampere) 2013-08-13T21:56:11.008Z,1376430971.008 [CommandLine](INFO): Aanderaa_O2.component_avgCurrent (milliampere) 2013-08-13T21:56:17.972Z,1376430977.972 [CommandLine](IMPORTANT): got command report touch Aanderaa_O2.mass_concentration_of_oxygen_in_sea_water 2013-08-13T21:56:22.392Z,1376430982.392 [SBIT](IMPORTANT): SBIT PASSED 2013-08-13T21:56:22.817Z,1376430982.817 [MissionManager](IMPORTANT): Started mission Startup 2013-08-13T21:56:22.817Z,1376430982.817 [Startup] Running Loop=1 2013-08-13T21:56:22.817Z,1376430982.817 [Startup](INFO): Aggregate::initialize Startup 2013-08-13T21:56:22.818Z,1376430982.818 [Startup:A.GoToSurface] Running Loop=1 2013-08-13T21:56:22.818Z,1376430982.818 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-08-13T21:56:22.823Z,1376430982.823 [Startup:StartupSatComms] Running Loop=1 2013-08-13T21:56:22.823Z,1376430982.823 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2013-08-13T21:56:22.824Z,1376430982.824 [Startup:StartupSatComms:A] Running Loop=1 2013-08-13T21:56:23.203Z,1376430983.203 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2013-08-13T21:56:25.163Z,1376430985.163 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2013-08-13T21:56:35.431Z,1376430995.431 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree 2013-08-13T21:56:35.608Z,1376430995.608 [Startup:StartupSatComms:A] Stopped 2013-08-13T21:56:35.608Z,1376430995.608 [Startup:StartupSatComms:B] Running Loop=1 2013-08-13T21:56:36.108Z,1376430996.108 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2013-08-13T21:56:42.874Z,1376431002.874 [Startup:StartupSatComms:B] Stopped 2013-08-13T21:56:42.874Z,1376431002.874 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2013-08-13T21:56:42.874Z,1376431002.874 [Startup:StartupSatComms] Stopped 2013-08-13T21:56:42.874Z,1376431002.874 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2013-08-13T21:56:42.875Z,1376431002.875 [Startup](INFO): Completed Startup 2013-08-13T21:56:42.875Z,1376431002.875 [Startup] Stopped 2013-08-13T21:56:42.875Z,1376431002.875 [Startup](INFO): Aggregate::uninitialize Startup 2013-08-13T21:56:42.875Z,1376431002.875 [Startup:A.GoToSurface] Stopped 2013-08-13T21:56:42.876Z,1376431002.876 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-08-13T21:56:43.399Z,1376431003.399 [MissionManager](IMPORTANT): Started mission Default 2013-08-13T21:56:43.399Z,1376431003.399 [Default] Running Loop=1 2013-08-13T21:56:43.399Z,1376431003.399 [Default](INFO): Aggregate::initialize Default 2013-08-13T21:56:43.399Z,1376431003.399 [Default:D.SetSpeed] Running Loop=1 2013-08-13T21:56:43.399Z,1376431003.399 [Default:D.SetSpeed](DEBUG): Initialize. 2013-08-13T21:56:43.399Z,1376431003.399 [Default:E.GoToSurface] Running Loop=1 2013-08-13T21:56:43.399Z,1376431003.399 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-08-13T21:56:43.399Z,1376431003.399 [Default:Iridium] Running Loop=1 2013-08-13T21:56:43.400Z,1376431003.400 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2013-08-13T21:56:43.400Z,1376431003.400 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-08-13T21:56:43.400Z,1376431003.400 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2013-08-13T21:56:43.400Z,1376431003.400 [Default:Iridium:B.GoToSurface] Running Loop=1 2013-08-13T21:56:43.400Z,1376431003.400 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-08-13T21:56:43.400Z,1376431003.400 [Default:E.GoToSurface] Running Loop=1 2013-08-13T21:56:43.406Z,1376431003.406 [Default:D.SetSpeed] Running Loop=1 2013-08-13T21:56:43.416Z,1376431003.416 [Default:Iridium:B.GoToSurface] Stopped 2013-08-13T21:56:43.416Z,1376431003.416 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-08-13T21:56:43.416Z,1376431003.416 [Default:Iridium:Read_Iridium] Running Loop=1 2013-08-13T21:56:43.416Z,1376431003.416 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-08-13T21:56:43.421Z,1376431003.421 [Default:GPS] Running Loop=1 2013-08-13T21:56:43.421Z,1376431003.421 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2013-08-13T21:56:43.421Z,1376431003.421 [Default:GPS:A.SetSpeed] Running Loop=1 2013-08-13T21:56:43.421Z,1376431003.421 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2013-08-13T21:56:43.421Z,1376431003.421 [Default:GPS:B.GoToSurface] Running Loop=1 2013-08-13T21:56:43.421Z,1376431003.421 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-08-13T21:56:43.426Z,1376431003.426 [Default:GPS:B.GoToSurface] Stopped 2013-08-13T21:56:43.426Z,1376431003.426 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-08-13T21:56:43.427Z,1376431003.427 [Default:GPS:Read_GPS] Running Loop=1 2013-08-13T21:56:43.427Z,1376431003.427 [Default:GPS:A.SetSpeed] Running Loop=1 2013-08-13T21:56:43.635Z,1376431003.635 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2013-08-13T21:56:43.638Z,1376431003.638 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2013-08-13T21:56:46.869Z,1376431006.869 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2013-08-13T21:56:50.724Z,1376431010.724 [Default:Iridium:Read_Iridium] Stopped 2013-08-13T21:56:50.725Z,1376431010.725 [Default:Iridium](INFO): Completed Default:Iridium 2013-08-13T21:56:50.725Z,1376431010.725 [Default:Iridium] Stopped 2013-08-13T21:56:50.725Z,1376431010.725 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2013-08-13T21:56:50.725Z,1376431010.725 [Default:Iridium:A.SetSpeed] Stopped 2013-08-13T21:56:50.725Z,1376431010.725 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2013-08-13T21:56:50.725Z,1376431010.725 [Default:F.Wait] Running Loop=1 2013-08-13T21:56:50.725Z,1376431010.725 [Default:F.Wait](DEBUG): Initialize Wait Component. 2013-08-13T21:56:52.590Z,1376431012.590 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/tank_weight.xml 2013-08-13T21:56:52.590Z,1376431012.590 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/tank_weight.xml 2013-08-13T21:56:52.646Z,1376431012.646 [MissionManager](INFO): DefineArg tank_weight.BuoyancyLimitHiCC = 0.000955 n/a 2013-08-13T21:56:52.650Z,1376431012.650 [MissionManager](INFO): DefineArg tank_weight.BuoyancyLimitLoCC = 0.000080 n/a 2013-08-13T21:56:52.652Z,1376431012.652 [tank_weight:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2013-08-13T21:56:52.660Z,1376431012.660 [tank_weight:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2013-08-13T21:56:52.669Z,1376431012.669 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2013-08-13T21:56:52.917Z,1376431012.917 [MissionManager](INFO): DefineArg tank_weight:Science.SampleISUS = 0 bool 2013-08-13T21:56:52.920Z,1376431012.920 [MissionManager](INFO): DefineArg tank_weight:Science.SampleISUSmaxPitch = 60.000000 arcdeg 2013-08-13T21:56:52.943Z,1376431012.943 [MissionManager](INFO): DefineArg tank_weight:Science.SampleISUSminPitch = -60.000000 arcdeg 2013-08-13T21:56:52.946Z,1376431012.946 [MissionManager](INFO): DefineArg tank_weight:Science.SampleRSSI = 1 bool 2013-08-13T21:56:52.948Z,1376431012.948 [MissionManager](INFO): DefineArg tank_weight:Science.SampleADCP = 1 bool 2013-08-13T21:56:52.963Z,1376431012.963 [MissionManager](INFO): DefineArg tank_weight:Science.PeakDetectChlActive = 0 bool 2013-08-13T21:56:52.966Z,1376431012.966 [MissionManager](INFO): DefineArg tank_weight:Science.PeakDetectNO3Active = 0 bool 2013-08-13T21:56:52.969Z,1376431012.969 [MissionManager](INFO): DefineArg tank_weight:Science.PeakDetectRSSIActive = 0 bool 2013-08-13T21:56:52.984Z,1376431012.984 [MissionManager](INFO): DefineArg tank_weight:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2013-08-13T21:56:53.023Z,1376431013.023 [MissionManager](INFO): DefineArg tank_weight:Science.ComputeWaterCurrent = 0 bool 2013-08-13T21:56:53.026Z,1376431013.026 [MissionManager](INFO): DefineArg tank_weight:Science.ComputeWaterCurrentMinDepth = 2.000000 m 2013-08-13T21:56:53.047Z,1376431013.047 [MissionManager](INFO): DefineArg tank_weight:Science.ComputeWaterCurrentMaxDepth = 5.000000 m 2013-08-13T21:56:53.050Z,1376431013.050 [MissionManager](INFO): DefineArg tank_weight:Science.EnabledAanderaaO2 = 1.000000 n/a 2013-08-13T21:56:53.071Z,1376431013.071 [MissionManager](INFO): DefineArg tank_weight:Science.EnabledNeilBrown = 0.000000 n/a 2013-08-13T21:56:53.074Z,1376431013.074 [MissionManager](INFO): DefineArg tank_weight:Science.EnabledTurbulence_NPS = 0.000000 n/a 2013-08-13T21:56:53.102Z,1376431013.102 [MissionManager](INFO): DefineArg tank_weight:Science.EnabledWetLabsBB2FL = 0.000000 n/a 2013-08-13T21:56:53.106Z,1376431013.106 [MissionManager](INFO): DefineArg tank_weight:Science.EnabledDVL = 1.000000 n/a 2013-08-13T21:56:53.109Z,1376431013.109 [MissionManager](INFO): DefineArg tank_weight:Science.EnabledISUS = 0.000000 n/a 2013-08-13T21:56:53.132Z,1376431013.132 [MissionManager](INFO): DefineArg tank_weight:Science.HFRadarModelActive = 0 bool 2013-08-13T21:56:53.135Z,1376431013.135 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChl = 0.000000 ug/l 2013-08-13T21:56:53.176Z,1376431013.176 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChlDepth = 0.000000 m 2013-08-13T21:56:53.219Z,1376431013.219 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChlLatitude = nan arcdeg 2013-08-13T21:56:53.223Z,1376431013.223 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChlLongitude = nan arcdeg 2013-08-13T21:56:53.226Z,1376431013.226 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3 = 0.000000 umol/l 2013-08-13T21:56:53.241Z,1376431013.241 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3Depth = 0.000000 m 2013-08-13T21:56:53.244Z,1376431013.244 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3Latitude = nan arcdeg 2013-08-13T21:56:53.247Z,1376431013.247 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3Longitude = nan arcdeg 2013-08-13T21:56:53.264Z,1376431013.264 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakRSSI = 0.000000 dB 2013-08-13T21:56:53.267Z,1376431013.267 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakRSSIDepth = 0.000000 m 2013-08-13T21:56:53.270Z,1376431013.270 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakRSSILatitude = nan arcdeg 2013-08-13T21:56:53.274Z,1376431013.274 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakRSSILongitude = nan arcdeg 2013-08-13T21:56:53.317Z,1376431013.317 [MissionManager](INFO): DefineOutput tank_weight:Science.eastwardSurfaceCurrent = nan m/s 2013-08-13T21:56:53.329Z,1376431013.329 [MissionManager](INFO): DefineOutput tank_weight:Science.northwardSurfaceCurrent = nan m/s 2013-08-13T21:56:53.364Z,1376431013.364 [tank_weight:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2013-08-13T21:56:53.419Z,1376431013.419 [tank_weight:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2013-08-13T21:56:53.462Z,1376431013.462 [tank_weight:Science:PeakDetectRSSI:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2013-08-13T21:56:53.521Z,1376431013.521 [MissionManager](INFO): RedefineArg tank_weight:Science.SampleISUS = 1 bool 2013-08-13T21:56:53.523Z,1376431013.523 [tank_weight:D:A.SetSpeed](DEBUG): Construct. 2013-08-13T21:56:53.577Z,1376431013.577 [tank_weight:D:BuoyancyLo:A.Buoyancy](DEBUG): Construct Buoyancy. 2013-08-13T21:56:53.581Z,1376431013.581 [tank_weight:D:BuoyancyLo:B.Pitch](DEBUG): Construct. 2013-08-13T21:56:53.619Z,1376431013.619 [tank_weight:D:BuoyancyLo:C.Wait](DEBUG): Construct Wait. 2013-08-13T21:56:53.622Z,1376431013.622 [tank_weight:D:BuoyancyHi:A.Buoyancy](DEBUG): Construct Buoyancy. 2013-08-13T21:56:53.629Z,1376431013.629 [tank_weight:D:BuoyancyHi:B.Pitch](DEBUG): Construct. 2013-08-13T21:56:53.645Z,1376431013.645 [tank_weight:D:BuoyancyHi:C.Wait](DEBUG): Construct Wait. 2013-08-13T21:56:53.671Z,1376431013.671 [MissionManager](DEBUG): 0 0 15 0 0 15 65 0 15 5 2013-08-13T21:56:53.674Z,1376431013.674 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/tank_weight.xml 2013-08-13T21:56:53.790Z,1376431013.790 [Default] Stopped 2013-08-13T21:56:53.790Z,1376431013.790 [Default](INFO): Aggregate::uninitialize Default 2013-08-13T21:56:53.790Z,1376431013.790 [Default:GPS] Stopped 2013-08-13T21:56:53.790Z,1376431013.790 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2013-08-13T21:56:53.790Z,1376431013.790 [Default:GPS:A.SetSpeed] Stopped 2013-08-13T21:56:53.790Z,1376431013.790 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2013-08-13T21:56:53.811Z,1376431013.811 [Default:GPS:Read_GPS] Stopped 2013-08-13T21:56:53.811Z,1376431013.811 [Default:D.SetSpeed] Stopped 2013-08-13T21:56:53.811Z,1376431013.811 [Default:D.SetSpeed](DEBUG): Uninitialize. 2013-08-13T21:56:53.811Z,1376431013.811 [Default:E.GoToSurface] Stopped 2013-08-13T21:56:53.811Z,1376431013.811 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-08-13T21:56:53.811Z,1376431013.811 [Default:F.Wait] Stopped 2013-08-13T21:56:53.811Z,1376431013.811 [Default:F.Wait](DEBUG): Uninitialize Wait Component. 2013-08-13T21:56:53.811Z,1376431013.811 [MissionManager](IMPORTANT): Started mission tank_weight 2013-08-13T21:56:53.812Z,1376431013.812 [tank_weight] Running Loop=1 2013-08-13T21:56:53.812Z,1376431013.812 [tank_weight](INFO): Aggregate::initialize tank_weight 2013-08-13T21:56:53.812Z,1376431013.812 [tank_weight:A.AltitudeEnvelope] Running Loop=1 2013-08-13T21:56:53.812Z,1376431013.812 [tank_weight:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2013-08-13T21:56:53.812Z,1376431013.812 [tank_weight:B.DepthEnvelope] Running Loop=1 2013-08-13T21:56:53.812Z,1376431013.812 [tank_weight:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2013-08-13T21:56:53.813Z,1376431013.813 [tank_weight:Science] Running Loop=1 2013-08-13T21:56:53.813Z,1376431013.813 [tank_weight:Science](INFO): Aggregate::initialize tank_weight:Science 2013-08-13T21:56:53.813Z,1376431013.813 [tank_weight:Science:A] Running Loop=1 2013-08-13T21:56:53.813Z,1376431013.813 [tank_weight:Science:B] Running Loop=1 2013-08-13T21:56:53.814Z,1376431013.814 [tank_weight:Science:C] Running Loop=1 2013-08-13T21:56:53.814Z,1376431013.814 [tank_weight:Science:D] Running Loop=1 2013-08-13T21:56:53.814Z,1376431013.814 [tank_weight:Science:E] Running Loop=1 2013-08-13T21:56:53.814Z,1376431013.814 [tank_weight:Science:F] Running Loop=1 2013-08-13T21:56:53.814Z,1376431013.814 [tank_weight:Science:G] Running Loop=1 2013-08-13T21:56:53.814Z,1376431013.814 [tank_weight:Science:H] Running Loop=1 2013-08-13T21:56:53.814Z,1376431013.814 [tank_weight:Science:I] Running Loop=1 2013-08-13T21:56:53.814Z,1376431013.814 [tank_weight:D] Running Loop=1 2013-08-13T21:56:53.814Z,1376431013.814 [tank_weight:D](INFO): Aggregate::initialize tank_weight:D 2013-08-13T21:56:53.814Z,1376431013.814 [tank_weight:D:A.SetSpeed] Running Loop=1 2013-08-13T21:56:53.814Z,1376431013.814 [tank_weight:D:A.SetSpeed](DEBUG): Initialize. 2013-08-13T21:56:53.815Z,1376431013.815 [tank_weight:D:BuoyancyLo] Running Loop=1 2013-08-13T21:56:53.823Z,1376431013.823 [tank_weight:D:BuoyancyLo](INFO): Aggregate::initialize tank_weight:D:BuoyancyLo 2013-08-13T21:56:53.823Z,1376431013.823 [tank_weight:D:BuoyancyLo:A.Buoyancy] Running Loop=1 2013-08-13T21:56:53.823Z,1376431013.823 [tank_weight:D:BuoyancyLo:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2013-08-13T21:56:53.823Z,1376431013.823 [tank_weight:D:BuoyancyLo:B.Pitch] Running Loop=1 2013-08-13T21:56:53.823Z,1376431013.823 [tank_weight:D:BuoyancyLo:B.Pitch](DEBUG): Initialize. 2013-08-13T21:56:53.824Z,1376431013.824 [tank_weight:D:BuoyancyLo:C.Wait] Running Loop=1 2013-08-13T21:56:53.824Z,1376431013.824 [tank_weight:D:BuoyancyLo:C.Wait](DEBUG): Initialize Wait Component. 2013-08-13T21:56:53.833Z,1376431013.833 [tank_weight:D:BuoyancyLo:B.Pitch] Running Loop=1 2013-08-13T21:56:53.886Z,1376431013.886 [tank_weight:D:BuoyancyLo:A.Buoyancy] Running Loop=1 2013-08-13T21:56:53.907Z,1376431013.907 [tank_weight:D:A.SetSpeed] Running Loop=1 2013-08-13T21:56:53.920Z,1376431013.920 [tank_weight:Science] Running Loop=1 2013-08-13T21:56:53.921Z,1376431013.921 [tank_weight:Science:H] Running Loop=1 2013-08-13T21:56:53.921Z,1376431013.921 [tank_weight:Science:H](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI 2013-08-13T21:56:53.932Z,1376431013.932 [tank_weight:Science:G] Running Loop=1 2013-08-13T21:56:53.932Z,1376431013.932 [tank_weight:Science:G](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity 2013-08-13T21:56:53.934Z,1376431013.934 [tank_weight:Science:A] Running Loop=1 2013-08-13T21:56:53.934Z,1376431013.934 [tank_weight:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water 2013-08-13T21:56:53.944Z,1376431013.944 [tank_weight:B.DepthEnvelope] Running Loop=1 2013-08-13T21:56:53.955Z,1376431013.955 [tank_weight:A.AltitudeEnvelope] Running Loop=1 2013-08-13T21:56:54.208Z,1376431014.208 [DVL_micro](INFO): RSSI requested 2013-08-13T21:56:54.208Z,1376431014.208 [DVL_micro](INFO): ADCP requested 2013-08-13T21:56:54.208Z,1376431014.208 [DVL_micro](INFO): NQ1 requested 2013-08-13T21:56:54.209Z,1376431014.209 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-08-13T21:56:54.209Z,1376431014.209 [DVL_micro](INFO): resume:Powering up 2013-08-13T21:56:54.209Z,1376431014.209 [DVL_micro](INFO): Cycling power to configure device. 2013-08-13T21:56:55.466Z,1376431015.466 [tank_weight:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2013-08-13T21:56:56.219Z,1376431016.219 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-08-13T21:56:56.219Z,1376431016.219 [DVL_micro](INFO): Querying output modes 2013-08-13T21:56:56.220Z,1376431016.220 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2013-08-13T21:56:56.247Z,1376431016.247 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2013-08-13T21:56:56.248Z,1376431016.248 [DVL_micro](INFO): NQ1 output enabled 2013-08-13T21:56:56.248Z,1376431016.248 [DVL_micro](INFO): RSSI output enabled 2013-08-13T21:56:56.248Z,1376431016.248 [DVL_micro](INFO): ADCP output enabled 2013-08-13T21:56:56.248Z,1376431016.248 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2013-08-13T21:56:56.272Z,1376431016.272 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2013-08-13T21:56:56.394Z,1376431016.394 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40 2013-08-13T21:56:56.476Z,1376431016.476 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=220 2013-08-13T21:56:56.524Z,1376431016.524 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=176 2013-08-13T21:56:56.560Z,1376431016.560 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:56:56.836Z,1376431016.836 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56 2013-08-13T21:56:56.944Z,1376431016.944 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=124 2013-08-13T21:56:57.040Z,1376431017.040 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32 2013-08-13T21:56:57.144Z,1376431017.144 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:56:57.330Z,1376431017.330 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=616 2013-08-13T21:56:57.345Z,1376431017.345 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=156 2013-08-13T21:56:57.393Z,1376431017.393 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=112 2013-08-13T21:56:57.449Z,1376431017.449 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:56:57.725Z,1376431017.725 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2013-08-13T21:56:57.826Z,1376431017.826 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=29 2013-08-13T21:56:57.934Z,1376431017.934 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=97 2013-08-13T21:56:58.030Z,1376431018.030 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:56:58.383Z,1376431018.383 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=120 2013-08-13T21:56:58.521Z,1376431018.521 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28 2013-08-13T21:56:58.667Z,1376431018.667 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=64 2013-08-13T21:56:58.816Z,1376431018.816 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:56:59.140Z,1376431019.140 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=156 2013-08-13T21:56:59.252Z,1376431019.252 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36 2013-08-13T21:56:59.375Z,1376431019.375 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=108 2013-08-13T21:56:59.492Z,1376431019.492 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:57:00.009Z,1376431020.009 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=92 2013-08-13T21:57:00.238Z,1376431020.238 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:57:00.482Z,1376431020.482 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=76 2013-08-13T21:57:00.718Z,1376431020.718 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:57:01.651Z,1376431021.651 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=157 2013-08-13T21:57:01.785Z,1376431021.785 [NAL9602](INFO): Powering down 2013-08-13T21:57:02.116Z,1376431022.116 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=69 2013-08-13T21:57:02.597Z,1376431022.597 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=77 2013-08-13T21:57:03.065Z,1376431023.065 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:57:03.955Z,1376431023.955 [DVL_micro](DEBUG): NQ1 2013-08-13T21:57:03.989Z,1376431023.989 [DVL_micro](DEBUG): ADCP 2013-08-13T21:57:04.805Z,1376431024.805 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=5 2013-08-13T21:57:05.653Z,1376431025.653 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=45 2013-08-13T21:57:06.487Z,1376431026.487 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=21 2013-08-13T21:57:06.892Z,1376431026.892 [Aanderaa_O2](FAULT): No communication! Re-starting 2013-08-13T21:57:06.892Z,1376431026.892 [Aanderaa_O2] Communications Fault, FailCount= 1 2013-08-13T21:57:06.892Z,1376431026.892 [Aanderaa_O2](ERROR): Communications Fault 2013-08-13T21:57:06.968Z,1376431026.968 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2013-08-13T21:57:07.332Z,1376431027.332 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:57:07.400Z,1376431027.400 [Aanderaa_O2](INFO): Powering down 2013-08-13T21:57:07.473Z,1376431027.473 [Reporter](INFO): Aanderaa_O2.mass_concentration_of_oxygen_in_sea_water no_value 2013-08-13T21:57:07.693Z,1376431027.693 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=136 2013-08-13T21:57:07.747Z,1376431027.747 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=156 2013-08-13T21:57:07.797Z,1376431027.797 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=80 2013-08-13T21:57:07.849Z,1376431027.849 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:57:08.126Z,1376431028.126 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56 2013-08-13T21:57:08.158Z,1376431028.158 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2013-08-13T21:57:08.158Z,1376431028.158 [Aanderaa_O2] No Fault, FailCount= 1 2013-08-13T21:57:08.238Z,1376431028.238 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=124 2013-08-13T21:57:08.330Z,1376431028.330 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2013-08-13T21:57:08.442Z,1376431028.442 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:57:08.570Z,1376431028.570 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=72 2013-08-13T21:57:08.622Z,1376431028.622 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2013-08-13T21:57:08.682Z,1376431028.682 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48 2013-08-13T21:57:08.738Z,1376431028.738 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:57:09.023Z,1376431029.023 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=120 2013-08-13T21:57:09.115Z,1376431029.115 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60 2013-08-13T21:57:09.227Z,1376431029.227 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=96 2013-08-13T21:57:09.319Z,1376431029.319 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:57:09.680Z,1376431029.680 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=216 2013-08-13T21:57:09.808Z,1376431029.808 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28 2013-08-13T21:57:09.960Z,1376431029.960 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=64 2013-08-13T21:57:10.108Z,1376431030.108 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:57:10.425Z,1376431030.425 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=60 2013-08-13T21:57:10.533Z,1376431030.533 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:57:10.665Z,1376431030.665 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=140 2013-08-13T21:57:10.775Z,1376431030.775 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:57:11.295Z,1376431031.295 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=60 2013-08-13T21:57:11.531Z,1376431031.531 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:57:11.775Z,1376431031.775 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=76 2013-08-13T21:57:12.015Z,1376431032.015 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:57:12.933Z,1376431032.933 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=124 2013-08-13T21:57:13.410Z,1376431033.410 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=132 2013-08-13T21:57:13.886Z,1376431033.886 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=108 2013-08-13T21:57:14.355Z,1376431034.355 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:57:15.240Z,1376431035.240 [DVL_micro](DEBUG): NQ1 2013-08-13T21:57:15.269Z,1376431035.269 [DVL_micro](DEBUG): ADCP 2013-08-13T21:57:16.110Z,1376431036.110 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=224 2013-08-13T21:57:16.943Z,1376431036.943 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=168 2013-08-13T21:57:17.772Z,1376431037.772 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=16 2013-08-13T21:57:18.610Z,1376431038.610 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:57:18.909Z,1376431038.909 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=104 2013-08-13T21:57:18.970Z,1376431038.970 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=188 2013-08-13T21:57:19.018Z,1376431039.018 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=112 2013-08-13T21:57:19.066Z,1376431039.066 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:57:19.356Z,1376431039.356 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=120 2013-08-13T21:57:19.459Z,1376431039.459 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=124 2013-08-13T21:57:19.559Z,1376431039.559 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=96 2013-08-13T21:57:19.655Z,1376431039.655 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:57:19.792Z,1376431039.792 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8 2013-08-13T21:57:19.840Z,1376431039.840 [CommandLine](IMPORTANT): got command failComponent 2013-08-13T21:57:19.840Z,1376431039.840 [CommandLine](IMPORTANT): Failed components: 2013-08-13T21:57:19.840Z,1376431039.840 [CommandLine](IMPORTANT): No failed Components. 2013-08-13T21:57:19.852Z,1376431039.852 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60 2013-08-13T21:57:19.908Z,1376431039.908 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=144 2013-08-13T21:57:19.956Z,1376431039.956 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:57:20.096Z,1376431040.096 [Aanderaa_O2](FAULT): No communication! Re-starting 2013-08-13T21:57:20.096Z,1376431040.096 [Aanderaa_O2] Communications Fault, FailCount= 2 2013-08-13T21:57:20.096Z,1376431040.096 [Aanderaa_O2](ERROR): Communications Fault 2013-08-13T21:57:20.164Z,1376431040.164 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2013-08-13T21:57:20.240Z,1376431040.240 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=88 2013-08-13T21:57:20.340Z,1376431040.340 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60 2013-08-13T21:57:20.441Z,1376431040.441 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2013-08-13T21:57:20.549Z,1376431040.549 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:57:20.609Z,1376431040.609 [Aanderaa_O2](INFO): Powering down 2013-08-13T21:57:20.657Z,1376431040.657 [Reporter](INFO): Aanderaa_O2.mass_concentration_of_oxygen_in_sea_water no_value 2013-08-13T21:57:20.893Z,1376431040.893 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=88 2013-08-13T21:57:21.042Z,1376431041.042 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=124 2013-08-13T21:57:21.178Z,1376431041.178 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0 2013-08-13T21:57:21.330Z,1376431041.330 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:57:21.418Z,1376431041.418 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2013-08-13T21:57:21.419Z,1376431041.419 [Aanderaa_O2] No Fault, FailCount= 2 2013-08-13T21:57:21.654Z,1376431041.654 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=156 2013-08-13T21:57:21.767Z,1376431041.767 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=100 2013-08-13T21:57:21.887Z,1376431041.887 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=140 2013-08-13T21:57:22.003Z,1376431042.003 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:57:22.520Z,1376431042.520 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=92 2013-08-13T21:57:22.756Z,1376431042.756 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=36 2013-08-13T21:57:22.993Z,1376431042.993 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12 2013-08-13T21:57:23.229Z,1376431043.229 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:57:24.162Z,1376431044.162 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=188 2013-08-13T21:57:24.627Z,1376431044.627 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=36 2013-08-13T21:57:25.108Z,1376431045.108 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=140 2013-08-13T21:57:25.577Z,1376431045.577 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:57:26.376Z,1376431046.376 [CommandLine](IMPORTANT): got command failComponent 2013-08-13T21:57:26.376Z,1376431046.376 [CommandLine](IMPORTANT): Failed components: 2013-08-13T21:57:26.377Z,1376431046.377 [CommandLine](IMPORTANT): No failed Components. 2013-08-13T21:57:26.458Z,1376431046.458 [DVL_micro](DEBUG): NQ1 2013-08-13T21:57:26.498Z,1376431046.498 [DVL_micro](DEBUG): ADCP 2013-08-13T21:57:27.315Z,1376431047.315 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=2 2013-08-13T21:57:28.169Z,1376431048.169 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=138 2013-08-13T21:57:28.998Z,1376431048.998 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=18 2013-08-13T21:57:29.843Z,1376431049.843 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:57:30.140Z,1376431050.140 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=104 2013-08-13T21:57:30.203Z,1376431050.203 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=124 2013-08-13T21:57:30.252Z,1376431050.252 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=80 2013-08-13T21:57:30.304Z,1376431050.304 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:57:30.580Z,1376431050.580 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56 2013-08-13T21:57:30.693Z,1376431050.693 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=156 2013-08-13T21:57:30.792Z,1376431050.792 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=128 2013-08-13T21:57:30.893Z,1376431050.893 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:57:31.029Z,1376431051.029 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=72 2013-08-13T21:57:31.089Z,1376431051.089 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=156 2013-08-13T21:57:31.133Z,1376431051.133 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2013-08-13T21:57:31.193Z,1376431051.193 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:57:31.466Z,1376431051.466 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2013-08-13T21:57:31.578Z,1376431051.578 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=124 2013-08-13T21:57:31.678Z,1376431051.678 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=128 2013-08-13T21:57:31.774Z,1376431051.774 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:57:32.123Z,1376431052.123 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=88 2013-08-13T21:57:32.267Z,1376431052.267 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28 2013-08-13T21:57:32.419Z,1376431052.419 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=128 2013-08-13T21:57:32.555Z,1376431052.555 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:57:32.876Z,1376431052.876 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28 2013-08-13T21:57:33.012Z,1376431053.012 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=100 2013-08-13T21:57:33.116Z,1376431053.116 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=140 2013-08-13T21:57:33.228Z,1376431053.228 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:57:33.291Z,1376431053.291 [Aanderaa_O2](FAULT): No communication! Re-starting 2013-08-13T21:57:33.291Z,1376431053.291 [Aanderaa_O2] Communications Fault, FailCount= 3 2013-08-13T21:57:33.291Z,1376431053.291 [Aanderaa_O2](ERROR): Communications Fault 2013-08-13T21:57:33.365Z,1376431053.365 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2013-08-13T21:57:33.365Z,1376431053.365 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2013-08-13T21:57:33.749Z,1376431053.749 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28 2013-08-13T21:57:33.803Z,1376431053.803 [Aanderaa_O2](INFO): Powering down 2013-08-13T21:57:33.859Z,1376431053.859 [Reporter](INFO): Aanderaa_O2.mass_concentration_of_oxygen_in_sea_water no_value 2013-08-13T21:57:33.994Z,1376431053.994 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=100 2013-08-13T21:57:34.226Z,1376431054.226 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=108 2013-08-13T21:57:34.466Z,1376431054.466 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:57:35.392Z,1376431055.392 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=125 2013-08-13T21:57:35.856Z,1376431055.856 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=5 2013-08-13T21:57:36.337Z,1376431056.337 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=77 2013-08-13T21:57:36.814Z,1376431056.814 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:57:37.687Z,1376431057.687 [DVL_micro](DEBUG): NQ1 2013-08-13T21:57:37.723Z,1376431057.723 [DVL_micro](DEBUG): ADCP 2013-08-13T21:57:38.028Z,1376431058.028 [CommandLine](IMPORTANT): got command failComponent 2013-08-13T21:57:38.028Z,1376431058.028 [CommandLine](IMPORTANT): Failed components: 2013-08-13T21:57:38.028Z,1376431058.028 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault 2013-08-13T21:57:38.549Z,1376431058.549 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:57:39.398Z,1376431059.398 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=108 2013-08-13T21:57:40.235Z,1376431060.235 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=116 2013-08-13T21:57:41.068Z,1376431061.068 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:57:41.369Z,1376431061.369 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=72 2013-08-13T21:57:41.429Z,1376431061.429 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=92 2013-08-13T21:57:41.477Z,1376431061.477 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2013-08-13T21:57:41.541Z,1376431061.541 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:57:41.822Z,1376431061.822 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=152 2013-08-13T21:57:41.918Z,1376431061.918 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=92 2013-08-13T21:57:42.026Z,1376431062.026 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=128 2013-08-13T21:57:42.122Z,1376431062.122 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:57:42.258Z,1376431062.258 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40 2013-08-13T21:57:42.311Z,1376431062.311 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60 2013-08-13T21:57:42.373Z,1376431062.373 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=80 2013-08-13T21:57:42.423Z,1376431062.423 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:57:42.707Z,1376431062.707 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=152 2013-08-13T21:57:42.804Z,1376431062.804 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2013-08-13T21:57:42.907Z,1376431062.907 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=96 2013-08-13T21:57:43.004Z,1376431063.004 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:57:43.357Z,1376431063.357 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56 2013-08-13T21:57:43.504Z,1376431063.504 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=124 2013-08-13T21:57:43.640Z,1376431063.640 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0 2013-08-13T21:57:43.797Z,1376431063.797 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:57:44.117Z,1376431064.117 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=124 2013-08-13T21:57:44.230Z,1376431064.230 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=100 2013-08-13T21:57:44.342Z,1376431064.342 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12 2013-08-13T21:57:44.466Z,1376431064.466 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:57:44.983Z,1376431064.983 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28 2013-08-13T21:57:45.219Z,1376431065.219 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:57:45.459Z,1376431065.459 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=44 2013-08-13T21:57:45.696Z,1376431065.696 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:57:46.617Z,1376431066.617 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=60 2013-08-13T21:57:47.090Z,1376431067.090 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=36 2013-08-13T21:57:47.566Z,1376431067.566 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=44 2013-08-13T21:57:48.039Z,1376431068.039 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:57:48.929Z,1376431068.929 [DVL_micro](DEBUG): NQ1 2013-08-13T21:57:48.957Z,1376431068.957 [DVL_micro](DEBUG): ADCP 2013-08-13T21:57:49.794Z,1376431069.794 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=98 2013-08-13T21:57:50.635Z,1376431070.635 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=106 2013-08-13T21:57:51.465Z,1376431071.465 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=19 2013-08-13T21:57:52.314Z,1376431072.314 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:57:52.594Z,1376431072.594 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40 2013-08-13T21:57:52.654Z,1376431072.654 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=92 2013-08-13T21:57:52.711Z,1376431072.711 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=80 2013-08-13T21:57:52.759Z,1376431072.759 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:57:53.031Z,1376431073.031 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2013-08-13T21:57:53.143Z,1376431073.143 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60 2013-08-13T21:57:53.239Z,1376431073.239 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2013-08-13T21:57:53.347Z,1376431073.347 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:57:53.488Z,1376431073.488 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=104 2013-08-13T21:57:53.540Z,1376431073.540 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60 2013-08-13T21:57:53.588Z,1376431073.588 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2013-08-13T21:57:53.652Z,1376431073.652 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:57:53.932Z,1376431073.932 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=120 2013-08-13T21:57:54.029Z,1376431074.029 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2013-08-13T21:57:54.133Z,1376431074.133 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=96 2013-08-13T21:57:54.229Z,1376431074.229 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:57:54.581Z,1376431074.581 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56 2013-08-13T21:57:54.722Z,1376431074.722 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28 2013-08-13T21:57:54.870Z,1376431074.870 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=32 2013-08-13T21:57:55.018Z,1376431075.018 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:57:55.335Z,1376431075.335 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28 2013-08-13T21:57:55.459Z,1376431075.459 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=132 2013-08-13T21:57:55.571Z,1376431075.571 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=76 2013-08-13T21:57:55.679Z,1376431075.679 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:57:55.845Z,1376431075.845 [CommandLine](IMPORTANT): got command show stack 2013-08-13T21:57:55.845Z,1376431075.845 [CommandLine](INFO): Behavior Stack: 2013-08-13T21:57:55.845Z,1376431075.845 [tank_weight](INFO): Priority 0: tank_weight:A.AltitudeEnvelope 2013-08-13T21:57:55.845Z,1376431075.845 [tank_weight](INFO): Priority 1: tank_weight:B.DepthEnvelope 2013-08-13T21:57:55.846Z,1376431075.846 [tank_weight:Science](INFO): Priority 2: tank_weight:Science:A 2013-08-13T21:57:55.847Z,1376431075.847 [tank_weight:Science](INFO): Priority 3: tank_weight:Science:G 2013-08-13T21:57:55.847Z,1376431075.847 [tank_weight:Science](INFO): Priority 4: tank_weight:Science:H 2013-08-13T21:57:55.847Z,1376431075.847 [tank_weight:D](INFO): Priority 5: tank_weight:D:A.SetSpeed 2013-08-13T21:57:55.848Z,1376431075.848 [tank_weight:D:BuoyancyLo](INFO): Priority 6: tank_weight:D:BuoyancyLo:A.Buoyancy 2013-08-13T21:57:55.848Z,1376431075.848 [tank_weight:D:BuoyancyLo](INFO): Priority 7: tank_weight:D:BuoyancyLo:B.Pitch 2013-08-13T21:57:55.848Z,1376431075.848 [tank_weight:D:BuoyancyLo](INFO): Priority 8: tank_weight:D:BuoyancyLo:C.Wait 2013-08-13T21:57:56.208Z,1376431076.208 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=92 2013-08-13T21:57:56.444Z,1376431076.444 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=36 2013-08-13T21:57:56.689Z,1376431076.689 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=108 2013-08-13T21:57:56.913Z,1376431076.913 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:57:57.843Z,1376431077.843 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=60 2013-08-13T21:57:58.307Z,1376431078.307 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:57:58.784Z,1376431078.784 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=12 2013-08-13T21:57:59.265Z,1376431079.265 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:58:00.150Z,1376431080.150 [DVL_micro](DEBUG): NQ1 2013-08-13T21:58:00.175Z,1376431080.175 [DVL_micro](DEBUG): ADCP 2013-08-13T21:58:01.027Z,1376431081.027 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=195 2013-08-13T21:58:01.853Z,1376431081.853 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=43 2013-08-13T21:58:02.694Z,1376431082.694 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=83 2013-08-13T21:58:03.535Z,1376431083.535 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:58:03.816Z,1376431083.816 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=136 2013-08-13T21:58:03.864Z,1376431083.864 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2013-08-13T21:58:03.916Z,1376431083.916 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48 2013-08-13T21:58:03.977Z,1376431083.977 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:58:04.256Z,1376431084.256 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=88 2013-08-13T21:58:04.365Z,1376431084.365 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=156 2013-08-13T21:58:04.465Z,1376431084.465 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=96 2013-08-13T21:58:04.569Z,1376431084.569 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:58:04.711Z,1376431084.711 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=200 2013-08-13T21:58:04.761Z,1376431084.761 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=124 2013-08-13T21:58:04.809Z,1376431084.809 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=80 2013-08-13T21:58:04.857Z,1376431084.857 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:58:05.146Z,1376431085.146 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=88 2013-08-13T21:58:05.242Z,1376431085.242 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2013-08-13T21:58:05.350Z,1376431085.350 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=64 2013-08-13T21:58:05.450Z,1376431085.450 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:58:05.808Z,1376431085.808 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=120 2013-08-13T21:58:05.947Z,1376431085.947 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=92 2013-08-13T21:58:06.079Z,1376431086.079 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0 2013-08-13T21:58:06.231Z,1376431086.231 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:58:06.472Z,1376431086.472 [CommandLine](IMPORTANT): got command failComponent 2013-08-13T21:58:06.472Z,1376431086.472 [CommandLine](IMPORTANT): Failed components: 2013-08-13T21:58:06.472Z,1376431086.472 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault 2013-08-13T21:58:06.556Z,1376431086.556 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=92 2013-08-13T21:58:06.664Z,1376431086.664 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:58:06.792Z,1376431086.792 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=108 2013-08-13T21:58:06.901Z,1376431086.901 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:58:07.426Z,1376431087.426 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28 2013-08-13T21:58:07.670Z,1376431087.670 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=132 2013-08-13T21:58:07.906Z,1376431087.906 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=108 2013-08-13T21:58:08.130Z,1376431088.130 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:58:09.064Z,1376431089.064 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=92 2013-08-13T21:58:09.527Z,1376431089.527 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:58:10.017Z,1376431090.017 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=140 2013-08-13T21:58:10.491Z,1376431090.491 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:58:11.363Z,1376431091.363 [DVL_micro](DEBUG): NQ1 2013-08-13T21:58:11.389Z,1376431091.389 [DVL_micro](DEBUG): ADCP 2013-08-13T21:58:12.225Z,1376431092.225 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=65 2013-08-13T21:58:13.062Z,1376431093.062 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=9 2013-08-13T21:58:13.907Z,1376431093.907 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=81 2013-08-13T21:58:14.749Z,1376431094.749 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:58:15.025Z,1376431095.025 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=72 2013-08-13T21:58:15.085Z,1376431095.085 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=124 2013-08-13T21:58:15.125Z,1376431095.125 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2013-08-13T21:58:15.185Z,1376431095.185 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:58:15.474Z,1376431095.474 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=152 2013-08-13T21:58:15.566Z,1376431095.566 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2013-08-13T21:58:15.678Z,1376431095.678 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=128 2013-08-13T21:58:15.770Z,1376431095.770 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:58:15.911Z,1376431095.911 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40 2013-08-13T21:58:15.963Z,1376431095.963 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2013-08-13T21:58:16.023Z,1376431096.023 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=80 2013-08-13T21:58:16.071Z,1376431096.071 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:58:16.347Z,1376431096.347 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2013-08-13T21:58:16.455Z,1376431096.455 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2013-08-13T21:58:16.564Z,1376431096.564 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=128 2013-08-13T21:58:16.668Z,1376431096.668 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:58:17.012Z,1376431097.012 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24 2013-08-13T21:58:17.156Z,1376431097.156 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=92 2013-08-13T21:58:17.303Z,1376431097.303 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=128 2013-08-13T21:58:17.445Z,1376431097.445 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:58:17.762Z,1376431097.762 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=60 2013-08-13T21:58:17.885Z,1376431097.885 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=132 2013-08-13T21:58:17.998Z,1376431097.998 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=76 2013-08-13T21:58:18.110Z,1376431098.110 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:58:18.643Z,1376431098.643 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=124 2013-08-13T21:58:18.879Z,1376431098.879 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=132 2013-08-13T21:58:19.112Z,1376431099.112 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=76 2013-08-13T21:58:19.352Z,1376431099.352 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:58:20.265Z,1376431100.265 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28 2013-08-13T21:58:20.734Z,1376431100.734 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:58:21.223Z,1376431101.223 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=108 2013-08-13T21:58:21.688Z,1376431101.688 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:58:22.577Z,1376431102.577 [DVL_micro](DEBUG): NQ1 2013-08-13T21:58:22.627Z,1376431102.627 [DVL_micro](DEBUG): ADCP 2013-08-13T21:58:23.438Z,1376431103.438 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=128 2013-08-13T21:58:24.275Z,1376431104.275 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=72 2013-08-13T21:58:25.113Z,1376431105.113 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=48 2013-08-13T21:58:25.954Z,1376431105.954 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:58:26.231Z,1376431106.231 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40 2013-08-13T21:58:26.291Z,1376431106.291 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=92 2013-08-13T21:58:26.343Z,1376431106.343 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=80 2013-08-13T21:58:26.391Z,1376431106.391 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:58:26.679Z,1376431106.679 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=120 2013-08-13T21:58:26.779Z,1376431106.779 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=92 2013-08-13T21:58:26.880Z,1376431106.880 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32 2013-08-13T21:58:26.976Z,1376431106.976 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:58:27.124Z,1376431107.124 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=137 2013-08-13T21:58:27.172Z,1376431107.172 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=29 2013-08-13T21:58:27.232Z,1376431107.232 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=113 2013-08-13T21:58:27.280Z,1376431107.280 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:58:27.569Z,1376431107.569 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=120 2013-08-13T21:58:27.669Z,1376431107.669 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=92 2013-08-13T21:58:27.769Z,1376431107.769 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=64 2013-08-13T21:58:27.873Z,1376431107.873 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:58:28.226Z,1376431108.226 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=216 2013-08-13T21:58:28.354Z,1376431108.354 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28 2013-08-13T21:58:28.505Z,1376431108.505 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=96 2013-08-13T21:58:28.642Z,1376431108.642 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:58:28.975Z,1376431108.975 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=156 2013-08-13T21:58:29.087Z,1376431109.087 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36 2013-08-13T21:58:29.207Z,1376431109.207 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=44 2013-08-13T21:58:29.319Z,1376431109.319 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:58:29.848Z,1376431109.848 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=92 2013-08-13T21:58:30.076Z,1376431110.076 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:58:30.325Z,1376431110.325 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=172 2013-08-13T21:58:30.557Z,1376431110.557 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:58:31.479Z,1376431111.479 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=60 2013-08-13T21:58:31.951Z,1376431111.951 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=36 2013-08-13T21:58:32.432Z,1376431112.432 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=76 2013-08-13T21:58:32.905Z,1376431112.905 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:58:33.794Z,1376431113.794 [DVL_micro](DEBUG): NQ1 2013-08-13T21:58:33.822Z,1376431113.822 [DVL_micro](DEBUG): ADCP 2013-08-13T21:58:34.652Z,1376431114.652 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=2 2013-08-13T21:58:35.502Z,1376431115.502 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=106 2013-08-13T21:58:36.326Z,1376431116.326 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=18 2013-08-13T21:58:37.179Z,1376431117.179 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:58:37.508Z,1376431117.508 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=72 2013-08-13T21:58:37.557Z,1376431117.557 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60 2013-08-13T21:58:37.624Z,1376431117.624 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=208 2013-08-13T21:58:37.672Z,1376431117.672 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:58:37.941Z,1376431117.941 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2013-08-13T21:58:38.053Z,1376431118.053 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=124 2013-08-13T21:58:38.153Z,1376431118.153 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=64 2013-08-13T21:58:38.257Z,1376431118.257 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:58:38.397Z,1376431118.397 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=104 2013-08-13T21:58:38.449Z,1376431118.449 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=92 2013-08-13T21:58:38.498Z,1376431118.498 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2013-08-13T21:58:38.546Z,1376431118.546 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:58:38.838Z,1376431118.838 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=120 2013-08-13T21:58:38.938Z,1376431118.938 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=92 2013-08-13T21:58:39.038Z,1376431119.038 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32 2013-08-13T21:58:39.139Z,1376431119.139 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:58:39.491Z,1376431119.491 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=88 2013-08-13T21:58:39.631Z,1376431119.631 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=60 2013-08-13T21:58:39.783Z,1376431119.783 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=96 2013-08-13T21:58:39.928Z,1376431119.928 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:58:40.252Z,1376431120.252 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=156 2013-08-13T21:58:40.353Z,1376431120.353 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:58:40.477Z,1376431120.477 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=44 2013-08-13T21:58:40.589Z,1376431120.589 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:58:41.114Z,1376431121.114 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=60 2013-08-13T21:58:41.350Z,1376431121.350 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:58:41.590Z,1376431121.590 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=44 2013-08-13T21:58:41.831Z,1376431121.831 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:58:42.753Z,1376431122.753 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=124 2013-08-13T21:58:43.225Z,1376431123.225 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=100 2013-08-13T21:58:43.708Z,1376431123.708 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=76 2013-08-13T21:58:44.170Z,1376431124.170 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:58:45.056Z,1376431125.056 [DVL_micro](DEBUG): NQ1 2013-08-13T21:58:45.085Z,1376431125.085 [DVL_micro](DEBUG): ADCP 2013-08-13T21:58:45.925Z,1376431125.925 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=97 2013-08-13T21:58:46.762Z,1376431126.762 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=138 2013-08-13T21:58:47.596Z,1376431127.596 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=18 2013-08-13T21:58:48.433Z,1376431128.433 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:58:48.746Z,1376431128.746 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=72 2013-08-13T21:58:48.794Z,1376431128.794 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60 2013-08-13T21:58:48.862Z,1376431128.862 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=176 2013-08-13T21:58:48.910Z,1376431128.910 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:58:49.186Z,1376431129.186 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=120 2013-08-13T21:58:49.294Z,1376431129.294 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=188 2013-08-13T21:58:49.390Z,1376431129.390 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=64 2013-08-13T21:58:49.487Z,1376431129.487 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:58:49.635Z,1376431129.635 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=104 2013-08-13T21:58:49.683Z,1376431129.683 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2013-08-13T21:58:49.731Z,1376431129.731 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2013-08-13T21:58:49.791Z,1376431129.791 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:58:50.076Z,1376431130.076 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=88 2013-08-13T21:58:50.176Z,1376431130.176 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60 2013-08-13T21:58:50.280Z,1376431130.280 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=64 2013-08-13T21:58:50.380Z,1376431130.380 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:58:50.733Z,1376431130.733 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=120 2013-08-13T21:58:50.877Z,1376431130.877 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=156 2013-08-13T21:58:51.017Z,1376431131.017 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=64 2013-08-13T21:58:51.157Z,1376431131.157 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:58:51.482Z,1376431131.482 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=60 2013-08-13T21:58:51.594Z,1376431131.594 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:58:51.714Z,1376431131.714 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12 2013-08-13T21:58:51.834Z,1376431131.834 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:58:52.359Z,1376431132.359 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=92 2013-08-13T21:58:52.587Z,1376431132.587 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:58:52.828Z,1376431132.828 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=44 2013-08-13T21:58:53.064Z,1376431133.064 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2013-08-13T21:58:53.990Z,1376431133.990 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=92 2013-08-13T21:58:54.458Z,1376431134.458 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=36 2013-08-13T21:58:54.929Z,1376431134.929 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=12 2013-08-13T21:58:55.404Z,1376431135.404 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2013-08-13T21:58:55.468Z,1376431135.468 [CommandLine](IMPORTANT): got command failComponent 2013-08-13T21:58:55.468Z,1376431135.468 [CommandLine](IMPORTANT): Failed components: 2013-08-13T21:58:55.468Z,1376431135.468 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault 2013-08-13T21:58:56.293Z,1376431136.293 [DVL_micro](DEBUG): NQ1 2013-08-13T21:58:56.327Z,1376431136.327 [DVL_micro](DEBUG): ADCP 2013-08-13T21:58:57.154Z,1376431137.154 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=2 2013-08-13T21:58:58.004Z,1376431138.004 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=170 2013-08-13T21:58:58.833Z,1376431138.833 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=18 2013-08-13T21:58:59.670Z,1376431139.670 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2013-08-13T21:59:00.047Z,1376431140.047 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40 2013-08-13T21:59:00.107Z,1376431140.107 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=156 2013-08-13T21:59:00.155Z,1376431140.155 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48 2013-08-13T21:59:00.223Z,1376431140.223 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:59:00.492Z,1376431140.492 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=88 2013-08-13T21:59:00.616Z,1376431140.616 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=124 2013-08-13T21:59:00.701Z,1376431140.701 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=96 2013-08-13T21:59:00.804Z,1376431140.804 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:59:00.929Z,1376431140.929 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8 2013-08-13T21:59:00.988Z,1376431140.988 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2013-08-13T21:59:01.052Z,1376431141.052 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=144 2013-08-13T21:59:01.103Z,1376431141.103 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2013-08-13T21:59:01.377Z,1376431141.377 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56 2013-08-13T21:59:01.485Z,1376431141.485 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=92 2013-08-13T21:59:01.585Z,1376431141.585 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=64 2013-08-13T21:59:01.693Z,1376431141.693 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2013-08-13T21:59:01.982Z,1376431141.982 [CommandLine](IMPORTANT): got command quit 2013-08-13T21:59:02.034Z,1376431142.034 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=88 2013-08-13T21:59:02.178Z,1376431142.178 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=60 2013-08-13T21:59:02.319Z,1376431142.319 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0 2013-08-13T21:59:02.463Z,1376431142.463 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2013-08-13T21:59:02.783Z,1376431142.783 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28 2013-08-13T21:59:02.907Z,1376431142.907 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=132 2013-08-13T21:59:03.016Z,1376431143.016 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=44 2013-08-13T21:59:03.111Z,1376431143.111 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2013-08-13T21:59:03.111Z,1376431143.111 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2013-08-13T21:59:03.128Z,1376431143.128 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2013-08-13T21:59:03.183Z,1376431143.183 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler 2013-08-13T21:59:03.225Z,1376431143.225 [DVL_micro](INFO): uninitialize:Powering down 2013-08-13T21:59:03.389Z,1376431143.389 [tank_weight] Stopped 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight](INFO): Aggregate::uninitialize tank_weight 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:A.AltitudeEnvelope] Stopped 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:B.DepthEnvelope] Stopped 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:B.DepthEnvelope](DEBUG): Uninitialize. 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:Science] Stopped 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:Science](INFO): Aggregate::uninitialize tank_weight:Science 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:Science:A] Stopped 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:Science:B] Stopped 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:Science:C] Stopped 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:Science:D] Stopped 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:Science:E] Stopped 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:Science:F] Stopped 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:Science:G] Stopped 2013-08-13T21:59:03.390Z,1376431143.390 [tank_weight:Science:H] Stopped 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:Science:I] Stopped 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:D] Stopped 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:D](INFO): Aggregate::uninitialize tank_weight:D 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:D:A.SetSpeed] Stopped 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:D:A.SetSpeed](DEBUG): Uninitialize. 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:D:BuoyancyLo] Stopped 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:D:BuoyancyLo](INFO): Aggregate::uninitialize tank_weight:D:BuoyancyLo 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:D:BuoyancyLo:A.Buoyancy] Stopped 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:D:BuoyancyLo:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:D:BuoyancyLo:B.Pitch] Stopped 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:D:BuoyancyLo:C.Wait] Stopped 2013-08-13T21:59:03.391Z,1376431143.391 [tank_weight:D:BuoyancyLo:C.Wait](DEBUG): Uninitialize Wait Component. 2013-08-13T21:59:03.629Z,1376431143.629 [DVL_micro](INFO): uninitialize:Powering down 2013-08-13T21:59:03.648Z,1376431143.648 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler 2013-08-13T21:59:03.705Z,1376431143.705 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator. 2013-08-13T21:59:03.709Z,1376431143.709 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2013-08-13T21:59:03.735Z,1376431143.735 [MissionManager](IMPORTANT): Started mission Default 2013-08-13T21:59:03.735Z,1376431143.735 [Default] Running Loop=1 2013-08-13T21:59:03.735Z,1376431143.735 [Default](INFO): Aggregate::initialize Default 2013-08-13T21:59:03.736Z,1376431143.736 [Default:D.SetSpeed] Running Loop=1 2013-08-13T21:59:03.736Z,1376431143.736 [Default:D.SetSpeed](DEBUG): Initialize. 2013-08-13T21:59:03.736Z,1376431143.736 [Default:E.GoToSurface] Running Loop=1 2013-08-13T21:59:03.736Z,1376431143.736 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-08-13T21:59:03.736Z,1376431143.736 [Default:Iridium] Running Loop=1 2013-08-13T21:59:03.736Z,1376431143.736 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2013-08-13T21:59:03.737Z,1376431143.737 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-08-13T21:59:03.737Z,1376431143.737 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2013-08-13T21:59:03.737Z,1376431143.737 [Default:Iridium:B.GoToSurface] Running Loop=1 2013-08-13T21:59:03.737Z,1376431143.737 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-08-13T21:59:03.739Z,1376431143.739 [Default:Iridium:B.GoToSurface] Stopped 2013-08-13T21:59:03.739Z,1376431143.739 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-08-13T21:59:03.739Z,1376431143.739 [Default:Iridium:Read_Iridium] Running Loop=1 2013-08-13T21:59:03.739Z,1376431143.739 [Default:GPS] Running Loop=1 2013-08-13T21:59:03.740Z,1376431143.740 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2013-08-13T21:59:03.740Z,1376431143.740 [Default:GPS:A.SetSpeed] Running Loop=1 2013-08-13T21:59:03.740Z,1376431143.740 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2013-08-13T21:59:03.740Z,1376431143.740 [Default:GPS:B.GoToSurface] Running Loop=1 2013-08-13T21:59:03.740Z,1376431143.740 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-08-13T21:59:03.741Z,1376431143.741 [Default:GPS:B.GoToSurface] Stopped 2013-08-13T21:59:03.741Z,1376431143.741 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-08-13T21:59:03.741Z,1376431143.741 [Default:GPS:Read_GPS] Running Loop=1 2013-08-13T21:59:03.813Z,1376431143.813 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2013-08-13T21:59:03.817Z,1376431143.817 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2013-08-13T21:59:03.917Z,1376431143.917 [controlThread](DEBUG): Uninitializing ControlThread 2013-08-13T21:59:03.917Z,1376431143.917 [AHRS_sp3003D](INFO): Powering down 2013-08-13T21:59:04.006Z,1376431144.006 [NAL9602](INFO): Powering down 2013-08-13T21:59:04.006Z,1376431144.006 [DAT](INFO): Powering down 2013-08-13T21:59:04.007Z,1376431144.007 [Aanderaa_O2](INFO): Powering down 2013-08-13T21:59:04.008Z,1376431144.008 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2013-08-13T21:59:04.009Z,1376431144.009 [NavChart](DEBUG): Uninitialize NavChart Derivation. 2013-08-13T21:59:04.010Z,1376431144.010 [Default] Stopped 2013-08-13T21:59:04.010Z,1376431144.010 [Default](INFO): Aggregate::uninitialize Default 2013-08-13T21:59:04.010Z,1376431144.010 [Default:GPS] Stopped 2013-08-13T21:59:04.010Z,1376431144.010 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2013-08-13T21:59:04.011Z,1376431144.011 [Default:GPS:A.SetSpeed] Stopped 2013-08-13T21:59:04.011Z,1376431144.011 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2013-08-13T21:59:04.011Z,1376431144.011 [Default:GPS:Read_GPS] Stopped 2013-08-13T21:59:04.011Z,1376431144.011 [Default:Iridium] Stopped 2013-08-13T21:59:04.011Z,1376431144.011 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2013-08-13T21:59:04.011Z,1376431144.011 [Default:Iridium:A.SetSpeed] Stopped 2013-08-13T21:59:04.011Z,1376431144.011 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2013-08-13T21:59:04.011Z,1376431144.011 [Default:Iridium:Read_Iridium] Stopped 2013-08-13T21:59:04.011Z,1376431144.011 [Default:D.SetSpeed] Stopped 2013-08-13T21:59:04.011Z,1376431144.011 [Default:D.SetSpeed](DEBUG): Uninitialize. 2013-08-13T21:59:04.011Z,1376431144.011 [Default:E.GoToSurface] Stopped 2013-08-13T21:59:04.011Z,1376431144.011 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-08-13T21:59:04.015Z,1376431144.015 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2013-08-13T21:59:04.016Z,1376431144.016 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2013-08-13T21:59:04.016Z,1376431144.016 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2013-08-13T21:59:04.016Z,1376431144.016 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2013-08-13T21:59:04.017Z,1376431144.017 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2013-08-13T21:59:04.018Z,1376431144.018 [BuoyancyServo](INFO): Powering down 2013-08-13T21:59:04.033Z,1376431144.033 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2013-08-13T21:59:04.033Z,1376431144.033 [ElevatorServo](INFO): Powering down 2013-08-13T21:59:04.034Z,1376431144.034 [MassServo](DEBUG): Uninitialize Mass Servo. 2013-08-13T21:59:04.034Z,1376431144.034 [MassServo](INFO): Powering down 2013-08-13T21:59:04.035Z,1376431144.035 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2013-08-13T21:59:04.035Z,1376431144.035 [RudderServo](INFO): Powering down 2013-08-13T21:59:04.036Z,1376431144.036 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-08-13T21:59:04.036Z,1376431144.036 [ThrusterServo](INFO): Powering down 2013-08-13T21:59:04.037Z,1376431144.037 [SBIT](DEBUG): Uninitialize SBIT Component. 2013-08-13T21:59:04.038Z,1376431144.038 [IBIT](DEBUG): Uninitialize IBIT Component. 2013-08-13T21:59:04.038Z,1376431144.038 [CBIT](DEBUG): Uninitialize CBIT Component.