2013-02-20T00:49:17.598Z,1361321357.598 [Supervisor](DEBUG): Initializing supervisor. 2013-02-20T00:49:17.600Z,1361321357.600 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2013-02-20T00:49:17.601Z,1361321357.601 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2013-02-20T00:49:17.602Z,1361321357.602 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2013-02-20T00:49:17.606Z,1361321357.606 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2013-02-20T00:49:17.617Z,1361321357.616 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2013-02-20T00:49:17.618Z,1361321357.618 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2013-02-20T00:49:17.618Z,1361321357.618 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2013-02-20T00:49:17.619Z,1361321357.619 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0 2013-02-20T00:49:17.620Z,1361321357.620 [Supervisor](INFO): Looking for Config files in directory: Config/ 2013-02-20T00:49:17.621Z,1361321357.621 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2013-02-20T00:49:17.898Z,1361321357.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2013-02-20T00:49:17.899Z,1361321357.899 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2013-02-20T00:49:18.086Z,1361321358.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2013-02-20T00:49:18.086Z,1361321358.086 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2013-02-20T00:49:18.170Z,1361321358.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2013-02-20T00:49:18.171Z,1361321358.171 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2013-02-20T00:49:18.366Z,1361321358.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2013-02-20T00:49:18.366Z,1361321358.366 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2013-02-20T00:49:18.499Z,1361321358.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2013-02-20T00:49:18.499Z,1361321358.499 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2013-02-20T00:49:18.730Z,1361321358.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2013-02-20T00:49:18.731Z,1361321358.731 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2013-02-20T00:49:18.900Z,1361321358.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2013-02-20T00:49:18.900Z,1361321358.901 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2013-02-20T00:49:19.151Z,1361321359.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2013-02-20T00:49:19.152Z,1361321359.152 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2013-02-20T00:49:19.249Z,1361321359.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2013-02-20T00:49:19.250Z,1361321359.250 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2013-02-20T00:49:19.648Z,1361321359.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2013-02-20T00:49:19.648Z,1361321359.648 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2013-02-20T00:49:19.762Z,1361321359.762 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2013-02-20T00:49:19.762Z,1361321359.763 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2013-02-20T00:49:19.848Z,1361321359.848 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2013-02-20T00:49:19.849Z,1361321359.849 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2013-02-20T00:49:19.951Z,1361321359.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2013-02-20T00:49:20.079Z,1361321360.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2013-02-20T00:49:20.164Z,1361321360.164 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2013-02-20T00:49:20.264Z,1361321360.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2013-02-20T00:49:20.364Z,1361321360.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2013-02-20T00:49:20.481Z,1361321360.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2013-02-20T00:49:20.574Z,1361321360.574 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2013-02-20T00:49:20.660Z,1361321360.660 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2013-02-20T00:49:20.688Z,1361321360.688 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2013-02-20T00:49:20.844Z,1361321360.844 [InternalSim] Loaded 2013-02-20T00:49:20.844Z,1361321360.844 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2013-02-20T00:49:20.845Z,1361321360.845 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2013-02-20T00:49:20.845Z,1361321360.845 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2013-02-20T00:49:20.902Z,1361321360.902 [SBIT](DEBUG): Construct Startup Built In Test. 2013-02-20T00:49:20.931Z,1361321360.931 [SBIT] Loaded 2013-02-20T00:49:20.932Z,1361321360.932 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2013-02-20T00:49:20.933Z,1361321360.933 [IBIT](DEBUG): Construct Initiated Built In Test. 2013-02-20T00:49:20.962Z,1361321360.962 [IBIT] Loaded 2013-02-20T00:49:20.962Z,1361321360.962 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2013-02-20T00:49:20.966Z,1361321360.966 [CBIT](DEBUG): Construct CBIT Built In Test. 2013-02-20T00:49:21.088Z,1361321361.088 [CBIT] Loaded 2013-02-20T00:49:21.088Z,1361321361.088 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2013-02-20T00:49:21.089Z,1361321361.089 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2013-02-20T00:49:21.089Z,1361321361.089 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2013-02-20T00:49:21.240Z,1361321361.240 [BuoyancyServo] Loaded 2013-02-20T00:49:21.240Z,1361321361.240 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2013-02-20T00:49:21.253Z,1361321361.253 [ElevatorServo] Loaded 2013-02-20T00:49:21.253Z,1361321361.253 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2013-02-20T00:49:21.265Z,1361321361.265 [MassServo] Loaded 2013-02-20T00:49:21.265Z,1361321361.265 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2013-02-20T00:49:21.278Z,1361321361.278 [RudderServo] Loaded 2013-02-20T00:49:21.278Z,1361321361.278 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2013-02-20T00:49:21.290Z,1361321361.290 [ThrusterServo] Loaded 2013-02-20T00:49:21.290Z,1361321361.290 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2013-02-20T00:49:21.291Z,1361321361.291 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2013-02-20T00:49:21.291Z,1361321361.291 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2013-02-20T00:49:21.308Z,1361321361.309 [DepthRateCalculator] Loaded 2013-02-20T00:49:21.309Z,1361321361.309 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2013-02-20T00:49:24.653Z,1361321364.653 [HFRadarModelCalc] Loaded 2013-02-20T00:49:24.653Z,1361321364.653 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2013-02-20T00:49:24.669Z,1361321364.669 [NavChart] Loaded 2013-02-20T00:49:24.670Z,1361321364.670 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2013-02-20T00:49:24.676Z,1361321364.676 [PitchRateCalculator] Loaded 2013-02-20T00:49:24.676Z,1361321364.676 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2013-02-20T00:49:24.683Z,1361321364.682 [SpeedCalculator] Loaded 2013-02-20T00:49:24.683Z,1361321364.683 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2013-02-20T00:49:24.698Z,1361321364.698 [TempGradientCalculator] Loaded 2013-02-20T00:49:24.698Z,1361321364.698 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2013-02-20T00:49:24.704Z,1361321364.704 [YawRateCalculator] Loaded 2013-02-20T00:49:24.704Z,1361321364.704 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2013-02-20T00:49:24.746Z,1361321364.746 [Navigation] Loaded 2013-02-20T00:49:24.746Z,1361321364.746 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2013-02-20T00:49:24.747Z,1361321364.747 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2013-02-20T00:49:24.747Z,1361321364.747 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2013-02-20T00:49:24.971Z,1361321364.971 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2013-02-20T00:49:24.972Z,1361321364.972 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2013-02-20T00:49:24.994Z,1361321364.994 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2013-02-20T00:49:24.994Z,1361321364.994 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2013-02-20T00:49:25.034Z,1361321365.034 [VerticalControl](DEBUG): Construct VerticalControl. 2013-02-20T00:49:25.130Z,1361321365.130 [VerticalControl] Loaded 2013-02-20T00:49:25.130Z,1361321365.130 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2013-02-20T00:49:25.131Z,1361321365.131 [HorizontalControl](DEBUG): Construct HorizontalControl. 2013-02-20T00:49:25.189Z,1361321365.189 [HorizontalControl] Loaded 2013-02-20T00:49:25.190Z,1361321365.190 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2013-02-20T00:49:25.191Z,1361321365.191 [SpeedControl](DEBUG): Construct SpeedControl. 2013-02-20T00:49:25.192Z,1361321365.192 [SpeedControl] Loaded 2013-02-20T00:49:25.193Z,1361321365.193 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2013-02-20T00:49:25.193Z,1361321365.194 [LoopControl](DEBUG): Construct LoopControl. 2013-02-20T00:49:25.194Z,1361321365.194 [LoopControl] Loaded 2013-02-20T00:49:25.194Z,1361321365.194 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2013-02-20T00:49:25.195Z,1361321365.195 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2013-02-20T00:49:25.195Z,1361321365.195 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2013-02-20T00:49:25.201Z,1361321365.201 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2013-02-20T00:49:25.206Z,1361321365.205 [AsyncPiEstimator] Loaded 2013-02-20T00:49:25.206Z,1361321365.206 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2013-02-20T00:49:25.207Z,1361321365.207 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406A44E0 2013-02-20T00:49:25.208Z,1361321365.208 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2013-02-20T00:49:25.208Z,1361321365.208 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2013-02-20T00:49:25.336Z,1361321365.336 [AHRS_sp3003D] Loaded 2013-02-20T00:49:25.337Z,1361321365.337 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2013-02-20T00:49:25.601Z,1361321365.601 [Batt_Ocean_Server] Loaded 2013-02-20T00:49:25.601Z,1361321365.601 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2013-02-20T00:49:25.615Z,1361321365.615 [Depth_Keller] Loaded 2013-02-20T00:49:25.615Z,1361321365.615 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2013-02-20T00:49:25.621Z,1361321365.621 [DropWeight] Loaded 2013-02-20T00:49:25.622Z,1361321365.622 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2013-02-20T00:49:25.785Z,1361321365.786 [DVL_micro] Loaded 2013-02-20T00:49:25.786Z,1361321365.786 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2013-02-20T00:49:25.787Z,1361321365.787 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 407284E0 2013-02-20T00:49:25.871Z,1361321365.871 [NAL9602] Loaded 2013-02-20T00:49:25.871Z,1361321365.871 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2013-02-20T00:49:25.923Z,1361321365.923 [Onboard] Loaded 2013-02-20T00:49:25.923Z,1361321365.923 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2013-02-20T00:49:25.930Z,1361321365.930 [Radio_Freewave] Loaded 2013-02-20T00:49:25.930Z,1361321365.931 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2013-02-20T00:49:25.931Z,1361321365.931 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2013-02-20T00:49:25.932Z,1361321365.932 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2013-02-20T00:49:25.989Z,1361321365.989 [Aanderaa_O2] Loaded 2013-02-20T00:49:25.989Z,1361321365.989 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2013-02-20T00:49:26.023Z,1361321366.023 [CTD_NeilBrown] Loaded 2013-02-20T00:49:26.023Z,1361321366.023 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2013-02-20T00:49:26.024Z,1361321366.024 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0 2013-02-20T00:49:26.038Z,1361321366.038 [ISUS] Loaded 2013-02-20T00:49:26.038Z,1361321366.038 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread. 2013-02-20T00:49:26.054Z,1361321366.054 [PAR_Licor] Loaded 2013-02-20T00:49:26.055Z,1361321366.055 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2013-02-20T00:49:26.085Z,1361321366.085 [WetLabsBB2FL] Loaded 2013-02-20T00:49:26.085Z,1361321366.085 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2013-02-20T00:49:26.086Z,1361321366.086 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0 2013-02-20T00:49:26.087Z,1361321366.087 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2013-02-20T00:49:26.089Z,1361321366.089 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2013-02-20T00:49:26.090Z,1361321366.090 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2013-02-20T00:49:26.097Z,1361321366.097 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2013-02-20T00:49:26.098Z,1361321366.098 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0 2013-02-20T00:49:26.102Z,1361321366.102 [Supervisor](DEBUG): Running supervisor. 2013-02-20T00:49:26.103Z,1361321366.103 [CommandLine](INFO): Thread ID is 4874 2013-02-20T00:49:26.106Z,1361321366.106 [controlThread](INFO): Thread ID is 4873 2013-02-20T00:49:26.106Z,1361321366.106 [controlThread](DEBUG): Initializing ControlThread 2013-02-20T00:49:26.106Z,1361321366.106 [CycleStarter](INFO): Thread ID is 4872 2013-02-20T00:49:26.107Z,1361321366.107 [InternalSim](DEBUG): InternalSim initializing... 2013-02-20T00:49:26.143Z,1361321366.143 [logger](INFO): Thread ID is 4875 2013-02-20T00:49:26.167Z,1361321366.167 [SBIT](INFO): Initialize SBIT Component. 2013-02-20T00:49:26.168Z,1361321366.168 [SBIT](IMPORTANT): Tethys CM Info: $Rev: 10131 2013-02-20T00:49:26.168Z,1361321366.168 [IBIT](INFO): Initialize IBIT Component. 2013-02-20T00:49:26.169Z,1361321366.169 [CBIT](DEBUG): Initialize CBIT Component. 2013-02-20T00:49:26.169Z,1361321366.170 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2013-02-20T00:49:26.179Z,1361321366.179 [AsyncPiEstimator](INFO): Thread ID is 4936 2013-02-20T00:49:26.179Z,1361321366.179 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2013-02-20T00:49:26.197Z,1361321366.197 [DVL_micro](INFO): Thread ID is 4937 2013-02-20T00:49:26.199Z,1361321366.199 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2013-02-20T00:49:26.200Z,1361321366.200 [NavChart](DEBUG): Initialize NavChart Derivation. 2013-02-20T00:49:26.200Z,1361321366.200 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2013-02-20T00:49:26.201Z,1361321366.201 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2013-02-20T00:49:26.201Z,1361321366.201 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2013-02-20T00:49:26.203Z,1361321366.203 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2013-02-20T00:49:26.203Z,1361321366.203 [Navigation](DEBUG): Initializing Navigation. 2013-02-20T00:49:26.204Z,1361321366.204 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2013-02-20T00:49:26.205Z,1361321366.205 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2013-02-20T00:49:26.206Z,1361321366.206 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2013-02-20T00:49:26.207Z,1361321366.207 [LoopControl](DEBUG): Initialize LoopControlComponent. 2013-02-20T00:49:26.217Z,1361321366.217 [DVL_micro](INFO): Initializing 2013-02-20T00:49:26.217Z,1361321366.217 [DVL_micro](INFO): start:Powering up 2013-02-20T00:49:26.219Z,1361321366.218 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-02-20T00:49:26.219Z,1361321366.219 [DVL_micro](INFO): Cycling power to configure device. 2013-02-20T00:49:26.227Z,1361321366.227 [CTD_NeilBrown](INFO): Thread ID is 4938 2013-02-20T00:49:26.227Z,1361321366.227 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2013-02-20T00:49:26.243Z,1361321366.243 [WetLabsBB2FL](INFO): Thread ID is 4939 2013-02-20T00:49:26.243Z,1361321366.243 [WetLabsBB2FL](INFO): Powering down 2013-02-20T00:49:26.279Z,1361321366.279 [NavChartDb](INFO): Thread ID is 4940 2013-02-20T00:49:26.281Z,1361321366.281 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2013-02-20T00:49:26.285Z,1361321366.285 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2013-02-20T00:49:26.287Z,1361321366.287 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2013-02-20T00:49:26.289Z,1361321366.289 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2013-02-20T00:49:26.290Z,1361321366.290 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2013-02-20T00:49:26.292Z,1361321366.292 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2013-02-20T00:49:26.294Z,1361321366.293 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2013-02-20T00:49:26.295Z,1361321366.295 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2013-02-20T00:49:26.297Z,1361321366.297 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2013-02-20T00:49:26.299Z,1361321366.299 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2013-02-20T00:49:26.486Z,1361321366.486 [NavChartDb](INFO): Setup scan of Resources/US5CA62M.000 2013-02-20T00:49:26.634Z,1361321366.634 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.000000 psu 2013-02-20T00:49:28.040Z,1361321368.039 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2013-02-20T00:49:28.095Z,1361321368.095 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2013-02-20T00:49:28.107Z,1361321368.107 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2013-02-20T00:49:28.117Z,1361321368.117 [MissionManager](DEBUG): 2013-02-20T00:49:28.118Z,1361321368.118 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2013-02-20T00:49:28.146Z,1361321368.146 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2013-02-20T00:49:28.168Z,1361321368.168 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2013-02-20T00:49:28.176Z,1361321368.175 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-02-20T00:49:28.195Z,1361321368.195 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2013-02-20T00:49:28.198Z,1361321368.198 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-02-20T00:49:28.207Z,1361321368.207 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2013-02-20T00:49:28.216Z,1361321368.216 [Default:D.SetSpeed](DEBUG): Construct. 2013-02-20T00:49:28.219Z,1361321368.219 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2013-02-20T00:49:28.224Z,1361321368.224 [Default:F.Wait](DEBUG): Construct Wait. 2013-02-20T00:49:28.227Z,1361321368.227 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2013-02-20T00:49:28.232Z,1361321368.232 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,Aanderaa_O2,ISUS,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, 2013-02-20T00:49:28.278Z,1361321368.278 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-02-20T00:49:28.279Z,1361321368.279 [DVL_micro](INFO): Querying output modes 2013-02-20T00:49:28.279Z,1361321368.279 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2013-02-20T00:49:28.290Z,1361321368.290 [DVL_micro](DEBUG): cmdResponse: 01 2013-02-20T00:49:28.290Z,1361321368.290 [DVL_micro](INFO): NQ1 output enabled 2013-02-20T00:49:28.291Z,1361321368.291 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2013-02-20T00:49:28.295Z,1361321368.295 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2013-02-20T00:49:28.303Z,1361321368.303 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2013-02-20T00:49:28.308Z,1361321368.308 [DVL_micro](INFO): pause:Powering down 2013-02-20T00:49:28.426Z,1361321368.426 [Radio_Freewave](INFO): Powering up 2013-02-20T00:49:28.616Z,1361321368.616 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:28.617Z,1361321368.617 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2013-02-20T00:49:28.624Z,1361321368.624 [ElevatorServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:28.624Z,1361321368.625 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2013-02-20T00:49:28.631Z,1361321368.631 [MassServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:28.632Z,1361321368.632 [MassServo](DEBUG): Initializing MassServo. 2013-02-20T00:49:28.637Z,1361321368.638 [RudderServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:28.638Z,1361321368.638 [RudderServo](DEBUG): Initializing RudderServo. 2013-02-20T00:49:28.644Z,1361321368.644 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:28.645Z,1361321368.645 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-02-20T00:49:29.782Z,1361321369.782 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2013-02-20T00:49:29.782Z,1361321369.782 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2013-02-20T00:49:29.782Z,1361321369.782 [BuoyancyServo] Communications Fault, FailCount= 1 2013-02-20T00:49:29.783Z,1361321369.783 [BuoyancyServo](ERROR): Communications Fault 2013-02-20T00:49:30.290Z,1361321370.290 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2013-02-20T00:49:30.290Z,1361321370.290 [ElevatorServo](FAULT): Elevator failed to initialize 2013-02-20T00:49:30.290Z,1361321370.290 [ElevatorServo] Communications Fault, FailCount= 1 2013-02-20T00:49:30.290Z,1361321370.290 [ElevatorServo](ERROR): Communications Fault 2013-02-20T00:49:30.874Z,1361321370.874 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2013-02-20T00:49:30.897Z,1361321370.897 [ThrusterServo](FAULT): Thruster failed to initialize 2013-02-20T00:49:30.898Z,1361321370.898 [ThrusterServo] Communications Fault, FailCount= 1 2013-02-20T00:49:30.898Z,1361321370.898 [ThrusterServo](ERROR): Communications Fault 2013-02-20T00:49:30.912Z,1361321370.912 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2013-02-20T00:49:30.914Z,1361321370.914 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2013-02-20T00:49:30.916Z,1361321370.916 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2013-02-20T00:49:31.206Z,1361321371.205 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2013-02-20T00:49:31.207Z,1361321371.207 [BuoyancyServo](INFO): Powering down 2013-02-20T00:49:31.272Z,1361321371.272 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2013-02-20T00:49:31.272Z,1361321371.272 [ElevatorServo](INFO): Powering down 2013-02-20T00:49:31.294Z,1361321371.294 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-02-20T00:49:31.294Z,1361321371.294 [ThrusterServo](INFO): Powering down 2013-02-20T00:49:31.818Z,1361321371.818 [MassServo](FAULT): Mass initialization uart error:serial timeout 2013-02-20T00:49:31.818Z,1361321371.818 [MassServo] Communications Fault, FailCount= 1 2013-02-20T00:49:31.818Z,1361321371.818 [MassServo](ERROR): Communications Fault 2013-02-20T00:49:31.821Z,1361321371.821 [CBIT](ERROR): Communications Fault in component: MassServo 2013-02-20T00:49:32.035Z,1361321372.035 [ElevatorServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:32.151Z,1361321372.151 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2013-02-20T00:49:32.152Z,1361321372.152 [MassServo](DEBUG): Uninitialize Mass Servo. 2013-02-20T00:49:32.152Z,1361321372.152 [MassServo](INFO): Powering down 2013-02-20T00:49:32.678Z,1361321372.678 [RudderServo](FAULT): Rudder initialization uart error:serial timeout 2013-02-20T00:49:32.678Z,1361321372.678 [RudderServo] Communications Fault, FailCount= 1 2013-02-20T00:49:32.678Z,1361321372.678 [RudderServo](ERROR): Communications Fault 2013-02-20T00:49:32.797Z,1361321372.797 [CBIT](INFO): Clearing failed state for component ElevatorServo 2013-02-20T00:49:32.797Z,1361321372.797 [ElevatorServo] No Fault, FailCount= 1 2013-02-20T00:49:32.797Z,1361321372.797 [CBIT](ERROR): Communications Fault in component: RudderServo 2013-02-20T00:49:32.797Z,1361321372.797 [CBIT](INFO): Clearing failed state for component ThrusterServo 2013-02-20T00:49:32.797Z,1361321372.797 [ThrusterServo] No Fault, FailCount= 1 2013-02-20T00:49:33.534Z,1361321373.534 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2013-02-20T00:49:33.534Z,1361321373.534 [ElevatorServo](FAULT): Elevator failed to initialize 2013-02-20T00:49:33.534Z,1361321373.534 [ElevatorServo] Communications Fault, FailCount= 2 2013-02-20T00:49:33.534Z,1361321373.534 [ElevatorServo](ERROR): Communications Fault 2013-02-20T00:49:33.535Z,1361321373.535 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2013-02-20T00:49:33.535Z,1361321373.535 [RudderServo](INFO): Powering down 2013-02-20T00:49:33.576Z,1361321373.576 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:33.577Z,1361321373.577 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-02-20T00:49:33.580Z,1361321373.580 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2013-02-20T00:49:33.872Z,1361321373.872 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2013-02-20T00:49:33.872Z,1361321373.872 [ElevatorServo](INFO): Powering down 2013-02-20T00:49:33.873Z,1361321373.873 [MassServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:33.992Z,1361321373.992 [MassServo](DEBUG): Initializing MassServo. 2013-02-20T00:49:33.995Z,1361321373.995 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2013-02-20T00:49:33.996Z,1361321373.996 [BuoyancyServo] No Fault, FailCount= 1 2013-02-20T00:49:33.996Z,1361321373.996 [CBIT](INFO): Clearing failed state for component MassServo 2013-02-20T00:49:33.996Z,1361321373.996 [MassServo] No Fault, FailCount= 1 2013-02-20T00:49:34.202Z,1361321374.202 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:34.323Z,1361321374.323 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2013-02-20T00:49:34.325Z,1361321374.325 [RudderServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:34.443Z,1361321374.443 [RudderServo](DEBUG): Initializing RudderServo. 2013-02-20T00:49:34.962Z,1361321374.962 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2013-02-20T00:49:34.980Z,1361321374.980 [ThrusterServo](FAULT): Thruster failed to initialize 2013-02-20T00:49:34.980Z,1361321374.980 [ThrusterServo] Communications Fault, FailCount= 2 2013-02-20T00:49:34.981Z,1361321374.981 [ThrusterServo](ERROR): Communications Fault 2013-02-20T00:49:34.997Z,1361321374.997 [CBIT](INFO): Clearing failed state for component RudderServo 2013-02-20T00:49:34.998Z,1361321374.998 [RudderServo] No Fault, FailCount= 1 2013-02-20T00:49:34.999Z,1361321374.999 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2013-02-20T00:49:35.806Z,1361321375.806 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2013-02-20T00:49:35.806Z,1361321375.806 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2013-02-20T00:49:35.806Z,1361321375.806 [BuoyancyServo] Communications Fault, FailCount= 2 2013-02-20T00:49:35.806Z,1361321375.806 [BuoyancyServo](ERROR): Communications Fault 2013-02-20T00:49:35.807Z,1361321375.807 [ElevatorServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:35.927Z,1361321375.927 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2013-02-20T00:49:35.987Z,1361321375.987 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-02-20T00:49:35.987Z,1361321375.987 [ThrusterServo](INFO): Powering down 2013-02-20T00:49:35.991Z,1361321375.991 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2013-02-20T00:49:35.991Z,1361321375.991 [CBIT](INFO): Clearing failed state for component ElevatorServo 2013-02-20T00:49:35.991Z,1361321375.991 [ElevatorServo] No Fault, FailCount= 2 2013-02-20T00:49:36.206Z,1361321376.205 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2013-02-20T00:49:36.208Z,1361321376.208 [BuoyancyServo](INFO): Powering down 2013-02-20T00:49:37.114Z,1361321377.114 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2013-02-20T00:49:37.114Z,1361321377.114 [ElevatorServo](FAULT): Elevator failed to initialize 2013-02-20T00:49:37.114Z,1361321377.114 [ElevatorServo] Communications Fault, FailCount= 3 2013-02-20T00:49:37.114Z,1361321377.114 [ElevatorServo](ERROR): Communications Fault 2013-02-20T00:49:37.236Z,1361321377.236 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2013-02-20T00:49:37.237Z,1361321377.237 [CBIT](CRITICAL): Communications Fault in component: ElevatorServo 2013-02-20T00:49:37.237Z,1361321377.237 [CBIT](INFO): Clearing failed state for component ThrusterServo 2013-02-20T00:49:37.237Z,1361321377.237 [ThrusterServo] No Fault, FailCount= 2 2013-02-20T00:49:37.467Z,1361321377.467 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2013-02-20T00:49:37.467Z,1361321377.467 [ElevatorServo](INFO): Powering down 2013-02-20T00:49:37.726Z,1361321377.726 [MassServo](FAULT): Mass initialization uart error:serial timeout 2013-02-20T00:49:37.726Z,1361321377.726 [MassServo] Communications Fault, FailCount= 2 2013-02-20T00:49:37.726Z,1361321377.726 [MassServo](ERROR): Communications Fault 2013-02-20T00:49:38.234Z,1361321378.234 [RudderServo](FAULT): Rudder initialization uart error:serial timeout 2013-02-20T00:49:38.234Z,1361321378.234 [RudderServo] Communications Fault, FailCount= 2 2013-02-20T00:49:38.234Z,1361321378.234 [RudderServo](ERROR): Communications Fault 2013-02-20T00:49:38.235Z,1361321378.235 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:49:38.236Z,1361321378.236 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-02-20T00:49:38.239Z,1361321378.239 [CBIT](ERROR): Communications Fault in component: MassServo 2013-02-20T00:49:38.239Z,1361321378.239 [CBIT](ERROR): Communications Fault in component: RudderServo 2013-02-20T00:49:38.543Z,1361321378.543 [Aanderaa_O2](FAULT): Timed out starting 2013-02-20T00:49:38.543Z,1361321378.543 [Aanderaa_O2] Communications Fault, FailCount= 1 2013-02-20T00:49:38.543Z,1361321378.543 [Aanderaa_O2](ERROR): Communications Fault 2013-02-20T00:49:38.581Z,1361321378.581 [MassServo](DEBUG): Uninitialize Mass Servo. 2013-02-20T00:49:38.581Z,1361321378.581 [MassServo](INFO): Powering down 2013-02-20T00:49:38.582Z,1361321378.582 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2013-02-20T00:49:38.582Z,1361321378.582 [RudderServo](INFO): Powering down 2013-02-20T00:49:38.586Z,1361321378.586 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2013-02-20T00:50:08.958Z,1361321408.958 [Aanderaa_O2](INFO): Powering down 2013-02-20T00:50:08.992Z,1361321408.992 [ElevatorServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:09.107Z,1361321409.107 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2013-02-20T00:50:09.216Z,1361321409.216 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2013-02-20T00:50:09.216Z,1361321409.216 [BuoyancyServo] No Fault, FailCount= 2 2013-02-20T00:50:09.972Z,1361321409.972 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:10.099Z,1361321410.099 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2013-02-20T00:50:10.100Z,1361321410.100 [MassServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:10.219Z,1361321410.219 [MassServo](DEBUG): Initializing MassServo. 2013-02-20T00:50:10.220Z,1361321410.220 [RudderServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:10.339Z,1361321410.339 [RudderServo](DEBUG): Initializing RudderServo. 2013-02-20T00:50:10.341Z,1361321410.341 [SBIT](IMPORTANT): Beginning Startup BIT 2013-02-20T00:50:10.344Z,1361321410.344 [CBIT](INFO): Clearing failed state for component MassServo 2013-02-20T00:50:10.344Z,1361321410.344 [MassServo] No Fault, FailCount= 2 2013-02-20T00:50:10.345Z,1361321410.345 [CBIT](INFO): Clearing failed state for component RudderServo 2013-02-20T00:50:10.345Z,1361321410.345 [RudderServo] No Fault, FailCount= 2 2013-02-20T00:50:10.345Z,1361321410.345 [CBIT](IMPORTANT): Beginning GF scan 2013-02-20T00:50:10.609Z,1361321410.609 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2013-02-20T00:50:10.609Z,1361321410.609 [Aanderaa_O2] No Fault, FailCount= 1 2013-02-20T00:50:11.878Z,1361321411.878 [ThrusterServo](ERROR): Thruster uart error: serial timeout 2013-02-20T00:50:11.878Z,1361321411.878 [ThrusterServo] Communications Fault, FailCount= 3 2013-02-20T00:50:11.878Z,1361321411.878 [ThrusterServo](ERROR): Communications Fault 2013-02-20T00:50:11.881Z,1361321411.881 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2013-02-20T00:50:12.088Z,1361321412.088 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-02-20T00:50:12.088Z,1361321412.088 [ThrusterServo](INFO): Powering down 2013-02-20T00:50:13.002Z,1361321413.002 [BuoyancyServo](FAULT): Buoyancy initialization uart error:serial timeout 2013-02-20T00:50:13.002Z,1361321413.002 [BuoyancyServo] Communications Fault, FailCount= 3 2013-02-20T00:50:13.002Z,1361321413.002 [BuoyancyServo](ERROR): Communications Fault 2013-02-20T00:50:13.262Z,1361321413.262 [MassServo](FAULT): Mass initialization uart error:serial timeout 2013-02-20T00:50:13.262Z,1361321413.262 [MassServo] Communications Fault, FailCount= 3 2013-02-20T00:50:13.262Z,1361321413.262 [MassServo](ERROR): Communications Fault 2013-02-20T00:50:13.266Z,1361321413.266 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2013-02-20T00:50:13.266Z,1361321413.266 [CBIT](ERROR): Communications Fault in component: MassServo 2013-02-20T00:50:13.475Z,1361321413.474 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2013-02-20T00:50:13.477Z,1361321413.477 [BuoyancyServo](INFO): Powering down 2013-02-20T00:50:13.494Z,1361321413.494 [MassServo](DEBUG): Uninitialize Mass Servo. 2013-02-20T00:50:13.495Z,1361321413.494 [MassServo](INFO): Powering down 2013-02-20T00:50:14.006Z,1361321414.006 [RudderServo](FAULT): Rudder initialization uart error:serial timeout 2013-02-20T00:50:14.006Z,1361321414.006 [RudderServo] Communications Fault, FailCount= 3 2013-02-20T00:50:14.006Z,1361321414.006 [RudderServo](ERROR): Communications Fault 2013-02-20T00:50:14.125Z,1361321414.125 [CBIT](ERROR): Communications Fault in component: RudderServo 2013-02-20T00:50:14.125Z,1361321414.125 [CBIT](CRITICAL): Communications Fault in component: RudderServo 2013-02-20T00:50:14.125Z,1361321414.125 [CBIT](INFO): Clearing failed state for component ThrusterServo 2013-02-20T00:50:14.125Z,1361321414.125 [ThrusterServo] No Fault, FailCount= 3 2013-02-20T00:50:14.376Z,1361321414.376 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2013-02-20T00:50:14.376Z,1361321414.376 [RudderServo](INFO): Powering down 2013-02-20T00:50:14.377Z,1361321414.377 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:14.378Z,1361321414.378 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-02-20T00:50:14.735Z,1361321414.735 [MassServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:14.859Z,1361321414.859 [MassServo](DEBUG): Initializing MassServo. 2013-02-20T00:50:14.863Z,1361321414.863 [CBIT](INFO): Clearing failed state for component MassServo 2013-02-20T00:50:14.863Z,1361321414.863 [MassServo] No Fault, FailCount= 3 2013-02-20T00:50:15.140Z,1361321415.140 [RudderServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:15.255Z,1361321415.255 [RudderServo](DEBUG): Initializing RudderServo. 2013-02-20T00:50:15.774Z,1361321415.774 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2013-02-20T00:50:15.791Z,1361321415.792 [ThrusterServo](FAULT): Thruster failed to initialize 2013-02-20T00:50:15.792Z,1361321415.792 [ThrusterServo] Communications Fault, FailCount= 4 2013-02-20T00:50:15.793Z,1361321415.793 [ThrusterServo](ERROR): Communications Fault 2013-02-20T00:50:15.811Z,1361321415.811 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2013-02-20T00:50:16.093Z,1361321416.093 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-02-20T00:50:16.094Z,1361321416.094 [ThrusterServo](INFO): Powering down 2013-02-20T00:50:16.115Z,1361321416.115 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2013-02-20T00:50:16.115Z,1361321416.115 [BuoyancyServo] No Fault, FailCount= 3 2013-02-20T00:50:16.431Z,1361321416.431 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:16.548Z,1361321416.548 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2013-02-20T00:50:16.941Z,1361321416.941 [CBIT](INFO): Clearing failed state for component ThrusterServo 2013-02-20T00:50:16.941Z,1361321416.941 [ThrusterServo] No Fault, FailCount= 4 2013-02-20T00:50:17.770Z,1361321417.770 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2013-02-20T00:50:17.770Z,1361321417.770 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2013-02-20T00:50:17.770Z,1361321417.770 [BuoyancyServo] Communications Fault, FailCount= 4 2013-02-20T00:50:17.770Z,1361321417.770 [BuoyancyServo](ERROR): Communications Fault 2013-02-20T00:50:17.772Z,1361321417.772 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:17.772Z,1361321417.773 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-02-20T00:50:17.776Z,1361321417.776 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2013-02-20T00:50:18.023Z,1361321418.023 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2013-02-20T00:50:18.025Z,1361321418.025 [BuoyancyServo](INFO): Powering down 2013-02-20T00:50:18.318Z,1361321418.318 [MassServo](FAULT): Mass initialization uart error:serial timeout 2013-02-20T00:50:18.318Z,1361321418.318 [MassServo] Communications Fault, FailCount= 4 2013-02-20T00:50:18.318Z,1361321418.318 [MassServo](ERROR): Communications Fault 2013-02-20T00:50:18.834Z,1361321418.834 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2013-02-20T00:50:19.354Z,1361321419.354 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2013-02-20T00:50:19.354Z,1361321419.354 [ThrusterServo](FAULT): Thruster failed to initialize 2013-02-20T00:50:19.354Z,1361321419.354 [ThrusterServo] Communications Fault, FailCount= 5 2013-02-20T00:50:19.354Z,1361321419.354 [ThrusterServo](ERROR): Communications Fault 2013-02-20T00:50:19.357Z,1361321419.357 [CBIT](ERROR): Communications Fault in component: MassServo 2013-02-20T00:50:19.358Z,1361321419.358 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2013-02-20T00:50:19.358Z,1361321419.358 [CBIT](CRITICAL): Communications Fault in component: ThrusterServo 2013-02-20T00:50:19.588Z,1361321419.588 [MassServo](DEBUG): Uninitialize Mass Servo. 2013-02-20T00:50:19.588Z,1361321419.588 [MassServo](INFO): Powering down 2013-02-20T00:50:19.589Z,1361321419.589 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-02-20T00:50:19.589Z,1361321419.589 [ThrusterServo](INFO): Powering down 2013-02-20T00:50:19.977Z,1361321419.977 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2013-02-20T00:50:19.977Z,1361321419.977 [BuoyancyServo] No Fault, FailCount= 4 2013-02-20T00:50:20.369Z,1361321420.369 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:20.491Z,1361321420.491 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2013-02-20T00:50:20.492Z,1361321420.492 [MassServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:20.611Z,1361321420.611 [MassServo](DEBUG): Initializing MassServo. 2013-02-20T00:50:20.733Z,1361321420.733 [CBIT](INFO): Clearing failed state for component MassServo 2013-02-20T00:50:20.733Z,1361321420.733 [MassServo] No Fault, FailCount= 4 2013-02-20T00:50:21.022Z,1361321421.022 [Aanderaa_O2](FAULT): Timed out starting 2013-02-20T00:50:21.023Z,1361321421.023 [Aanderaa_O2] Communications Fault, FailCount= 2 2013-02-20T00:50:21.023Z,1361321421.023 [Aanderaa_O2](ERROR): Communications Fault 2013-02-20T00:50:21.570Z,1361321421.570 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2013-02-20T00:50:21.570Z,1361321421.570 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2013-02-20T00:50:21.570Z,1361321421.570 [BuoyancyServo] Communications Fault, FailCount= 5 2013-02-20T00:50:21.570Z,1361321421.570 [BuoyancyServo](ERROR): Communications Fault 2013-02-20T00:50:21.594Z,1361321421.594 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2013-02-20T00:50:21.596Z,1361321421.596 [CBIT](CRITICAL): Communications Fault in component: BuoyancyServo 2013-02-20T00:50:21.600Z,1361321421.600 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2013-02-20T00:50:51.926Z,1361321451.926 [Aanderaa_O2](INFO): Powering down 2013-02-20T00:50:51.959Z,1361321451.959 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2013-02-20T00:50:51.960Z,1361321451.960 [BuoyancyServo](INFO): Powering down 2013-02-20T00:50:51.979Z,1361321451.979 [CBIT](INFO): Clearing failed count for component ThrusterServo 2013-02-20T00:50:51.979Z,1361321451.979 [ThrusterServo] No Fault, FailCount= 5 2013-02-20T00:50:52.807Z,1361321452.807 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:52.808Z,1361321452.808 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-02-20T00:50:53.021Z,1361321453.021 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2013-02-20T00:50:53.021Z,1361321453.021 [Aanderaa_O2] No Fault, FailCount= 2 2013-02-20T00:50:53.966Z,1361321453.966 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2013-02-20T00:50:54.030Z,1361321454.030 [ThrusterServo](FAULT): Thruster failed to initialize 2013-02-20T00:50:54.030Z,1361321454.030 [ThrusterServo] Communications Fault, FailCount= 1 2013-02-20T00:50:54.030Z,1361321454.030 [ThrusterServo](ERROR): Communications Fault 2013-02-20T00:50:54.033Z,1361321454.033 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2013-02-20T00:50:54.794Z,1361321454.794 [MassServo](ERROR): isCommunicating uart error serial timeout 2013-02-20T00:50:54.794Z,1361321454.794 [MassServo](FAULT): Invalid EZ Servo response:"" 2013-02-20T00:50:54.794Z,1361321454.794 [MassServo] Communications Fault, FailCount= 5 2013-02-20T00:50:54.794Z,1361321454.795 [MassServo](ERROR): Communications Fault 2013-02-20T00:50:54.795Z,1361321454.795 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2013-02-20T00:50:54.795Z,1361321454.795 [MassServo] Hardware Fault, FailCount= 5 2013-02-20T00:50:54.795Z,1361321454.795 [MassServo](ERROR): Hardware Fault 2013-02-20T00:50:55.818Z,1361321455.818 [MassServo](ERROR): getPosition uart error serial timeout 2013-02-20T00:50:55.818Z,1361321455.818 [MassServo](FAULT): Invalid EZ Servo response:"" 2013-02-20T00:50:55.818Z,1361321455.818 [MassServo] Communications Fault, FailCount= 5 2013-02-20T00:50:55.818Z,1361321455.818 [MassServo](ERROR): Communications Fault 2013-02-20T00:50:55.819Z,1361321455.819 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-02-20T00:50:55.819Z,1361321455.819 [ThrusterServo](INFO): Powering down 2013-02-20T00:50:55.823Z,1361321455.823 [CBIT](ERROR): Communications Fault in component: MassServo 2013-02-20T00:50:55.823Z,1361321455.823 [CBIT](CRITICAL): Communications Fault in component: MassServo 2013-02-20T00:50:56.068Z,1361321456.068 [MassServo](DEBUG): Uninitialize Mass Servo. 2013-02-20T00:50:56.068Z,1361321456.068 [MassServo](INFO): Powering down 2013-02-20T00:50:56.551Z,1361321456.551 [CBIT](INFO): Clearing failed state for component ThrusterServo 2013-02-20T00:50:56.551Z,1361321456.551 [ThrusterServo] No Fault, FailCount= 1 2013-02-20T00:50:56.927Z,1361321456.927 [MassServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:57.044Z,1361321457.044 [MassServo](DEBUG): Initializing MassServo. 2013-02-20T00:50:57.045Z,1361321457.045 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:50:57.050Z,1361321457.050 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-02-20T00:50:58.214Z,1361321458.214 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2013-02-20T00:50:58.734Z,1361321458.734 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2013-02-20T00:50:58.734Z,1361321458.734 [ThrusterServo](FAULT): Thruster failed to initialize 2013-02-20T00:50:58.734Z,1361321458.734 [ThrusterServo] Communications Fault, FailCount= 2 2013-02-20T00:50:58.734Z,1361321458.734 [ThrusterServo](ERROR): Communications Fault 2013-02-20T00:50:58.737Z,1361321458.737 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2013-02-20T00:50:58.970Z,1361321458.970 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-02-20T00:50:58.971Z,1361321458.971 [ThrusterServo](INFO): Powering down 2013-02-20T00:50:59.861Z,1361321459.861 [CBIT](INFO): Clearing failed state for component ThrusterServo 2013-02-20T00:50:59.861Z,1361321459.861 [ThrusterServo] No Fault, FailCount= 2 2013-02-20T00:51:00.193Z,1361321460.193 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:51:00.194Z,1361321460.194 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-02-20T00:51:01.466Z,1361321461.466 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2013-02-20T00:51:01.483Z,1361321461.483 [ThrusterServo](FAULT): Thruster failed to initialize 2013-02-20T00:51:01.484Z,1361321461.484 [ThrusterServo] Communications Fault, FailCount= 3 2013-02-20T00:51:01.484Z,1361321461.484 [ThrusterServo](ERROR): Communications Fault 2013-02-20T00:51:01.504Z,1361321461.504 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2013-02-20T00:51:01.734Z,1361321461.734 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-02-20T00:51:01.735Z,1361321461.735 [ThrusterServo](INFO): Powering down 2013-02-20T00:51:02.641Z,1361321462.641 [CBIT](INFO): Clearing failed state for component ThrusterServo 2013-02-20T00:51:02.641Z,1361321462.641 [ThrusterServo] No Fault, FailCount= 3 2013-02-20T00:51:02.644Z,1361321462.644 [CBIT](FAULT): Low side GF detected mA: CHAN 5 (24V): 0.391695 CHAN 4 (Batt): 0.391695 CHAN 2 (12V): 0.391695 CHAN 1 (5V): 0.391695 CHAN 0 (3.3V): 0.338991 OPEN: 0.391695 Full Scale Calc: 0.392 2013-02-20T00:51:02.923Z,1361321462.923 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-02-20T00:51:02.924Z,1361321462.924 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-02-20T00:51:03.734Z,1361321463.734 [Aanderaa_O2](FAULT): Timed out starting 2013-02-20T00:51:03.734Z,1361321463.734 [Aanderaa_O2] Communications Fault, FailCount= 3 2013-02-20T00:51:03.734Z,1361321463.734 [Aanderaa_O2](ERROR): Communications Fault 2013-02-20T00:51:04.286Z,1361321464.286 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2013-02-20T00:51:04.303Z,1361321464.303 [ThrusterServo](FAULT): Thruster failed to initialize 2013-02-20T00:51:04.304Z,1361321464.304 [ThrusterServo] Communications Fault, FailCount= 4 2013-02-20T00:51:04.305Z,1361321464.305 [ThrusterServo](ERROR): Communications Fault 2013-02-20T00:51:04.325Z,1361321464.325 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2013-02-20T00:51:04.327Z,1361321464.328 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2013-02-20T00:51:04.330Z,1361321464.330 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2013-02-20T00:51:25.594Z,1361321485.594 [CommandLine](IMPORTANT): got command failComponent 2013-02-20T00:51:25.595Z,1361321485.595 [CommandLine](IMPORTANT): Failed components: 2013-02-20T00:51:25.595Z,1361321485.595 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault 2013-02-20T00:51:25.595Z,1361321485.595 [CommandLine](IMPORTANT): ElevatorServo: Communications Fault 2013-02-20T00:51:25.595Z,1361321485.595 [CommandLine](IMPORTANT): MassServo: Communications Fault 2013-02-20T00:51:25.595Z,1361321485.595 [CommandLine](IMPORTANT): RudderServo: Communications Fault 2013-02-20T00:51:25.596Z,1361321485.596 [CommandLine](IMPORTANT): ThrusterServo: Communications Fault 2013-02-20T00:51:25.596Z,1361321485.596 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault 2013-02-20T00:51:27.854Z,1361321487.854 [CommandLine](IMPORTANT): got command restart application 2013-02-20T00:51:28.942Z,1361321488.942 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2013-02-20T00:51:28.942Z,1361321488.942 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2013-02-20T00:51:28.944Z,1361321488.944 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2013-02-20T00:51:29.166Z,1361321489.166 [WetLabsBB2FL](INFO): Powering down 2013-02-20T00:51:29.182Z,1361321489.182 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2013-02-20T00:51:29.482Z,1361321489.482 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler 2013-02-20T00:51:29.534Z,1361321489.534 [DVL_micro](INFO): uninitialize:Powering down 2013-02-20T00:51:29.542Z,1361321489.542 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler 2013-02-20T00:51:29.846Z,1361321489.846 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator. 2013-02-20T00:51:29.863Z,1361321489.863 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2013-02-20T00:51:29.963Z,1361321489.963 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2013-02-20T00:51:29.964Z,1361321489.964 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2013-02-20T00:51:34.674Z,1361321494.674 [Aanderaa_O2](INFO): Powering down 2013-02-20T00:51:34.694Z,1361321494.694 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-02-20T00:51:34.694Z,1361321494.694 [ThrusterServo](INFO): Powering down 2013-02-20T00:51:34.696Z,1361321494.696 [SBIT](FAULT): Mass: EXPECTED:-0.006000 ACTUAL:-0.004643 2013-02-20T00:51:34.696Z,1361321494.696 [SBIT](FAULT): Control surface position failure. 2013-02-20T00:51:34.700Z,1361321494.700 [controlThread](DEBUG): Uninitializing ControlThread 2013-02-20T00:51:34.700Z,1361321494.700 [AHRS_sp3003D](INFO): Powering down 2013-02-20T00:51:34.787Z,1361321494.787 [NAL9602](INFO): Powering down 2013-02-20T00:51:34.788Z,1361321494.788 [Aanderaa_O2](INFO): Powering down 2013-02-20T00:51:34.790Z,1361321494.790 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2013-02-20T00:51:34.791Z,1361321494.791 [NavChart](DEBUG): Uninitialize NavChart Derivation. 2013-02-20T00:51:34.796Z,1361321494.796 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2013-02-20T00:51:34.796Z,1361321494.796 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2013-02-20T00:51:34.796Z,1361321494.796 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2013-02-20T00:51:34.797Z,1361321494.797 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2013-02-20T00:51:34.797Z,1361321494.797 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2013-02-20T00:51:34.798Z,1361321494.798 [BuoyancyServo](INFO): Powering down 2013-02-20T00:51:34.814Z,1361321494.814 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2013-02-20T00:51:34.814Z,1361321494.814 [ElevatorServo](INFO): Powering down 2013-02-20T00:51:34.815Z,1361321494.815 [MassServo](DEBUG): Uninitialize Mass Servo. 2013-02-20T00:51:34.815Z,1361321494.815 [MassServo](INFO): Powering down 2013-02-20T00:51:34.816Z,1361321494.816 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2013-02-20T00:51:34.816Z,1361321494.816 [RudderServo](INFO): Powering down 2013-02-20T00:51:34.817Z,1361321494.817 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-02-20T00:51:34.817Z,1361321494.817 [ThrusterServo](INFO): Powering down 2013-02-20T00:51:34.818Z,1361321494.818 [SBIT](DEBUG): Uninitialize SBIT Component. 2013-02-20T00:51:34.818Z,1361321494.818 [IBIT](DEBUG): Uninitialize IBIT Component. 2013-02-20T00:51:34.818Z,1361321494.819 [CBIT](DEBUG): Uninitialize CBIT Component.