2012-10-25T22:34:47.099Z,1351204487.099 [Supervisor](DEBUG): Initializing supervisor. 2012-10-25T22:34:47.102Z,1351204487.102 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2012-10-25T22:34:47.103Z,1351204487.103 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2012-10-25T22:34:47.104Z,1351204487.104 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2012-10-25T22:34:47.108Z,1351204487.108 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2012-10-25T22:34:47.119Z,1351204487.119 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2012-10-25T22:34:47.120Z,1351204487.121 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2012-10-25T22:34:47.121Z,1351204487.121 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread. 2012-10-25T22:34:47.122Z,1351204487.122 [Supervisor](INFO): Looking for Config files in directory: Config/ 2012-10-25T22:34:47.125Z,1351204487.125 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2012-10-25T22:34:47.488Z,1351204487.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2012-10-25T22:34:47.490Z,1351204487.490 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2012-10-25T22:34:47.677Z,1351204487.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2012-10-25T22:34:47.678Z,1351204487.678 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2012-10-25T22:34:47.764Z,1351204487.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2012-10-25T22:34:47.766Z,1351204487.766 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2012-10-25T22:34:47.982Z,1351204487.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2012-10-25T22:34:47.983Z,1351204487.983 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2012-10-25T22:34:48.124Z,1351204488.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2012-10-25T22:34:48.126Z,1351204488.126 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2012-10-25T22:34:48.371Z,1351204488.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2012-10-25T22:34:48.372Z,1351204488.372 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2012-10-25T22:34:48.548Z,1351204488.548 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2012-10-25T22:34:48.550Z,1351204488.550 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2012-10-25T22:34:48.817Z,1351204488.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2012-10-25T22:34:48.819Z,1351204488.819 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2012-10-25T22:34:48.919Z,1351204488.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2012-10-25T22:34:48.920Z,1351204488.920 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2012-10-25T22:34:49.343Z,1351204489.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2012-10-25T22:34:49.344Z,1351204489.344 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2012-10-25T22:34:49.460Z,1351204489.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2012-10-25T22:34:49.461Z,1351204489.461 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2012-10-25T22:34:49.547Z,1351204489.547 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2012-10-25T22:34:49.551Z,1351204489.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2012-10-25T22:34:49.655Z,1351204489.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2012-10-25T22:34:49.789Z,1351204489.789 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2012-10-25T22:34:49.876Z,1351204489.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2012-10-25T22:34:49.980Z,1351204489.980 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2012-10-25T22:34:50.082Z,1351204490.082 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2012-10-25T22:34:50.203Z,1351204490.203 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2012-10-25T22:34:50.295Z,1351204490.295 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2012-10-25T22:34:50.383Z,1351204490.383 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2012-10-25T22:34:50.414Z,1351204490.414 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2012-10-25T22:34:50.592Z,1351204490.592 [InternalSim] Loaded 2012-10-25T22:34:50.592Z,1351204490.592 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2012-10-25T22:34:50.593Z,1351204490.593 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2012-10-25T22:34:50.594Z,1351204490.594 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2012-10-25T22:34:50.674Z,1351204490.674 [SBIT](DEBUG): Construct Startup Built In Test. 2012-10-25T22:34:50.689Z,1351204490.689 [SBIT] Loaded 2012-10-25T22:34:50.689Z,1351204490.689 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2012-10-25T22:34:50.690Z,1351204490.690 [IBIT](DEBUG): Construct Initiated Built In Test. 2012-10-25T22:34:50.721Z,1351204490.721 [IBIT] Loaded 2012-10-25T22:34:50.722Z,1351204490.722 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2012-10-25T22:34:50.725Z,1351204490.725 [CBIT](DEBUG): Construct CBIT Built In Test. 2012-10-25T22:34:50.855Z,1351204490.855 [CBIT] Loaded 2012-10-25T22:34:50.855Z,1351204490.855 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2012-10-25T22:34:50.855Z,1351204490.855 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2012-10-25T22:34:50.856Z,1351204490.856 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2012-10-25T22:34:51.022Z,1351204491.022 [BuoyancyServo] Loaded 2012-10-25T22:34:51.022Z,1351204491.022 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2012-10-25T22:34:51.032Z,1351204491.032 [ElevatorServo] Loaded 2012-10-25T22:34:51.032Z,1351204491.032 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2012-10-25T22:34:51.045Z,1351204491.045 [MassServo] Loaded 2012-10-25T22:34:51.046Z,1351204491.046 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2012-10-25T22:34:51.059Z,1351204491.059 [RudderServo] Loaded 2012-10-25T22:34:51.059Z,1351204491.059 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2012-10-25T22:34:51.072Z,1351204491.072 [ThrusterServo] Loaded 2012-10-25T22:34:51.072Z,1351204491.072 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2012-10-25T22:34:51.072Z,1351204491.072 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2012-10-25T22:34:51.073Z,1351204491.073 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2012-10-25T22:34:51.102Z,1351204491.102 [DepthRateCalculator] Loaded 2012-10-25T22:34:51.103Z,1351204491.103 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2012-10-25T22:34:55.527Z,1351204495.527 [HFRadarModelCalc] Loaded 2012-10-25T22:34:55.528Z,1351204495.528 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2012-10-25T22:34:55.545Z,1351204495.545 [NavChart] Loaded 2012-10-25T22:34:55.546Z,1351204495.546 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2012-10-25T22:34:55.552Z,1351204495.552 [PitchRateCalculator] Loaded 2012-10-25T22:34:55.552Z,1351204495.552 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2012-10-25T22:34:55.559Z,1351204495.559 [SpeedCalculator] Loaded 2012-10-25T22:34:55.559Z,1351204495.559 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2012-10-25T22:34:55.575Z,1351204495.575 [TempGradientCalculator] Loaded 2012-10-25T22:34:55.576Z,1351204495.576 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2012-10-25T22:34:55.582Z,1351204495.582 [YawRateCalculator] Loaded 2012-10-25T22:34:55.582Z,1351204495.582 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2012-10-25T22:34:55.604Z,1351204495.603 [Navigation] Loaded 2012-10-25T22:34:55.604Z,1351204495.604 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2012-10-25T22:34:55.604Z,1351204495.604 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2012-10-25T22:34:55.605Z,1351204495.605 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2012-10-25T22:34:55.875Z,1351204495.875 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2012-10-25T22:34:55.876Z,1351204495.876 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2012-10-25T22:34:55.914Z,1351204495.914 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2012-10-25T22:34:55.915Z,1351204495.915 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2012-10-25T22:34:55.969Z,1351204495.968 [VerticalControl](DEBUG): Construct VerticalControl. 2012-10-25T22:34:56.020Z,1351204496.020 [VerticalControl] Loaded 2012-10-25T22:34:56.020Z,1351204496.020 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2012-10-25T22:34:56.021Z,1351204496.021 [HorizontalControl](DEBUG): Construct HorizontalControl. 2012-10-25T22:34:56.044Z,1351204496.044 [HorizontalControl] Loaded 2012-10-25T22:34:56.044Z,1351204496.044 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2012-10-25T22:34:56.045Z,1351204496.045 [SpeedControl](DEBUG): Construct SpeedControl. 2012-10-25T22:34:56.047Z,1351204496.047 [SpeedControl] Loaded 2012-10-25T22:34:56.047Z,1351204496.047 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2012-10-25T22:34:56.048Z,1351204496.048 [LoopControl](DEBUG): Construct LoopControl. 2012-10-25T22:34:56.049Z,1351204496.049 [LoopControl] Loaded 2012-10-25T22:34:56.049Z,1351204496.049 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2012-10-25T22:34:56.050Z,1351204496.050 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2012-10-25T22:34:56.050Z,1351204496.050 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2012-10-25T22:34:56.062Z,1351204496.062 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2012-10-25T22:34:56.068Z,1351204496.068 [AsyncPiEstimator] Loaded 2012-10-25T22:34:56.069Z,1351204496.069 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2012-10-25T22:34:56.070Z,1351204496.070 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406714E0 2012-10-25T22:34:56.071Z,1351204496.071 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2012-10-25T22:34:56.071Z,1351204496.071 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2012-10-25T22:34:56.241Z,1351204496.241 [AHRS_sp3003D] Loaded 2012-10-25T22:34:56.241Z,1351204496.241 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2012-10-25T22:34:56.522Z,1351204496.522 [Batt_Ocean_Server] Loaded 2012-10-25T22:34:56.522Z,1351204496.522 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2012-10-25T22:34:56.536Z,1351204496.536 [Depth_Keller] Loaded 2012-10-25T22:34:56.536Z,1351204496.536 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2012-10-25T22:34:56.543Z,1351204496.543 [DropWeight] Loaded 2012-10-25T22:34:56.543Z,1351204496.543 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2012-10-25T22:34:56.672Z,1351204496.672 [DVL_micro] Loaded 2012-10-25T22:34:56.672Z,1351204496.672 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2012-10-25T22:34:56.673Z,1351204496.673 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406F24E0 2012-10-25T22:34:56.763Z,1351204496.763 [NAL9602] Loaded 2012-10-25T22:34:56.764Z,1351204496.764 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2012-10-25T22:34:56.819Z,1351204496.819 [Onboard] Loaded 2012-10-25T22:34:56.819Z,1351204496.819 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2012-10-25T22:34:56.827Z,1351204496.827 [Radio_Freewave] Loaded 2012-10-25T22:34:56.827Z,1351204496.827 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2012-10-25T22:34:56.828Z,1351204496.828 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2012-10-25T22:34:56.828Z,1351204496.828 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2012-10-25T22:34:56.924Z,1351204496.924 [CTD_NeilBrown] Loaded 2012-10-25T22:34:56.925Z,1351204496.925 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2012-10-25T22:34:56.926Z,1351204496.926 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4074A4E0 2012-10-25T22:34:56.937Z,1351204496.937 [PAR_Licor] Loaded 2012-10-25T22:34:56.937Z,1351204496.937 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2012-10-25T22:34:56.957Z,1351204496.957 [Turbulence_NPS] Loaded 2012-10-25T22:34:56.957Z,1351204496.957 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread. 2012-10-25T22:34:56.958Z,1351204496.958 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0 2012-10-25T22:34:56.990Z,1351204496.990 [WetLabsBB2FL] Loaded 2012-10-25T22:34:56.991Z,1351204496.991 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2012-10-25T22:34:56.992Z,1351204496.992 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0 2012-10-25T22:34:56.993Z,1351204496.993 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2012-10-25T22:34:56.995Z,1351204496.995 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2012-10-25T22:34:56.996Z,1351204496.996 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2012-10-25T22:34:57.003Z,1351204497.003 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2012-10-25T22:34:57.005Z,1351204497.005 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0 2012-10-25T22:34:57.009Z,1351204497.009 [Supervisor](DEBUG): Running supervisor. 2012-10-25T22:34:57.010Z,1351204497.010 [CommandLine](INFO): Thread ID is 5239 2012-10-25T22:34:57.013Z,1351204497.013 [controlThread](INFO): Thread ID is 5238 2012-10-25T22:34:57.013Z,1351204497.013 [controlThread](DEBUG): Initializing ControlThread 2012-10-25T22:34:57.014Z,1351204497.013 [CycleStarter](INFO): Thread ID is 5237 2012-10-25T22:34:57.014Z,1351204497.014 [InternalSim](DEBUG): InternalSim initializing... 2012-10-25T22:34:57.047Z,1351204497.047 [AsyncPiEstimator](INFO): Thread ID is 5300 2012-10-25T22:34:57.047Z,1351204497.047 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2012-10-25T22:34:57.060Z,1351204497.060 [SBIT](INFO): Initialize SBIT Component. 2012-10-25T22:34:57.061Z,1351204497.061 [SBIT](IMPORTANT): Tethys CM Info: $Rev: 9999 2012-10-25T22:34:57.061Z,1351204497.061 [IBIT](INFO): Initialize IBIT Component. 2012-10-25T22:34:57.062Z,1351204497.062 [CBIT](DEBUG): Initialize CBIT Component. 2012-10-25T22:34:57.062Z,1351204497.062 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2012-10-25T22:34:57.063Z,1351204497.063 [CBIT](CRITICAL): Watchdog Timer failed to initialize. 2012-10-25T22:34:57.063Z,1351204497.063 [CBIT] Hardware Fault, FailCount= 1 2012-10-25T22:34:57.063Z,1351204497.063 [CBIT](ERROR): Hardware Fault 2012-10-25T22:34:57.064Z,1351204497.064 [DVL_micro](INFO): Thread ID is 5301 2012-10-25T22:34:57.073Z,1351204497.073 [DVL_micro](INFO): Initializing 2012-10-25T22:34:57.073Z,1351204497.073 [DVL_micro](INFO): start:Powering up 2012-10-25T22:34:57.075Z,1351204497.075 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-25T22:34:57.075Z,1351204497.076 [DVL_micro](INFO): Cycling power to configure device. 2012-10-25T22:34:57.088Z,1351204497.088 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2012-10-25T22:34:57.088Z,1351204497.088 [NavChart](DEBUG): Initialize NavChart Derivation. 2012-10-25T22:34:57.089Z,1351204497.089 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2012-10-25T22:34:57.089Z,1351204497.089 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2012-10-25T22:34:57.090Z,1351204497.089 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2012-10-25T22:34:57.091Z,1351204497.091 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2012-10-25T22:34:57.091Z,1351204497.091 [Navigation](DEBUG): Initializing Navigation. 2012-10-25T22:34:57.092Z,1351204497.092 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2012-10-25T22:34:57.094Z,1351204497.093 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2012-10-25T22:34:57.094Z,1351204497.094 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2012-10-25T22:34:57.095Z,1351204497.095 [LoopControl](DEBUG): Initialize LoopControlComponent. 2012-10-25T22:34:57.097Z,1351204497.097 [CTD_NeilBrown](INFO): Thread ID is 5302 2012-10-25T22:34:57.097Z,1351204497.098 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2012-10-25T22:34:57.103Z,1351204497.103 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-10-25T22:34:57.113Z,1351204497.113 [Turbulence_NPS](INFO): Thread ID is 5303 2012-10-25T22:34:57.113Z,1351204497.113 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS. 2012-10-25T22:34:57.114Z,1351204497.114 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-10-25T22:34:57.126Z,1351204497.126 [WetLabsBB2FL](INFO): Thread ID is 5304 2012-10-25T22:34:57.127Z,1351204497.127 [WetLabsBB2FL](INFO): Powering down 2012-10-25T22:34:57.148Z,1351204497.148 [NavChartDb](INFO): Thread ID is 5305 2012-10-25T22:34:57.154Z,1351204497.154 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2012-10-25T22:34:57.159Z,1351204497.159 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2012-10-25T22:34:57.160Z,1351204497.160 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2012-10-25T22:34:57.160Z,1351204497.160 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2012-10-25T22:34:57.160Z,1351204497.160 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2012-10-25T22:34:57.183Z,1351204497.183 [Turbulence_NPS](INFO): Pause powering down 2012-10-25T22:34:58.739Z,1351204498.739 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2012-10-25T22:34:58.768Z,1351204498.768 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2012-10-25T22:34:58.788Z,1351204498.788 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-25T22:34:58.798Z,1351204498.798 [MissionManager](DEBUG): 2012-10-25T22:34:58.799Z,1351204498.799 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2012-10-25T22:34:58.834Z,1351204498.834 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2012-10-25T22:34:58.836Z,1351204498.836 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2012-10-25T22:34:58.839Z,1351204498.839 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-25T22:34:58.848Z,1351204498.848 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2012-10-25T22:34:58.851Z,1351204498.851 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-25T22:34:58.861Z,1351204498.861 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2012-10-25T22:34:58.870Z,1351204498.870 [Default:D.SetSpeed](DEBUG): Construct. 2012-10-25T22:34:58.873Z,1351204498.873 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-25T22:34:58.878Z,1351204498.878 [Default:F.Wait](DEBUG): Construct Wait. 2012-10-25T22:34:58.882Z,1351204498.882 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2012-10-25T22:34:58.886Z,1351204498.886 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger, 2012-10-25T22:34:58.906Z,1351204498.906 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2012-10-25T22:34:59.355Z,1351204499.355 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-25T22:34:59.355Z,1351204499.355 [DVL_micro](INFO): Querying output modes 2012-10-25T22:34:59.356Z,1351204499.356 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-25T22:34:59.385Z,1351204499.385 [DVL_micro](DEBUG): cmdResponse: 01 2012-10-25T22:34:59.385Z,1351204499.385 [DVL_micro](INFO): NQ1 output enabled 2012-10-25T22:34:59.386Z,1351204499.386 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-25T22:34:59.388Z,1351204499.388 [Radio_Freewave](INFO): Powering up 2012-10-25T22:34:59.391Z,1351204499.391 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-25T22:34:59.396Z,1351204499.396 [DVL_micro](INFO): pause:Powering down 2012-10-25T22:34:59.571Z,1351204499.571 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2012-10-25T22:34:59.575Z,1351204499.575 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2012-10-25T22:34:59.581Z,1351204499.581 [ElevatorServo](DEBUG): Initializing EZServoServo. 2012-10-25T22:34:59.582Z,1351204499.582 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2012-10-25T22:34:59.588Z,1351204499.588 [MassServo](DEBUG): Initializing EZServoServo. 2012-10-25T22:34:59.591Z,1351204499.591 [MassServo](DEBUG): Initializing MassServo. 2012-10-25T22:34:59.596Z,1351204499.596 [RudderServo](DEBUG): Initializing EZServoServo. 2012-10-25T22:34:59.597Z,1351204499.597 [RudderServo](DEBUG): Initializing RudderServo. 2012-10-25T22:34:59.603Z,1351204499.603 [ThrusterServo](DEBUG): Initializing EZServoServo. 2012-10-25T22:34:59.607Z,1351204499.607 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2012-10-25T22:34:59.625Z,1351204499.625 [CBIT](DEBUG): Uninitialize CBIT Component. 2012-10-25T22:35:02.055Z,1351204502.055 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout 2012-10-25T22:35:02.055Z,1351204502.055 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0xA409A0 2012-10-25T22:35:02.055Z,1351204502.055 [AHRS_sp3003D](ERROR): SP3003D failed to initialize 2012-10-25T22:35:02.055Z,1351204502.055 [AHRS_sp3003D] Hardware Fault, FailCount= 1 2012-10-25T22:35:02.055Z,1351204502.055 [AHRS_sp3003D](ERROR): Hardware Fault 2012-10-25T22:35:02.097Z,1351204502.097 [NAL9602](INFO): Powering up NAL9602 2012-10-25T22:35:02.145Z,1351204502.145 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-25T22:35:02.145Z,1351204502.145 [DVL_micro](INFO): resume:Powering up 2012-10-25T22:35:02.146Z,1351204502.146 [DVL_micro](INFO): Cycling power to configure device. 2012-10-25T22:35:02.651Z,1351204502.651 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2012-10-25T22:35:02.651Z,1351204502.651 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2012-10-25T22:35:02.651Z,1351204502.651 [BuoyancyServo] Communications Fault, FailCount= 1 2012-10-25T22:35:02.651Z,1351204502.651 [BuoyancyServo](ERROR): Communications Fault 2012-10-25T22:35:03.155Z,1351204503.155 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2012-10-25T22:35:03.155Z,1351204503.155 [ElevatorServo](FAULT): Elevator failed to initialize 2012-10-25T22:35:03.155Z,1351204503.155 [ElevatorServo] Communications Fault, FailCount= 1 2012-10-25T22:35:03.155Z,1351204503.155 [ElevatorServo](ERROR): Communications Fault 2012-10-25T22:35:03.659Z,1351204503.659 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2012-10-25T22:35:03.659Z,1351204503.659 [RudderServo](FAULT): Rudder failed to initialize 2012-10-25T22:35:03.659Z,1351204503.659 [RudderServo] Communications Fault, FailCount= 1 2012-10-25T22:35:03.659Z,1351204503.659 [RudderServo](ERROR): Communications Fault 2012-10-25T22:35:04.155Z,1351204504.155 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-25T22:35:04.155Z,1351204504.155 [DVL_micro](INFO): Querying output modes 2012-10-25T22:35:04.156Z,1351204504.156 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-25T22:35:04.167Z,1351204504.167 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2012-10-25T22:35:04.167Z,1351204504.167 [ThrusterServo](FAULT): Thruster failed to initialize 2012-10-25T22:35:04.167Z,1351204504.167 [ThrusterServo] Communications Fault, FailCount= 1 2012-10-25T22:35:04.167Z,1351204504.167 [ThrusterServo](ERROR): Communications Fault 2012-10-25T22:35:04.294Z,1351204504.294 [AHRS_sp3003D](INFO): Powering down 2012-10-25T22:35:04.802Z,1351204504.802 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2012-10-25T22:35:04.802Z,1351204504.802 [BuoyancyServo](INFO): Powering down 2012-10-25T22:35:04.827Z,1351204504.827 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2012-10-25T22:35:04.827Z,1351204504.827 [ElevatorServo](INFO): Powering down 2012-10-25T22:35:05.354Z,1351204505.355 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2012-10-25T22:35:05.355Z,1351204505.355 [MassServo] Communications Fault, FailCount= 1 2012-10-25T22:35:05.355Z,1351204505.355 [MassServo](ERROR): Communications Fault 2012-10-25T22:35:05.355Z,1351204505.355 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2012-10-25T22:35:05.355Z,1351204505.355 [RudderServo](INFO): Powering down 2012-10-25T22:35:05.375Z,1351204505.375 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2012-10-25T22:35:05.375Z,1351204505.375 [ThrusterServo](INFO): Powering down 2012-10-25T22:35:06.159Z,1351204506.158 [DVL_micro](DEBUG): cmdResponse: 2012-10-25T22:35:06.159Z,1351204506.159 [DVL_micro](INFO): Output Modes: No Response 2012-10-25T22:35:06.159Z,1351204506.159 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-25T22:35:06.188Z,1351204506.188 [MassServo](DEBUG): Uninitialize Mass Servo. 2012-10-25T22:35:06.188Z,1351204506.188 [MassServo](INFO): Powering down 2012-10-25T22:35:08.238Z,1351204508.239 [DVL_micro](DEBUG): cmdResponse: 2012-10-25T22:35:13.806Z,1351204513.806 [SBIT](IMPORTANT): Beginning Startup BIT 2012-10-25T22:35:14.499Z,1351204514.499 [DVL_micro](ERROR): No DVL communication! Re-initializing 2012-10-25T22:35:14.500Z,1351204514.500 [DVL_micro] Communications Fault, FailCount= 1 2012-10-25T22:35:14.500Z,1351204514.500 [DVL_micro](ERROR): Communications Fault 2012-10-25T22:35:14.501Z,1351204514.501 [DVL_micro](INFO): uninitialize:Powering down 2012-10-25T22:35:15.970Z,1351204515.970 [NAL9602](ERROR): NAL9602 initialize uart error: serial timeout 2012-10-25T22:35:15.971Z,1351204515.971 [NAL9602] Communications Fault, FailCount= 1 2012-10-25T22:35:15.971Z,1351204515.971 [NAL9602](ERROR): Communications Fault 2012-10-25T22:35:16.752Z,1351204516.752 [NAL9602](INFO): Powering down 2012-10-25T22:35:43.839Z,1351204543.839 [Depth_Keller](INFO): A/D timeout, 2 tries over 556 ms 2012-10-25T22:35:43.839Z,1351204543.839 [Depth_Keller] Data Fault, FailCount= 1 2012-10-25T22:35:43.839Z,1351204543.839 [Depth_Keller](ERROR): Data Fault 2012-10-25T22:35:44.355Z,1351204544.355 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-10-25T22:35:50.547Z,1351204550.546 [CTD_NeilBrown](ERROR): Bad response: 0.14 2012-10-25T22:35:51.635Z,1351204551.635 [CTD_NeilBrown](ERROR): Bad response: -15.26 2012-10-25T22:35:51.659Z,1351204551.659 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.052060 psu 2012-10-25T22:35:52.245Z,1351204552.245 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.066480 psu 2012-10-25T22:35:52.257Z,1351204552.257 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.037910 psu 2012-10-25T22:35:52.271Z,1351204552.271 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.076060 psu 2012-10-25T22:35:52.837Z,1351204552.837 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.069160 psu 2012-10-25T22:35:52.843Z,1351204552.843 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.062060 psu 2012-10-25T22:35:52.887Z,1351204552.887 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.050480 psu 2012-10-25T22:35:53.473Z,1351204553.473 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.061150 psu 2012-10-25T22:35:53.484Z,1351204553.484 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.042980 psu 2012-10-25T22:35:53.944Z,1351204553.944 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.086580 psu 2012-10-25T22:35:53.947Z,1351204553.947 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.075140 psu 2012-10-25T22:35:54.007Z,1351204554.007 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.089660 psu 2012-10-25T22:35:54.589Z,1351204554.589 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.031530 psu 2012-10-25T22:35:54.596Z,1351204554.596 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.046750 psu 2012-10-25T22:35:54.620Z,1351204554.620 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.080440 psu 2012-10-25T22:35:55.021Z,1351204555.021 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.046320 psu 2012-10-25T22:35:55.032Z,1351204555.032 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.034730 psu 2012-10-25T22:35:55.235Z,1351204555.235 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.080950 psu 2012-10-25T22:35:55.724Z,1351204555.724 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017870 psu 2012-10-25T22:35:55.731Z,1351204555.731 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.074270 psu 2012-10-25T22:35:56.184Z,1351204556.184 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022880 psu 2012-10-25T22:35:56.191Z,1351204556.191 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.070490 psu 2012-10-25T22:35:56.259Z,1351204556.259 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039540 psu 2012-10-25T22:35:56.636Z,1351204556.636 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.105910 psu 2012-10-25T22:35:56.668Z,1351204556.668 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.055100 psu 2012-10-25T22:35:57.329Z,1351204557.329 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.041800 psu 2012-10-25T22:35:57.340Z,1351204557.340 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.068560 psu 2012-10-25T22:35:57.342Z,1351204557.342 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.075280 psu 2012-10-25T22:35:57.877Z,1351204557.877 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010830 psu 2012-10-25T22:35:57.888Z,1351204557.888 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.064160 psu 2012-10-25T22:35:57.996Z,1351204557.996 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.053550 psu 2012-10-25T22:35:58.419Z,1351204558.419 [CommandLine](IMPORTANT): got command show stack 2012-10-25T22:35:58.419Z,1351204558.419 [CommandLine](INFO): Behavior Stack: 2012-10-25T22:35:58.419Z,1351204558.419 [MissionManager](INFO): Mission loaded, but not running. 2012-10-25T22:35:58.477Z,1351204558.477 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.101940 psu 2012-10-25T22:35:58.483Z,1351204558.483 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.086850 psu 2012-10-25T22:35:58.964Z,1351204558.964 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.086220 psu 2012-10-25T22:35:58.980Z,1351204558.980 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.092200 psu 2012-10-25T22:35:59.020Z,1351204559.020 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.059190 psu 2012-10-25T22:35:59.223Z,1351204559.223 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.077960 psu 2012-10-25T22:35:59.665Z,1351204559.665 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.044940 psu 2012-10-25T22:35:59.671Z,1351204559.671 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039410 psu 2012-10-25T22:36:00.277Z,1351204560.277 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.028640 psu 2012-10-25T22:36:00.280Z,1351204560.280 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.082160 psu 2012-10-25T22:36:00.287Z,1351204560.286 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.054450 psu 2012-10-25T22:36:00.451Z,1351204560.451 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.058270 psu 2012-10-25T22:36:00.829Z,1351204560.829 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.062950 psu 2012-10-25T22:36:00.863Z,1351204560.863 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.032990 psu 2012-10-25T22:36:01.421Z,1351204561.421 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.051150 psu 2012-10-25T22:36:01.431Z,1351204561.431 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.070490 psu 2012-10-25T22:36:01.476Z,1351204561.476 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.080060 psu 2012-10-25T22:36:01.949Z,1351204561.949 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.071550 psu 2012-10-25T22:36:01.984Z,1351204561.984 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.060290 psu 2012-10-25T22:36:02.681Z,1351204562.681 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.076450 psu 2012-10-25T22:36:02.692Z,1351204562.692 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.106060 psu 2012-10-25T22:36:02.694Z,1351204562.694 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022480 psu 2012-10-25T22:36:03.177Z,1351204563.177 [CTD_NeilBrown](ERROR): Bad response: 5 2012-10-25T22:36:03.183Z,1351204563.183 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.056740 psu 2012-10-25T22:36:03.215Z,1351204563.215 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.042490 psu 2012-10-25T22:36:03.632Z,1351204563.632 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.122970 psu 2012-10-25T22:36:03.639Z,1351204563.639 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.061930 psu 2012-10-25T22:36:03.823Z,1351204563.823 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009800 psu 2012-10-25T22:36:04.201Z,1351204564.201 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.065700 psu 2012-10-25T22:36:04.231Z,1351204564.231 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.061830 psu 2012-10-25T22:36:04.757Z,1351204564.757 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.095730 psu 2012-10-25T22:36:04.767Z,1351204564.767 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.102760 psu 2012-10-25T22:36:04.799Z,1351204564.799 [CommandLine](IMPORTANT): got command restart application 2012-10-25T22:36:05.312Z,1351204565.312 [CTD_NeilBrown](ERROR): Bad response: 05690 2012-10-25T22:36:05.314Z,1351204565.314 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.091320 psu 2012-10-25T22:36:05.321Z,1351204565.321 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.034570 psu 2012-10-25T22:36:05.459Z,1351204565.459 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.059250 psu 2012-10-25T22:36:05.840Z,1351204565.840 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.044970 psu 2012-10-25T22:36:05.871Z,1351204565.871 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.069900 psu 2012-10-25T22:36:05.975Z,1351204565.975 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2012-10-25T22:36:05.975Z,1351204565.975 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2012-10-25T22:36:06.497Z,1351204566.497 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.036410 psu 2012-10-25T22:36:06.508Z,1351204566.508 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.069800 psu 2012-10-25T22:36:06.583Z,1351204566.583 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.061630 psu 2012-10-25T22:36:07.069Z,1351204567.069 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.067460 psu 2012-10-25T22:36:07.076Z,1351204567.076 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.045110 psu 2012-10-25T22:36:07.199Z,1351204567.199 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.081990 psu 2012-10-25T22:36:07.793Z,1351204567.793 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.060560 psu 2012-10-25T22:36:07.800Z,1351204567.800 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.059480 psu 2012-10-25T22:36:07.811Z,1351204567.811 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.038140 psu 2012-10-25T22:36:08.349Z,1351204568.349 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.055930 psu 2012-10-25T22:36:08.359Z,1351204568.359 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.035050 psu 2012-10-25T22:36:08.427Z,1351204568.427 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.033500 psu 2012-10-25T22:36:08.535Z,1351204568.535 [SBIT](IMPORTANT): SBIT PASSED 2012-10-25T22:36:09.021Z,1351204569.021 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.084910 psu 2012-10-25T22:36:09.029Z,1351204569.029 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.037790 psu 2012-10-25T22:36:09.040Z,1351204569.040 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.062700 psu 2012-10-25T22:36:09.045Z,1351204569.045 [MissionManager](IMPORTANT): Started mission Startup 2012-10-25T22:36:09.045Z,1351204569.045 [Startup] Running Loop=1 2012-10-25T22:36:09.045Z,1351204569.046 [Startup](INFO): Aggregate::initialize Startup 2012-10-25T22:36:09.046Z,1351204569.046 [Startup:A.GoToSurface] Running Loop=1 2012-10-25T22:36:09.046Z,1351204569.046 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-25T22:36:09.064Z,1351204569.064 [Startup:StartupSatComms] Running Loop=1 2012-10-25T22:36:09.064Z,1351204569.064 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2012-10-25T22:36:09.064Z,1351204569.064 [Startup:StartupSatComms:A] Running Loop=1 2012-10-25T22:36:09.641Z,1351204569.641 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.036870 psu 2012-10-25T22:36:09.649Z,1351204569.648 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.078410 psu 2012-10-25T22:36:09.661Z,1351204569.661 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-10-25T22:36:09.668Z,1351204569.668 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.092730 psu 2012-10-25T22:36:10.337Z,1351204570.337 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.037930 psu 2012-10-25T22:36:10.349Z,1351204570.349 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.067460 psu 2012-10-25T22:36:10.367Z,1351204570.367 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.041330 psu 2012-10-25T22:36:10.893Z,1351204570.893 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.097460 psu 2012-10-25T22:36:10.900Z,1351204570.899 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.062900 psu 2012-10-25T22:36:10.983Z,1351204570.983 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.028400 psu 2012-10-25T22:36:11.589Z,1351204571.589 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.080150 psu 2012-10-25T22:36:11.596Z,1351204571.596 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.047270 psu 2012-10-25T22:36:11.607Z,1351204571.607 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.016370 psu 2012-10-25T22:36:12.169Z,1351204572.169 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.040470 psu 2012-10-25T22:36:12.176Z,1351204572.176 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.104180 psu 2012-10-25T22:36:12.211Z,1351204572.211 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.049650 psu 2012-10-25T22:36:12.889Z,1351204572.889 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.029650 psu 2012-10-25T22:36:12.900Z,1351204572.900 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.033920 psu 2012-10-25T22:36:12.908Z,1351204572.908 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.089720 psu 2012-10-25T22:36:13.438Z,1351204573.438 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.064060 psu 2012-10-25T22:36:13.444Z,1351204573.444 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.052440 psu 2012-10-25T22:36:13.459Z,1351204573.459 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.066960 psu 2012-10-25T22:36:14.097Z,1351204574.097 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.044690 psu 2012-10-25T22:36:14.108Z,1351204574.108 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011080 psu 2012-10-25T22:36:14.119Z,1351204574.118 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.049700 psu 2012-10-25T22:36:14.645Z,1351204574.645 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.054630 psu 2012-10-25T22:36:14.656Z,1351204574.656 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.047050 psu 2012-10-25T22:36:15.173Z,1351204575.173 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.090710 psu 2012-10-25T22:36:15.184Z,1351204575.184 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039400 psu 2012-10-25T22:36:15.191Z,1351204575.191 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039940 psu 2012-10-25T22:36:15.375Z,1351204575.375 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.051800 psu 2012-10-25T22:36:15.853Z,1351204575.853 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.054920 psu 2012-10-25T22:36:15.863Z,1351204575.864 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.055830 psu 2012-10-25T22:36:16.389Z,1351204576.389 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.054370 psu 2012-10-25T22:36:16.397Z,1351204576.397 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.055690 psu 2012-10-25T22:36:16.412Z,1351204576.412 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.076890 psu 2012-10-25T22:36:16.949Z,1351204576.949 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.046830 psu 2012-10-25T22:36:16.960Z,1351204576.960 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024930 psu 2012-10-25T22:36:17.012Z,1351204577.012 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.068590 psu 2012-10-25T22:36:17.622Z,1351204577.622 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.072120 psu 2012-10-25T22:36:17.636Z,1351204577.636 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.056810 psu 2012-10-25T22:36:17.643Z,1351204577.643 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.026390 psu 2012-10-25T22:36:18.169Z,1351204578.169 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.070600 psu 2012-10-25T22:36:18.180Z,1351204578.180 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.045110 psu 2012-10-25T22:36:18.243Z,1351204578.243 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.051380 psu 2012-10-25T22:36:18.753Z,1351204578.753 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.048780 psu 2012-10-25T22:36:18.760Z,1351204578.760 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.063090 psu 2012-10-25T22:36:19.385Z,1351204579.385 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.026020 psu 2012-10-25T22:36:19.392Z,1351204579.392 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.046150 psu 2012-10-25T22:36:19.402Z,1351204579.402 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.004680 psu 2012-10-25T22:36:19.563Z,1351204579.563 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021210 psu 2012-10-25T22:36:20.073Z,1351204580.073 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.065610 psu 2012-10-25T22:36:20.087Z,1351204580.088 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.035750 psu 2012-10-25T22:36:20.665Z,1351204580.665 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.067320 psu 2012-10-25T22:36:20.676Z,1351204580.676 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.061980 psu 2012-10-25T22:36:20.687Z,1351204580.687 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.058580 psu 2012-10-25T22:36:20.791Z,1351204580.791 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.073410 psu 2012-10-25T22:36:21.360Z,1351204581.360 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.002500 psu 2012-10-25T22:36:21.416Z,1351204581.416 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.023620 psu 2012-10-25T22:36:21.423Z,1351204581.423 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.100000 psu 2012-10-25T22:36:21.904Z,1351204581.904 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.036070 psu 2012-10-25T22:36:21.907Z,1351204581.907 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022850 psu 2012-10-25T22:36:22.015Z,1351204582.015 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.088660 psu 2012-10-25T22:36:22.565Z,1351204582.565 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.053850 psu 2012-10-25T22:36:22.576Z,1351204582.576 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.086660 psu 2012-10-25T22:36:23.156Z,1351204583.156 [CTD_NeilBrown](ERROR): Bad response: 9124 2012-10-25T22:36:23.162Z,1351204583.162 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.074310 psu 2012-10-25T22:36:23.165Z,1351204583.165 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.036490 psu 2012-10-25T22:36:23.243Z,1351204583.243 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.033290 psu 2012-10-25T22:36:23.825Z,1351204583.825 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.041780 psu 2012-10-25T22:36:23.836Z,1351204583.836 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.092730 psu 2012-10-25T22:36:24.409Z,1351204584.409 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.074810 psu 2012-10-25T22:36:24.415Z,1351204584.415 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.041900 psu 2012-10-25T22:36:24.418Z,1351204584.418 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.031070 psu 2012-10-25T22:36:24.904Z,1351204584.904 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.027560 psu 2012-10-25T22:36:24.907Z,1351204584.907 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.043280 psu 2012-10-25T22:36:24.983Z,1351204584.983 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.053590 psu 2012-10-25T22:36:25.497Z,1351204585.497 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039080 psu 2012-10-25T22:36:25.504Z,1351204585.504 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.041500 psu 2012-10-25T22:36:26.109Z,1351204586.109 [CTD_NeilBrown](ERROR): Bad response: 2A 0.15 2012-10-25T22:36:26.119Z,1351204586.119 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.063210 psu 2012-10-25T22:36:26.211Z,1351204586.211 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.086070 psu 2012-10-25T22:36:26.765Z,1351204586.765 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.030790 psu 2012-10-25T22:36:26.776Z,1351204586.776 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.111490 psu 2012-10-25T22:36:27.284Z,1351204587.284 [CTD_NeilBrown](ERROR): Bad response: .01214 2012-10-25T22:36:27.286Z,1351204587.286 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.013120 psu 2012-10-25T22:36:27.300Z,1351204587.300 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021150 psu 2012-10-25T22:36:27.439Z,1351204587.439 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.036350 psu 2012-10-25T22:36:27.933Z,1351204587.933 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.063050 psu 2012-10-25T22:36:27.949Z,1351204587.949 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.016610 psu 2012-10-25T22:36:28.565Z,1351204588.565 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.058000 psu 2012-10-25T22:36:28.572Z,1351204588.572 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.043330 psu 2012-10-25T22:36:28.582Z,1351204588.582 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.046230 psu 2012-10-25T22:36:29.252Z,1351204589.252 [CTD_NeilBrown](ERROR): Bad response: 2.19241 2012-10-25T22:36:29.263Z,1351204589.263 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.020340 psu 2012-10-25T22:36:29.265Z,1351204589.265 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.082600 psu 2012-10-25T22:36:29.797Z,1351204589.797 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.050150 psu 2012-10-25T22:36:29.808Z,1351204589.808 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.058900 psu 2012-10-25T22:36:29.819Z,1351204589.819 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.075480 psu 2012-10-25T22:36:30.469Z,1351204590.469 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.088500 psu 2012-10-25T22:36:30.476Z,1351204590.476 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.026020 psu 2012-10-25T22:36:30.478Z,1351204590.478 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.070190 psu 2012-10-25T22:36:31.005Z,1351204591.005 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.063700 psu 2012-10-25T22:36:31.020Z,1351204591.020 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.050950 psu 2012-10-25T22:36:31.031Z,1351204591.031 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.077230 psu 2012-10-25T22:36:31.629Z,1351204591.629 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.072580 psu 2012-10-25T22:36:31.641Z,1351204591.641 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.062770 psu 2012-10-25T22:36:31.651Z,1351204591.651 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012700 psu 2012-10-25T22:36:32.233Z,1351204592.233 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.088050 psu 2012-10-25T22:36:32.243Z,1351204592.243 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.067990 psu 2012-10-25T22:36:32.347Z,1351204592.347 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015510 psu 2012-10-25T22:36:32.950Z,1351204592.950 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.065850 psu 2012-10-25T22:36:32.957Z,1351204592.957 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.064500 psu 2012-10-25T22:36:32.960Z,1351204592.960 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.051700 psu 2012-10-25T22:36:33.585Z,1351204593.585 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.047750 psu 2012-10-25T22:36:33.596Z,1351204593.596 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.004720 psu 2012-10-25T22:36:33.607Z,1351204593.606 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.034490 psu 2012-10-25T22:36:34.269Z,1351204594.269 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.008790 psu 2012-10-25T22:36:34.280Z,1351204594.280 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.119880 psu 2012-10-25T22:36:34.291Z,1351204594.291 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.052730 psu 2012-10-25T22:36:34.861Z,1351204594.861 [CTD_NeilBrown](ERROR): Bad response: 2C 0.15 2012-10-25T22:36:34.871Z,1351204594.871 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.054110 psu 2012-10-25T22:36:35.516Z,1351204595.516 [CTD_NeilBrown](ERROR): Bad response: 13 2012-10-25T22:36:35.522Z,1351204595.523 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.090410 psu 2012-10-25T22:36:35.525Z,1351204595.525 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.023650 psu 2012-10-25T22:36:36.186Z,1351204596.186 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.021510 psu 2012-10-25T22:36:36.194Z,1351204596.194 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.045960 psu 2012-10-25T22:36:36.218Z,1351204596.218 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.046600 psu 2012-10-25T22:36:36.241Z,1351204596.241 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.043590 psu