2012-11-26T19:36:04.662Z,1353958564.662 [Supervisor](DEBUG): Initializing supervisor. 2012-11-26T19:36:04.670Z,1353958564.670 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2012-11-26T19:36:04.671Z,1353958564.671 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2012-11-26T19:36:04.675Z,1353958564.675 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2012-11-26T19:36:04.678Z,1353958564.678 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2012-11-26T19:36:04.689Z,1353958564.689 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2012-11-26T19:36:04.691Z,1353958564.691 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2012-11-26T19:36:04.692Z,1353958564.692 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2012-11-26T19:36:04.695Z,1353958564.695 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0 2012-11-26T19:36:04.695Z,1353958564.695 [Supervisor](INFO): Looking for Config files in directory: Config/ 2012-11-26T19:36:04.698Z,1353958564.698 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2012-11-26T19:36:05.007Z,1353958565.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2012-11-26T19:36:05.010Z,1353958565.010 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2012-11-26T19:36:05.219Z,1353958565.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2012-11-26T19:36:05.220Z,1353958565.220 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2012-11-26T19:36:05.315Z,1353958565.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2012-11-26T19:36:05.317Z,1353958565.317 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2012-11-26T19:36:05.535Z,1353958565.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2012-11-26T19:36:05.536Z,1353958565.536 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2012-11-26T19:36:05.683Z,1353958565.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2012-11-26T19:36:05.685Z,1353958565.685 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2012-11-26T19:36:05.936Z,1353958565.936 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2012-11-26T19:36:05.936Z,1353958565.936 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2012-11-26T19:36:06.123Z,1353958566.123 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2012-11-26T19:36:06.125Z,1353958566.125 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2012-11-26T19:36:06.402Z,1353958566.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2012-11-26T19:36:06.404Z,1353958566.404 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2012-11-26T19:36:06.514Z,1353958566.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2012-11-26T19:36:06.515Z,1353958566.515 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2012-11-26T19:36:06.958Z,1353958566.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2012-11-26T19:36:06.959Z,1353958566.959 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2012-11-26T19:36:07.087Z,1353958567.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2012-11-26T19:36:07.088Z,1353958567.088 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2012-11-26T19:36:07.184Z,1353958567.184 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2012-11-26T19:36:07.188Z,1353958567.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2012-11-26T19:36:07.298Z,1353958567.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2012-11-26T19:36:07.444Z,1353958567.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2012-11-26T19:36:07.538Z,1353958567.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2012-11-26T19:36:07.652Z,1353958567.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2012-11-26T19:36:07.762Z,1353958567.763 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2012-11-26T19:36:07.896Z,1353958567.896 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2012-11-26T19:36:08.001Z,1353958568.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2012-11-26T19:36:08.101Z,1353958568.101 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2012-11-26T19:36:08.198Z,1353958568.198 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2012-11-26T19:36:08.407Z,1353958568.407 [InternalSim] Loaded 2012-11-26T19:36:08.407Z,1353958568.407 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2012-11-26T19:36:08.407Z,1353958568.408 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2012-11-26T19:36:08.408Z,1353958568.408 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2012-11-26T19:36:08.490Z,1353958568.490 [SBIT](DEBUG): Construct Startup Built In Test. 2012-11-26T19:36:08.522Z,1353958568.522 [SBIT] Loaded 2012-11-26T19:36:08.523Z,1353958568.523 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2012-11-26T19:36:08.524Z,1353958568.523 [IBIT](DEBUG): Construct Initiated Built In Test. 2012-11-26T19:36:08.556Z,1353958568.556 [IBIT] Loaded 2012-11-26T19:36:08.556Z,1353958568.556 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2012-11-26T19:36:08.560Z,1353958568.560 [CBIT](DEBUG): Construct CBIT Built In Test. 2012-11-26T19:36:08.693Z,1353958568.693 [CBIT] Loaded 2012-11-26T19:36:08.693Z,1353958568.694 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2012-11-26T19:36:08.694Z,1353958568.694 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2012-11-26T19:36:08.695Z,1353958568.695 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2012-11-26T19:36:08.871Z,1353958568.871 [BuoyancyServo] Loaded 2012-11-26T19:36:08.872Z,1353958568.872 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2012-11-26T19:36:08.884Z,1353958568.884 [ElevatorServo] Loaded 2012-11-26T19:36:08.884Z,1353958568.884 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2012-11-26T19:36:08.900Z,1353958568.900 [MassServo] Loaded 2012-11-26T19:36:08.900Z,1353958568.900 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2012-11-26T19:36:08.912Z,1353958568.912 [RudderServo] Loaded 2012-11-26T19:36:08.913Z,1353958568.913 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2012-11-26T19:36:08.924Z,1353958568.924 [ThrusterServo] Loaded 2012-11-26T19:36:08.925Z,1353958568.925 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2012-11-26T19:36:08.925Z,1353958568.925 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2012-11-26T19:36:08.926Z,1353958568.926 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2012-11-26T19:36:08.962Z,1353958568.962 [DepthRateCalculator] Loaded 2012-11-26T19:36:08.963Z,1353958568.963 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2012-11-26T19:36:12.406Z,1353958572.406 [HFRadarModelCalc] Loaded 2012-11-26T19:36:12.406Z,1353958572.406 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2012-11-26T19:36:12.422Z,1353958572.422 [NavChart] Loaded 2012-11-26T19:36:12.422Z,1353958572.422 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2012-11-26T19:36:12.428Z,1353958572.428 [PitchRateCalculator] Loaded 2012-11-26T19:36:12.429Z,1353958572.429 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2012-11-26T19:36:12.439Z,1353958572.439 [SpeedCalculator] Loaded 2012-11-26T19:36:12.439Z,1353958572.439 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2012-11-26T19:36:12.454Z,1353958572.454 [TempGradientCalculator] Loaded 2012-11-26T19:36:12.454Z,1353958572.454 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2012-11-26T19:36:12.460Z,1353958572.460 [YawRateCalculator] Loaded 2012-11-26T19:36:12.461Z,1353958572.461 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2012-11-26T19:36:12.505Z,1353958572.505 [Navigation] Loaded 2012-11-26T19:36:12.506Z,1353958572.506 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2012-11-26T19:36:12.506Z,1353958572.506 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2012-11-26T19:36:12.507Z,1353958572.507 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2012-11-26T19:36:12.790Z,1353958572.790 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2012-11-26T19:36:12.791Z,1353958572.791 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2012-11-26T19:36:12.832Z,1353958572.832 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2012-11-26T19:36:12.833Z,1353958572.833 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2012-11-26T19:36:12.898Z,1353958572.898 [VerticalControl](DEBUG): Construct VerticalControl. 2012-11-26T19:36:13.000Z,1353958573.000 [VerticalControl] Loaded 2012-11-26T19:36:13.001Z,1353958573.001 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2012-11-26T19:36:13.002Z,1353958573.002 [HorizontalControl](DEBUG): Construct HorizontalControl. 2012-11-26T19:36:13.066Z,1353958573.066 [HorizontalControl] Loaded 2012-11-26T19:36:13.067Z,1353958573.066 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2012-11-26T19:36:13.068Z,1353958573.068 [SpeedControl](DEBUG): Construct SpeedControl. 2012-11-26T19:36:13.069Z,1353958573.069 [SpeedControl] Loaded 2012-11-26T19:36:13.069Z,1353958573.069 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2012-11-26T19:36:13.070Z,1353958573.070 [LoopControl](DEBUG): Construct LoopControl. 2012-11-26T19:36:13.071Z,1353958573.071 [LoopControl] Loaded 2012-11-26T19:36:13.071Z,1353958573.071 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2012-11-26T19:36:13.072Z,1353958573.072 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2012-11-26T19:36:13.072Z,1353958573.072 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2012-11-26T19:36:13.080Z,1353958573.080 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2012-11-26T19:36:13.089Z,1353958573.089 [AsyncPiEstimator] Loaded 2012-11-26T19:36:13.090Z,1353958573.090 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2012-11-26T19:36:13.091Z,1353958573.091 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406A14E0 2012-11-26T19:36:13.093Z,1353958573.093 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2012-11-26T19:36:13.094Z,1353958573.094 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2012-11-26T19:36:13.283Z,1353958573.283 [AHRS_sp3003D] Loaded 2012-11-26T19:36:13.283Z,1353958573.283 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2012-11-26T19:36:13.577Z,1353958573.577 [Batt_Ocean_Server] Loaded 2012-11-26T19:36:13.577Z,1353958573.577 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2012-11-26T19:36:13.591Z,1353958573.591 [Depth_Keller] Loaded 2012-11-26T19:36:13.591Z,1353958573.592 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2012-11-26T19:36:13.597Z,1353958573.597 [DropWeight] Loaded 2012-11-26T19:36:13.598Z,1353958573.598 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2012-11-26T19:36:13.782Z,1353958573.782 [DVL_micro] Loaded 2012-11-26T19:36:13.782Z,1353958573.782 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2012-11-26T19:36:13.787Z,1353958573.787 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 407234E0 2012-11-26T19:36:13.878Z,1353958573.878 [NAL9602] Loaded 2012-11-26T19:36:13.879Z,1353958573.879 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2012-11-26T19:36:13.933Z,1353958573.933 [Onboard] Loaded 2012-11-26T19:36:13.934Z,1353958573.934 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2012-11-26T19:36:13.945Z,1353958573.944 [Radio_Freewave] Loaded 2012-11-26T19:36:13.945Z,1353958573.945 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2012-11-26T19:36:13.946Z,1353958573.946 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2012-11-26T19:36:13.946Z,1353958573.946 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2012-11-26T19:36:14.053Z,1353958574.053 [CTD_NeilBrown] Loaded 2012-11-26T19:36:14.054Z,1353958574.053 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2012-11-26T19:36:14.059Z,1353958574.059 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4077C4E0 2012-11-26T19:36:14.075Z,1353958574.075 [PAR_Licor] Loaded 2012-11-26T19:36:14.075Z,1353958574.075 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2012-11-26T19:36:14.136Z,1353958574.136 [Turbulence_NPS] Loaded 2012-11-26T19:36:14.137Z,1353958574.137 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread. 2012-11-26T19:36:14.139Z,1353958574.139 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 407AC4E0 2012-11-26T19:36:14.173Z,1353958574.173 [WetLabsBB2FL] Loaded 2012-11-26T19:36:14.173Z,1353958574.173 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2012-11-26T19:36:14.175Z,1353958574.175 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407DC4E0 2012-11-26T19:36:14.175Z,1353958574.175 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2012-11-26T19:36:14.178Z,1353958574.178 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2012-11-26T19:36:14.179Z,1353958574.179 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2012-11-26T19:36:14.189Z,1353958574.189 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2012-11-26T19:36:14.191Z,1353958574.191 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 4080C4E0 2012-11-26T19:36:14.195Z,1353958574.195 [Supervisor](DEBUG): Running supervisor. 2012-11-26T19:36:14.196Z,1353958574.196 [CommandLine](INFO): Thread ID is 749 2012-11-26T19:36:14.199Z,1353958574.199 [controlThread](INFO): Thread ID is 748 2012-11-26T19:36:14.199Z,1353958574.200 [controlThread](DEBUG): Initializing ControlThread 2012-11-26T19:36:14.200Z,1353958574.200 [CycleStarter](INFO): Thread ID is 747 2012-11-26T19:36:14.200Z,1353958574.200 [InternalSim](DEBUG): InternalSim initializing... 2012-11-26T19:36:14.307Z,1353958574.307 [logger](INFO): Thread ID is 750 2012-11-26T19:36:14.448Z,1353958574.448 [AsyncPiEstimator](INFO): Thread ID is 811 2012-11-26T19:36:14.448Z,1353958574.448 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2012-11-26T19:36:14.543Z,1353958574.543 [DVL_micro](INFO): Thread ID is 812 2012-11-26T19:36:14.562Z,1353958574.562 [DVL_micro](INFO): Initializing 2012-11-26T19:36:14.562Z,1353958574.562 [DVL_micro](INFO): start:Powering up 2012-11-26T19:36:14.563Z,1353958574.563 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-11-26T19:36:14.564Z,1353958574.564 [DVL_micro](INFO): Cycling power to configure device. 2012-11-26T19:36:14.575Z,1353958574.575 [CTD_NeilBrown](INFO): Thread ID is 813 2012-11-26T19:36:14.576Z,1353958574.576 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2012-11-26T19:36:14.591Z,1353958574.591 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-11-26T19:36:14.636Z,1353958574.636 [Turbulence_NPS](INFO): Thread ID is 814 2012-11-26T19:36:14.637Z,1353958574.637 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS. 2012-11-26T19:36:14.637Z,1353958574.637 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-11-26T19:36:14.679Z,1353958574.679 [WetLabsBB2FL](INFO): Thread ID is 815 2012-11-26T19:36:14.680Z,1353958574.680 [WetLabsBB2FL](INFO): Powering down 2012-11-26T19:36:14.705Z,1353958574.705 [SBIT](INFO): Initialize SBIT Component. 2012-11-26T19:36:14.706Z,1353958574.706 [SBIT](IMPORTANT): Tethys CM Info: $Rev: 10092 2012-11-26T19:36:14.706Z,1353958574.706 [IBIT](INFO): Initialize IBIT Component. 2012-11-26T19:36:14.708Z,1353958574.708 [NavChartDb](INFO): Thread ID is 816 2012-11-26T19:36:14.711Z,1353958574.711 [CBIT](DEBUG): Initialize CBIT Component. 2012-11-26T19:36:14.711Z,1353958574.711 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2012-11-26T19:36:14.712Z,1353958574.712 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2012-11-26T19:36:14.719Z,1353958574.719 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2012-11-26T19:36:14.720Z,1353958574.720 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2012-11-26T19:36:14.720Z,1353958574.720 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2012-11-26T19:36:14.720Z,1353958574.720 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2012-11-26T19:36:14.720Z,1353958574.720 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2012-11-26T19:36:14.736Z,1353958574.736 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2012-11-26T19:36:14.737Z,1353958574.737 [NavChart](DEBUG): Initialize NavChart Derivation. 2012-11-26T19:36:14.737Z,1353958574.737 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2012-11-26T19:36:14.737Z,1353958574.737 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2012-11-26T19:36:14.738Z,1353958574.738 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2012-11-26T19:36:14.756Z,1353958574.756 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2012-11-26T19:36:14.756Z,1353958574.756 [Navigation](DEBUG): Initializing Navigation. 2012-11-26T19:36:14.756Z,1353958574.756 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2012-11-26T19:36:14.758Z,1353958574.758 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2012-11-26T19:36:14.767Z,1353958574.767 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2012-11-26T19:36:14.767Z,1353958574.767 [LoopControl](DEBUG): Initialize LoopControlComponent. 2012-11-26T19:36:14.779Z,1353958574.779 [Turbulence_NPS](INFO): Pause powering down 2012-11-26T19:36:16.635Z,1353958576.635 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-11-26T19:36:16.635Z,1353958576.635 [DVL_micro](INFO): Querying output modes 2012-11-26T19:36:16.636Z,1353958576.636 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-11-26T19:36:16.647Z,1353958576.647 [DVL_micro](DEBUG): cmdResponse: 01 2012-11-26T19:36:16.647Z,1353958576.647 [DVL_micro](INFO): NQ1 output enabled 2012-11-26T19:36:16.647Z,1353958576.647 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-11-26T19:36:16.659Z,1353958576.659 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-11-26T19:36:16.664Z,1353958576.664 [DVL_micro](INFO): pause:Powering down 2012-11-26T19:36:16.668Z,1353958576.668 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2012-11-26T19:36:16.698Z,1353958576.698 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2012-11-26T19:36:16.736Z,1353958576.736 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-26T19:36:16.799Z,1353958576.799 [MissionManager](DEBUG): 2012-11-26T19:36:16.800Z,1353958576.800 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2012-11-26T19:36:16.900Z,1353958576.900 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2012-11-26T19:36:16.902Z,1353958576.902 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2012-11-26T19:36:16.921Z,1353958576.921 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-26T19:36:16.957Z,1353958576.957 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2012-11-26T19:36:16.960Z,1353958576.960 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-26T19:36:16.999Z,1353958576.999 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2012-11-26T19:36:17.028Z,1353958577.028 [Default:D.SetSpeed](DEBUG): Construct. 2012-11-26T19:36:17.063Z,1353958577.063 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-26T19:36:17.072Z,1353958577.072 [Default:F.Wait](DEBUG): Construct Wait. 2012-11-26T19:36:17.076Z,1353958577.076 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2012-11-26T19:36:17.103Z,1353958577.103 [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, 2012-11-26T19:36:17.179Z,1353958577.180 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2012-11-26T19:36:17.512Z,1353958577.511 [Radio_Freewave](INFO): Powering up 2012-11-26T19:36:17.791Z,1353958577.791 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2012-11-26T19:36:17.820Z,1353958577.820 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2012-11-26T19:36:17.856Z,1353958577.856 [ElevatorServo](DEBUG): Initializing EZServoServo. 2012-11-26T19:36:17.863Z,1353958577.863 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2012-11-26T19:36:17.885Z,1353958577.885 [MassServo](DEBUG): Initializing EZServoServo. 2012-11-26T19:36:17.891Z,1353958577.891 [MassServo](DEBUG): Initializing MassServo. 2012-11-26T19:36:17.909Z,1353958577.908 [RudderServo](DEBUG): Initializing EZServoServo. 2012-11-26T19:36:17.915Z,1353958577.915 [RudderServo](DEBUG): Initializing RudderServo. 2012-11-26T19:36:17.920Z,1353958577.921 [ThrusterServo](DEBUG): Initializing EZServoServo. 2012-11-26T19:36:17.927Z,1353958577.927 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2012-11-26T19:36:19.226Z,1353958579.226 [NAL9602](INFO): Powering up NAL9602 2012-11-26T19:36:29.747Z,1353958589.747 [NAL9602](INFO): NAL9602 initialized 2012-11-26T19:36:30.116Z,1353958590.116 [SBIT](IMPORTANT): Beginning Startup BIT 2012-11-26T19:36:30.122Z,1353958590.122 [CBIT](IMPORTANT): Beginning GF scan 2012-11-26T19:36:50.835Z,1353958610.835 [CommandLine](IMPORTANT): got command burn 5.000000 2012-11-26T19:36:56.856Z,1353958616.856 [CBIT](IMPORTANT): No ground fault detected 2012-11-26T19:37:13.560Z,1353958633.560 [CommandLine](IMPORTANT): got command get platform_battery_voltage 2012-11-26T19:37:13.561Z,1353958633.561 [CommandLine](IMPORTANT): platform_battery_voltage 16.319687 volt 2012-11-26T19:37:23.872Z,1353958643.872 [SBIT](IMPORTANT): SBIT PASSED 2012-11-26T19:37:24.264Z,1353958644.264 [MissionManager](IMPORTANT): Started mission Startup 2012-11-26T19:37:24.264Z,1353958644.264 [Startup] Running Loop=1 2012-11-26T19:37:24.264Z,1353958644.264 [Startup](INFO): Aggregate::initialize Startup 2012-11-26T19:37:24.264Z,1353958644.264 [Startup:A.GoToSurface] Running Loop=1 2012-11-26T19:37:24.264Z,1353958644.264 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-11-26T19:37:24.270Z,1353958644.270 [Startup:StartupSatComms] Running Loop=1 2012-11-26T19:37:24.270Z,1353958644.270 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2012-11-26T19:37:24.271Z,1353958644.271 [Startup:StartupSatComms:A] Running Loop=1 2012-11-26T19:37:24.706Z,1353958644.706 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-11-26T19:37:46.895Z,1353958666.895 [CommandLine](IMPORTANT): got command burn 5.000000 2012-11-26T19:38:04.167Z,1353958684.167 [CommandLine](IMPORTANT): got command @ 2012-11-26T19:38:04.167Z,1353958684.167 [CommandLine](FAULT): Incomplete syntax. Try: help @ 2012-11-26T19:38:11.147Z,1353958691.147 [CommandLine](IMPORTANT): got command @ date 2012-11-26T19:38:24.295Z,1353958704.295 [Startup:StartupSatComms:A](INFO): Timed out from 2012-11-26T19:37:24.3Z 2012-11-26T19:38:24.295Z,1353958704.295 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1 2012-11-26T19:38:24.295Z,1353958704.295 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout 2012-11-26T19:38:24.296Z,1353958704.296 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout 2012-11-26T19:38:24.296Z,1353958704.296 [Startup:StartupSatComms:A] Stopped 2012-11-26T19:38:24.296Z,1353958704.296 [Startup:StartupSatComms:B] Running Loop=1 2012-11-26T19:38:24.666Z,1353958704.666 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-11-26T19:38:31.415Z,1353958711.415 [CommandLine](IMPORTANT): got command @ date 2012-11-26T19:38:47.339Z,1353958727.339 [CommandLine](IMPORTANT): got command @ hwclock --systohc 2012-11-26T19:38:54.023Z,1353958734.023 [CommandLine](IMPORTANT): got command retransmit 2012-11-26T19:38:54.023Z,1353958734.023 [CommandLine](FAULT): Incomplete syntax. Try: help retransmit 2012-11-26T19:39:10.839Z,1353958750.839 [CommandLine](IMPORTANT): got command restart 2012-11-26T19:39:10.839Z,1353958750.839 [CommandLine](FAULT): Incomplete syntax. Try: help restart