2012-11-15T18:38:36.629Z,1353004716.629 [Supervisor](DEBUG): Initializing supervisor. 2012-11-15T18:38:36.632Z,1353004716.632 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2012-11-15T18:38:36.632Z,1353004716.632 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2012-11-15T18:38:36.637Z,1353004716.636 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2012-11-15T18:38:36.640Z,1353004716.640 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2012-11-15T18:38:36.651Z,1353004716.651 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2012-11-15T18:38:36.652Z,1353004716.652 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2012-11-15T18:38:36.653Z,1353004716.653 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2012-11-15T18:38:36.656Z,1353004716.657 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0 2012-11-15T18:38:36.657Z,1353004716.657 [Supervisor](INFO): Looking for Config files in directory: Config/ 2012-11-15T18:38:36.660Z,1353004716.660 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2012-11-15T18:38:36.975Z,1353004716.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2012-11-15T18:38:36.977Z,1353004716.977 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2012-11-15T18:38:37.184Z,1353004717.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2012-11-15T18:38:37.184Z,1353004717.184 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2012-11-15T18:38:37.280Z,1353004717.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2012-11-15T18:38:37.282Z,1353004717.282 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2012-11-15T18:38:37.505Z,1353004717.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2012-11-15T18:38:37.506Z,1353004717.506 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2012-11-15T18:38:37.656Z,1353004717.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2012-11-15T18:38:37.658Z,1353004717.658 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2012-11-15T18:38:37.921Z,1353004717.921 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2012-11-15T18:38:37.922Z,1353004717.922 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2012-11-15T18:38:38.112Z,1353004718.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2012-11-15T18:38:38.114Z,1353004718.114 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2012-11-15T18:38:38.398Z,1353004718.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2012-11-15T18:38:38.400Z,1353004718.401 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2012-11-15T18:38:38.513Z,1353004718.513 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2012-11-15T18:38:38.514Z,1353004718.514 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2012-11-15T18:38:38.962Z,1353004718.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2012-11-15T18:38:38.962Z,1353004718.963 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2012-11-15T18:38:39.091Z,1353004719.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2012-11-15T18:38:39.091Z,1353004719.091 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2012-11-15T18:38:39.186Z,1353004719.186 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2012-11-15T18:38:39.189Z,1353004719.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2012-11-15T18:38:39.304Z,1353004719.304 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2012-11-15T18:38:39.454Z,1353004719.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2012-11-15T18:38:39.552Z,1353004719.552 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2012-11-15T18:38:39.666Z,1353004719.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2012-11-15T18:38:39.776Z,1353004719.776 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2012-11-15T18:38:39.910Z,1353004719.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2012-11-15T18:38:40.016Z,1353004720.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2012-11-15T18:38:40.195Z,1353004720.195 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2012-11-15T18:38:41.287Z,1353004721.287 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2012-11-15T18:38:41.473Z,1353004721.473 [InternalSim] Loaded 2012-11-15T18:38:41.474Z,1353004721.474 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2012-11-15T18:38:41.474Z,1353004721.474 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2012-11-15T18:38:41.475Z,1353004721.475 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2012-11-15T18:38:41.552Z,1353004721.552 [SBIT](DEBUG): Construct Startup Built In Test. 2012-11-15T18:38:41.580Z,1353004721.580 [SBIT] Loaded 2012-11-15T18:38:41.580Z,1353004721.580 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2012-11-15T18:38:41.582Z,1353004721.582 [IBIT](DEBUG): Construct Initiated Built In Test. 2012-11-15T18:38:41.610Z,1353004721.610 [IBIT] Loaded 2012-11-15T18:38:41.610Z,1353004721.610 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2012-11-15T18:38:41.613Z,1353004721.613 [CBIT](DEBUG): Construct CBIT Built In Test. 2012-11-15T18:38:41.732Z,1353004721.732 [CBIT] Loaded 2012-11-15T18:38:41.732Z,1353004721.732 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2012-11-15T18:38:41.733Z,1353004721.733 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2012-11-15T18:38:41.734Z,1353004721.734 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2012-11-15T18:38:41.901Z,1353004721.901 [BuoyancyServo] Loaded 2012-11-15T18:38:41.901Z,1353004721.901 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2012-11-15T18:38:41.913Z,1353004721.913 [ElevatorServo] Loaded 2012-11-15T18:38:41.913Z,1353004721.913 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2012-11-15T18:38:41.925Z,1353004721.925 [MassServo] Loaded 2012-11-15T18:38:41.925Z,1353004721.925 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2012-11-15T18:38:41.937Z,1353004721.937 [RudderServo] Loaded 2012-11-15T18:38:41.937Z,1353004721.937 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2012-11-15T18:38:41.949Z,1353004721.949 [ThrusterServo] Loaded 2012-11-15T18:38:41.949Z,1353004721.949 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2012-11-15T18:38:41.950Z,1353004721.950 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2012-11-15T18:38:41.950Z,1353004721.950 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2012-11-15T18:38:41.983Z,1353004721.983 [DepthRateCalculator] Loaded 2012-11-15T18:38:41.983Z,1353004721.983 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2012-11-15T18:38:45.845Z,1353004725.845 [HFRadarModelCalc] Loaded 2012-11-15T18:38:45.845Z,1353004725.845 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2012-11-15T18:38:45.861Z,1353004725.861 [NavChart] Loaded 2012-11-15T18:38:45.862Z,1353004725.862 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2012-11-15T18:38:45.868Z,1353004725.868 [PitchRateCalculator] Loaded 2012-11-15T18:38:45.868Z,1353004725.868 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2012-11-15T18:38:45.874Z,1353004725.874 [SpeedCalculator] Loaded 2012-11-15T18:38:45.874Z,1353004725.874 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2012-11-15T18:38:45.889Z,1353004725.889 [TempGradientCalculator] Loaded 2012-11-15T18:38:45.890Z,1353004725.889 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2012-11-15T18:38:45.895Z,1353004725.895 [YawRateCalculator] Loaded 2012-11-15T18:38:45.896Z,1353004725.895 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2012-11-15T18:38:45.936Z,1353004725.936 [Navigation] Loaded 2012-11-15T18:38:45.936Z,1353004725.936 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2012-11-15T18:38:45.936Z,1353004725.936 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2012-11-15T18:38:45.937Z,1353004725.937 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2012-11-15T18:38:46.198Z,1353004726.198 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2012-11-15T18:38:46.198Z,1353004726.198 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2012-11-15T18:38:46.237Z,1353004726.237 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2012-11-15T18:38:46.238Z,1353004726.238 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2012-11-15T18:38:46.297Z,1353004726.297 [VerticalControl](DEBUG): Construct VerticalControl. 2012-11-15T18:38:46.390Z,1353004726.390 [VerticalControl] Loaded 2012-11-15T18:38:46.390Z,1353004726.390 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2012-11-15T18:38:46.391Z,1353004726.391 [HorizontalControl](DEBUG): Construct HorizontalControl. 2012-11-15T18:38:46.448Z,1353004726.448 [HorizontalControl] Loaded 2012-11-15T18:38:46.448Z,1353004726.448 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2012-11-15T18:38:46.449Z,1353004726.449 [SpeedControl](DEBUG): Construct SpeedControl. 2012-11-15T18:38:46.451Z,1353004726.451 [SpeedControl] Loaded 2012-11-15T18:38:46.451Z,1353004726.451 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2012-11-15T18:38:46.452Z,1353004726.452 [LoopControl](DEBUG): Construct LoopControl. 2012-11-15T18:38:46.452Z,1353004726.452 [LoopControl] Loaded 2012-11-15T18:38:46.453Z,1353004726.453 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2012-11-15T18:38:46.453Z,1353004726.453 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2012-11-15T18:38:46.454Z,1353004726.454 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2012-11-15T18:38:46.462Z,1353004726.462 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2012-11-15T18:38:46.467Z,1353004726.467 [AsyncPiEstimator] Loaded 2012-11-15T18:38:46.467Z,1353004726.467 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2012-11-15T18:38:46.468Z,1353004726.468 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406A14E0 2012-11-15T18:38:46.469Z,1353004726.469 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2012-11-15T18:38:46.470Z,1353004726.470 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2012-11-15T18:38:46.635Z,1353004726.635 [AHRS_sp3003D] Loaded 2012-11-15T18:38:46.636Z,1353004726.636 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2012-11-15T18:38:46.893Z,1353004726.893 [Batt_Ocean_Server] Loaded 2012-11-15T18:38:46.893Z,1353004726.893 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2012-11-15T18:38:46.906Z,1353004726.906 [Depth_Keller] Loaded 2012-11-15T18:38:46.906Z,1353004726.906 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2012-11-15T18:38:46.912Z,1353004726.912 [DropWeight] Loaded 2012-11-15T18:38:46.912Z,1353004726.912 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2012-11-15T18:38:47.073Z,1353004727.073 [DVL_micro] Loaded 2012-11-15T18:38:47.073Z,1353004727.073 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2012-11-15T18:38:47.074Z,1353004727.074 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 407234E0 2012-11-15T18:38:47.156Z,1353004727.156 [NAL9602] Loaded 2012-11-15T18:38:47.156Z,1353004727.156 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2012-11-15T18:38:47.207Z,1353004727.207 [Onboard] Loaded 2012-11-15T18:38:47.207Z,1353004727.207 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2012-11-15T18:38:47.214Z,1353004727.214 [Radio_Freewave] Loaded 2012-11-15T18:38:47.214Z,1353004727.214 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2012-11-15T18:38:47.215Z,1353004727.215 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2012-11-15T18:38:47.216Z,1353004727.216 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2012-11-15T18:38:47.312Z,1353004727.312 [CTD_NeilBrown] Loaded 2012-11-15T18:38:47.312Z,1353004727.312 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2012-11-15T18:38:47.313Z,1353004727.313 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4077C4E0 2012-11-15T18:38:47.329Z,1353004727.329 [PAR_Licor] Loaded 2012-11-15T18:38:47.330Z,1353004727.330 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2012-11-15T18:38:47.382Z,1353004727.382 [Turbulence_NPS] Loaded 2012-11-15T18:38:47.383Z,1353004727.383 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread. 2012-11-15T18:38:47.384Z,1353004727.384 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 407AC4E0 2012-11-15T18:38:47.413Z,1353004727.413 [WetLabsBB2FL] Loaded 2012-11-15T18:38:47.414Z,1353004727.414 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2012-11-15T18:38:47.415Z,1353004727.415 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407DC4E0 2012-11-15T18:38:47.415Z,1353004727.415 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2012-11-15T18:38:47.418Z,1353004727.418 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2012-11-15T18:38:47.419Z,1353004727.419 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2012-11-15T18:38:47.429Z,1353004727.429 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2012-11-15T18:38:47.430Z,1353004727.430 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 4080C4E0 2012-11-15T18:38:47.434Z,1353004727.434 [Supervisor](DEBUG): Running supervisor. 2012-11-15T18:38:47.435Z,1353004727.435 [CommandLine](INFO): Thread ID is 746 2012-11-15T18:38:47.438Z,1353004727.438 [controlThread](INFO): Thread ID is 745 2012-11-15T18:38:47.438Z,1353004727.438 [controlThread](DEBUG): Initializing ControlThread 2012-11-15T18:38:47.439Z,1353004727.439 [CycleStarter](INFO): Thread ID is 744 2012-11-15T18:38:47.439Z,1353004727.439 [InternalSim](DEBUG): InternalSim initializing... 2012-11-15T18:38:47.473Z,1353004727.473 [logger](INFO): Thread ID is 747 2012-11-15T18:38:47.576Z,1353004727.576 [AsyncPiEstimator](INFO): Thread ID is 808 2012-11-15T18:38:47.576Z,1353004727.576 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2012-11-15T18:38:47.593Z,1353004727.593 [DVL_micro](INFO): Thread ID is 809 2012-11-15T18:38:47.658Z,1353004727.658 [CTD_NeilBrown](INFO): Thread ID is 810 2012-11-15T18:38:47.659Z,1353004727.658 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2012-11-15T18:38:47.661Z,1353004727.661 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-11-15T18:38:47.741Z,1353004727.741 [Turbulence_NPS](INFO): Thread ID is 811 2012-11-15T18:38:47.741Z,1353004727.741 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS. 2012-11-15T18:38:47.742Z,1353004727.742 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-11-15T18:38:47.773Z,1353004727.773 [WetLabsBB2FL](INFO): Thread ID is 812 2012-11-15T18:38:47.774Z,1353004727.774 [WetLabsBB2FL](INFO): Powering down 2012-11-15T18:38:47.809Z,1353004727.809 [Turbulence_NPS](INFO): Pause powering down 2012-11-15T18:38:47.869Z,1353004727.869 [NavChartDb](INFO): Thread ID is 813 2012-11-15T18:38:47.875Z,1353004727.875 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2012-11-15T18:38:47.875Z,1353004727.875 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2012-11-15T18:38:47.876Z,1353004727.876 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2012-11-15T18:38:47.876Z,1353004727.876 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2012-11-15T18:38:47.876Z,1353004727.876 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2012-11-15T18:38:47.893Z,1353004727.893 [DVL_micro](INFO): Initializing 2012-11-15T18:38:47.894Z,1353004727.894 [DVL_micro](INFO): start:Powering up 2012-11-15T18:38:47.895Z,1353004727.895 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-11-15T18:38:47.895Z,1353004727.895 [DVL_micro](INFO): Cycling power to configure device. 2012-11-15T18:38:47.911Z,1353004727.911 [SBIT](INFO): Initialize SBIT Component. 2012-11-15T18:38:47.912Z,1353004727.912 [SBIT](IMPORTANT): Tethys CM Info: $Rev: 10057 2012-11-15T18:38:47.915Z,1353004727.915 [IBIT](INFO): Initialize IBIT Component. 2012-11-15T18:38:47.916Z,1353004727.916 [CBIT](DEBUG): Initialize CBIT Component. 2012-11-15T18:38:47.924Z,1353004727.924 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2012-11-15T18:38:47.925Z,1353004727.925 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2012-11-15T18:38:47.950Z,1353004727.950 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2012-11-15T18:38:47.950Z,1353004727.950 [NavChart](DEBUG): Initialize NavChart Derivation. 2012-11-15T18:38:47.951Z,1353004727.951 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2012-11-15T18:38:47.951Z,1353004727.951 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2012-11-15T18:38:47.951Z,1353004727.951 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2012-11-15T18:38:47.953Z,1353004727.953 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2012-11-15T18:38:47.953Z,1353004727.953 [Navigation](DEBUG): Initializing Navigation. 2012-11-15T18:38:47.954Z,1353004727.954 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2012-11-15T18:38:47.955Z,1353004727.955 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2012-11-15T18:38:47.956Z,1353004727.956 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2012-11-15T18:38:47.969Z,1353004727.969 [LoopControl](DEBUG): Initialize LoopControlComponent. 2012-11-15T18:38:49.503Z,1353004729.503 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2012-11-15T18:38:49.538Z,1353004729.538 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2012-11-15T18:38:49.600Z,1353004729.600 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-15T18:38:49.620Z,1353004729.620 [MissionManager](DEBUG): 2012-11-15T18:38:49.621Z,1353004729.621 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2012-11-15T18:38:49.703Z,1353004729.703 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2012-11-15T18:38:49.705Z,1353004729.705 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2012-11-15T18:38:49.718Z,1353004729.718 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-15T18:38:49.725Z,1353004729.726 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2012-11-15T18:38:49.739Z,1353004729.740 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-15T18:38:49.752Z,1353004729.752 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2012-11-15T18:38:49.792Z,1353004729.792 [Default:D.SetSpeed](DEBUG): Construct. 2012-11-15T18:38:49.795Z,1353004729.795 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-15T18:38:49.817Z,1353004729.816 [Default:F.Wait](DEBUG): Construct Wait. 2012-11-15T18:38:49.820Z,1353004729.820 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2012-11-15T18:38:49.837Z,1353004729.837 [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-15T18:38:49.897Z,1353004729.897 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2012-11-15T18:38:49.981Z,1353004729.981 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-11-15T18:38:49.981Z,1353004729.981 [DVL_micro](INFO): Querying output modes 2012-11-15T18:38:49.982Z,1353004729.982 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-11-15T18:38:49.993Z,1353004729.993 [DVL_micro](DEBUG): cmdResponse: 01 2012-11-15T18:38:49.993Z,1353004729.993 [DVL_micro](INFO): NQ1 output enabled 2012-11-15T18:38:49.993Z,1353004729.993 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-11-15T18:38:50.005Z,1353004730.005 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-11-15T18:38:50.010Z,1353004730.010 [DVL_micro](INFO): pause:Powering down 2012-11-15T18:38:50.350Z,1353004730.350 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2012-11-15T18:38:50.373Z,1353004730.373 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2012-11-15T18:38:50.391Z,1353004730.391 [ElevatorServo](DEBUG): Initializing EZServoServo. 2012-11-15T18:38:50.397Z,1353004730.397 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2012-11-15T18:38:50.413Z,1353004730.413 [MassServo](DEBUG): Initializing EZServoServo. 2012-11-15T18:38:50.421Z,1353004730.421 [MassServo](DEBUG): Initializing MassServo. 2012-11-15T18:38:50.426Z,1353004730.426 [RudderServo](DEBUG): Initializing EZServoServo. 2012-11-15T18:38:50.433Z,1353004730.433 [RudderServo](DEBUG): Initializing RudderServo. 2012-11-15T18:38:50.438Z,1353004730.438 [ThrusterServo](DEBUG): Initializing EZServoServo. 2012-11-15T18:38:50.445Z,1353004730.445 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2012-11-15T18:38:52.371Z,1353004732.371 [NAL9602](INFO): Powering up NAL9602 2012-11-15T18:39:02.841Z,1353004742.841 [NAL9602](INFO): NAL9602 initialized 2012-11-15T18:39:03.334Z,1353004743.334 [SBIT](IMPORTANT): Beginning Startup BIT 2012-11-15T18:39:03.337Z,1353004743.337 [CBIT](IMPORTANT): Beginning GF scan 2012-11-15T18:39:16.420Z,1353004756.420 [SBIT](FAULT): Elevator: EXPECTED:15.000000 ACTUAL:14.797676 2012-11-15T18:39:18.325Z,1353004758.325 [Radio_Freewave](INFO): Powering up 2012-11-15T18:39:29.709Z,1353004769.709 [CBIT](IMPORTANT): No ground fault detected 2012-11-15T18:39:40.869Z,1353004780.869 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -1.000000 centimeter 2012-11-15T18:39:40.871Z,1353004780.871 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2012-11-15T18:39:41.291Z,1353004781.291 [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,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter, 2012-11-15T18:39:57.012Z,1353004797.012 [SBIT](FAULT): Mass: EXPECTED:0.000000 ACTUAL:-0.009986 2012-11-15T18:39:57.012Z,1353004797.012 [SBIT](FAULT): Control surface position failure. 2012-11-15T18:39:57.374Z,1353004797.374 [SBIT](CRITICAL): SBIT FAILED 2012-11-15T18:39:57.789Z,1353004797.789 [MissionManager](IMPORTANT): Started mission Startup 2012-11-15T18:39:57.789Z,1353004797.789 [Startup] Running Loop=1 2012-11-15T18:39:57.789Z,1353004797.789 [Startup](INFO): Aggregate::initialize Startup 2012-11-15T18:39:57.789Z,1353004797.789 [Startup:A.GoToSurface] Running Loop=1 2012-11-15T18:39:57.789Z,1353004797.789 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-11-15T18:39:57.795Z,1353004797.795 [Startup:StartupSatComms] Running Loop=1 2012-11-15T18:39:57.795Z,1353004797.796 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2012-11-15T18:39:57.796Z,1353004797.796 [Startup:StartupSatComms:A] Running Loop=1 2012-11-15T18:39:58.156Z,1353004798.156 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-11-15T18:40:03.348Z,1353004803.348 [Radio_Freewave](INFO): Powering down 2012-11-15T18:40:58.152Z,1353004858.152 [Startup:StartupSatComms:A](INFO): Timed out from 2012-11-15T18:39:57.8Z 2012-11-15T18:40:58.153Z,1353004858.152 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1 2012-11-15T18:40:58.153Z,1353004858.153 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout 2012-11-15T18:40:58.153Z,1353004858.153 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout 2012-11-15T18:40:58.153Z,1353004858.153 [Startup:StartupSatComms:A] Stopped 2012-11-15T18:40:58.153Z,1353004858.153 [Startup:StartupSatComms:B] Running Loop=1 2012-11-15T18:40:58.628Z,1353004858.628 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-11-15T18:41:00.544Z,1353004860.544 [Radio_Freewave](INFO): Powering up 2012-11-15T18:41:02.115Z,1353004862.115 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:41:06.454Z,1353004866.454 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:41:09.973Z,1353004869.973 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:41:14.343Z,1353004874.343 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:41:18.210Z,1353004878.210 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:41:22.551Z,1353004882.551 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:41:24.573Z,1353004884.573 [Radio_Freewave](INFO): Powering down 2012-11-15T18:41:34.486Z,1353004894.486 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:41:42.841Z,1353004902.841 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:41:47.182Z,1353004907.182 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:41:51.595Z,1353004911.595 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:41:56.321Z,1353004916.321 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:41:58.327Z,1353004918.327 [Startup:StartupSatComms:B](INFO): Timed out from 2012-11-15T18:40:58.2Z 2012-11-15T18:41:58.327Z,1353004918.328 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1 2012-11-15T18:41:58.328Z,1353004918.328 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout 2012-11-15T18:41:58.328Z,1353004918.328 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout 2012-11-15T18:41:58.328Z,1353004918.328 [Startup:StartupSatComms:B] Stopped 2012-11-15T18:41:58.328Z,1353004918.328 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2012-11-15T18:41:58.328Z,1353004918.328 [Startup:StartupSatComms] Stopped 2012-11-15T18:41:58.328Z,1353004918.328 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2012-11-15T18:41:58.329Z,1353004918.329 [Startup](INFO): Completed Startup 2012-11-15T18:41:58.329Z,1353004918.329 [Startup] Stopped 2012-11-15T18:41:58.329Z,1353004918.329 [Startup](INFO): Aggregate::uninitialize Startup 2012-11-15T18:41:58.330Z,1353004918.330 [Startup:A.GoToSurface] Stopped 2012-11-15T18:41:58.330Z,1353004918.330 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-11-15T18:41:58.722Z,1353004918.722 [MissionManager](IMPORTANT): Started mission Default 2012-11-15T18:41:58.722Z,1353004918.722 [Default] Running Loop=1 2012-11-15T18:41:58.722Z,1353004918.722 [Default](INFO): Aggregate::initialize Default 2012-11-15T18:41:58.722Z,1353004918.722 [Default:D.SetSpeed] Running Loop=1 2012-11-15T18:41:58.722Z,1353004918.722 [Default:D.SetSpeed](DEBUG): Initialize. 2012-11-15T18:41:58.723Z,1353004918.723 [Default:E.GoToSurface] Running Loop=1 2012-11-15T18:41:58.723Z,1353004918.723 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-11-15T18:41:58.723Z,1353004918.723 [Default:Iridium] Running Loop=1 2012-11-15T18:41:58.723Z,1353004918.723 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-11-15T18:41:58.723Z,1353004918.723 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-11-15T18:41:58.723Z,1353004918.723 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-11-15T18:41:58.723Z,1353004918.723 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-11-15T18:41:58.724Z,1353004918.724 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-11-15T18:41:58.724Z,1353004918.724 [Default:E.GoToSurface] Running Loop=1 2012-11-15T18:41:58.729Z,1353004918.729 [Default:D.SetSpeed] Running Loop=1 2012-11-15T18:41:58.734Z,1353004918.734 [Default:CallIridium] Running Loop=1 2012-11-15T18:41:58.734Z,1353004918.734 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-11-15T18:41:58.734Z,1353004918.734 [Default:CallIridium:A] Running Loop=1 2012-11-15T18:41:58.736Z,1353004918.736 [Default:CallIridium:A] Stopped 2012-11-15T18:41:58.736Z,1353004918.736 [Default:CallIridium:B] Running Loop=1 2012-11-15T18:41:58.736Z,1353004918.736 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-11-15T18:41:58.741Z,1353004918.741 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-11-15T18:41:58.746Z,1353004918.746 [Default:GPS] Running Loop=1 2012-11-15T18:41:58.746Z,1353004918.746 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-11-15T18:41:58.746Z,1353004918.746 [Default:GPS:A.SetSpeed] Running Loop=1 2012-11-15T18:41:58.746Z,1353004918.746 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-11-15T18:41:58.747Z,1353004918.747 [Default:GPS:B.GoToSurface] Running Loop=1 2012-11-15T18:41:58.747Z,1353004918.747 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-11-15T18:41:58.752Z,1353004918.752 [Default:GPS:A.SetSpeed] Running Loop=1 2012-11-15T18:42:45.126Z,1353004965.126 [Radio_Freewave](INFO): Powering up 2012-11-15T18:42:45.135Z,1353004965.135 [Default:Iridium:B.GoToSurface] Stopped 2012-11-15T18:42:45.136Z,1353004965.136 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-11-15T18:42:45.136Z,1353004965.136 [Default:Iridium:Read_Iridium] Running Loop=1 2012-11-15T18:42:45.137Z,1353004965.137 [Default:GPS:B.GoToSurface] Stopped 2012-11-15T18:42:45.137Z,1353004965.137 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-11-15T18:42:45.137Z,1353004965.137 [Default:GPS:Read_GPS] Running Loop=1 2012-11-15T18:42:45.541Z,1353004965.541 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-11-15T18:42:45.543Z,1353004965.543 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-11-15T18:42:49.049Z,1353004969.049 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:42:53.407Z,1353004973.407 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:43:00.949Z,1353004980.949 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -2.000000 centimeter 2012-11-15T18:43:04.942Z,1353004984.942 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:43:09.327Z,1353004989.327 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:43:18.183Z,1353004998.183 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:43:26.485Z,1353005006.485 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:43:30.798Z,1353005010.798 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:43:35.122Z,1353005015.122 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:43:39.131Z,1353005019.131 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:43:43.046Z,1353005023.046 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:43:46.953Z,1353005026.953 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:43:51.274Z,1353005031.274 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:00.072Z,1353005040.072 [Radio_Freewave](INFO): Powering down 2012-11-15T18:44:01.186Z,1353005041.186 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:09.668Z,1353005049.668 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:13.961Z,1353005053.961 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:18.346Z,1353005058.346 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:22.710Z,1353005062.710 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:26.719Z,1353005066.719 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:27.569Z,1353005067.569 [Radio_Freewave](INFO): Powering up 2012-11-15T18:44:30.795Z,1353005070.795 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:35.065Z,1353005075.065 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:39.790Z,1353005079.790 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:44.543Z,1353005084.543 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:45.145Z,1353005085.145 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -3.000000 centimeter 2012-11-15T18:44:54.805Z,1353005094.805 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2012-11-15T18:44:54.806Z,1353005094.806 [CommandLine](IMPORTANT): platform_mass_position -2.515445 centimeter 2012-11-15T18:44:57.364Z,1353005097.364 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:44:57.858Z,1353005097.858 [CommandLine](IMPORTANT): got command failComponent 2012-11-15T18:44:57.858Z,1353005097.858 [CommandLine](IMPORTANT): Failed components: 2012-11-15T18:44:57.858Z,1353005097.858 [CommandLine](IMPORTANT): No failed Components. 2012-11-15T18:45:01.426Z,1353005101.425 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2012-11-15T18:45:01.426Z,1353005101.426 [CommandLine](IMPORTANT): platform_mass_position -2.992231 centimeter 2012-11-15T18:45:04.085Z,1353005104.085 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2012-11-15T18:45:04.086Z,1353005104.086 [CommandLine](IMPORTANT): platform_mass_position -2.993691 centimeter 2012-11-15T18:45:06.135Z,1353005106.135 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:45:10.961Z,1353005110.961 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:45:14.964Z,1353005114.964 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:45:20.183Z,1353005120.183 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:45:24.949Z,1353005124.949 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:45:29.783Z,1353005129.783 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:45:34.154Z,1353005134.154 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:45:38.723Z,1353005138.723 [Radio_Freewave](INFO): Powering down 2012-11-15T18:45:39.010Z,1353005139.010 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:45:44.082Z,1353005144.082 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:45:48.791Z,1353005148.791 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:45:52.802Z,1353005152.802 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:45:56.834Z,1353005156.834 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:46:01.265Z,1353005161.265 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:46:05.722Z,1353005165.722 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:46:10.166Z,1353005170.166 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:46:14.646Z,1353005174.647 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:46:19.034Z,1353005179.034 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:46:23.638Z,1353005183.638 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:46:23.643Z,1353005183.644 [Radio_Freewave](INFO): Powering up 2012-11-15T18:46:27.834Z,1353005187.834 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:46:31.870Z,1353005191.870 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:46:35.891Z,1353005195.891 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:46:48.262Z,1353005208.262 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:47:01.094Z,1353005221.095 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:47:13.122Z,1353005233.122 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:47:50.292Z,1353005270.292 [NAL9602](INFO): SBD MO Status=0, MOMSN=5110, MT Status=0, MTMSN=0 2012-11-15T18:47:50.391Z,1353005270.391 [Default:Iridium:Read_Iridium] Stopped 2012-11-15T18:47:50.392Z,1353005270.392 [Default:Iridium](INFO): Completed Default:Iridium 2012-11-15T18:47:50.392Z,1353005270.392 [Default:Iridium] Stopped 2012-11-15T18:47:50.392Z,1353005270.392 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-11-15T18:47:50.392Z,1353005270.392 [Default:Iridium:A.SetSpeed] Stopped 2012-11-15T18:47:50.392Z,1353005270.392 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-11-15T18:47:50.392Z,1353005270.392 [Default:F.Wait] Running Loop=1 2012-11-15T18:47:50.392Z,1353005270.392 [Default:F.Wait](DEBUG): Initialize Wait Component. 2012-11-15T18:47:50.705Z,1353005270.705 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2012-11-15T18:47:50.705Z,1353005270.705 [Default:CallIridium:B] Stopped 2012-11-15T18:47:50.705Z,1353005270.705 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2012-11-15T18:47:50.705Z,1353005270.705 [Default:CallIridium](INFO): Completed Default:CallIridium 2012-11-15T18:47:50.706Z,1353005270.705 [Default:CallIridium] Stopped 2012-11-15T18:47:50.706Z,1353005270.706 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-11-15T18:48:10.081Z,1353005290.081 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 0.000000 centimeter 2012-11-15T18:48:14.265Z,1353005294.265 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2012-11-15T18:48:14.266Z,1353005294.266 [CommandLine](IMPORTANT): platform_mass_position -2.739948 centimeter 2012-11-15T18:48:40.797Z,1353005320.797 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 2.000000 centimeter 2012-11-15T18:48:45.357Z,1353005325.357 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2012-11-15T18:48:45.358Z,1353005325.358 [CommandLine](IMPORTANT): platform_mass_position -0.419160 centimeter 2012-11-15T18:48:52.181Z,1353005332.181 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2012-11-15T18:48:52.182Z,1353005332.181 [CommandLine](IMPORTANT): platform_mass_position 0.074618 centimeter 2012-11-15T18:48:54.294Z,1353005334.294 [NAL9602](IMPORTANT): GPS fix at: 1353005311.00 2012-11-15T18:48:54.311Z,1353005334.311 [Default:GPS:Read_GPS] Stopped 2012-11-15T18:48:54.311Z,1353005334.311 [Default:GPS:D] Running Loop=1 2012-11-15T18:48:54.732Z,1353005334.731 [Default:GPS:D] Stopped 2012-11-15T18:48:54.732Z,1353005334.732 [Default:GPS](INFO): Completed Default:GPS 2012-11-15T18:48:54.732Z,1353005334.732 [Default:GPS] Stopped 2012-11-15T18:48:54.732Z,1353005334.732 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-11-15T18:48:54.732Z,1353005334.732 [Default:GPS:A.SetSpeed] Stopped 2012-11-15T18:48:54.732Z,1353005334.732 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-11-15T18:48:57.297Z,1353005337.297 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2012-11-15T18:48:57.298Z,1353005337.297 [CommandLine](IMPORTANT): platform_mass_position 0.312910 centimeter 2012-11-15T18:49:01.541Z,1353005341.541 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2012-11-15T18:49:01.541Z,1353005341.542 [CommandLine](IMPORTANT): platform_mass_position 0.700276 centimeter 2012-11-15T18:49:11.197Z,1353005351.197 [CommandLine](IMPORTANT): got command get platform_pitch_angle 2012-11-15T18:49:11.198Z,1353005351.197 [CommandLine](IMPORTANT): platform_pitch_angle -6.394043 degree 2012-11-15T18:49:14.929Z,1353005354.929 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2012-11-15T18:49:14.930Z,1353005354.930 [CommandLine](IMPORTANT): platform_mass_position 1.443173 centimeter 2012-11-15T18:49:15.271Z,1353005355.271 [NAL9602](INFO): Powering down 2012-11-15T18:49:20.437Z,1353005360.437 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2012-11-15T18:49:20.438Z,1353005360.438 [CommandLine](IMPORTANT): platform_mass_position 1.998349 centimeter 2012-11-15T18:50:00.255Z,1353005400.255 [Radio_Freewave](INFO): Powering down 2012-11-15T18:50:15.288Z,1353005415.288 [Radio_Freewave](INFO): Powering up 2012-11-15T18:51:20.228Z,1353005480.228 [Radio_Freewave](INFO): Powering down 2012-11-15T18:51:40.275Z,1353005500.275 [Radio_Freewave](INFO): Powering up 2012-11-15T18:51:50.276Z,1353005510.276 [Radio_Freewave](INFO): Powering down 2012-11-15T18:52:10.239Z,1353005530.239 [Radio_Freewave](INFO): Powering up 2012-11-15T18:52:24.365Z,1353005544.365 [CommandLine](IMPORTANT): got command run ./Missions/Default.xml 2012-11-15T18:52:24.365Z,1353005544.365 [MissionManager](INFO): Loading Mission: ./Missions/Default.xml 2012-11-15T18:52:24.392Z,1353005544.392 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2012-11-15T18:52:24.394Z,1353005544.394 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2012-11-15T18:52:24.396Z,1353005544.396 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-15T18:52:24.402Z,1353005544.402 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2012-11-15T18:52:24.404Z,1353005544.404 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-15T18:52:24.409Z,1353005544.409 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2012-11-15T18:52:24.415Z,1353005544.415 [Default:D.SetSpeed](DEBUG): Construct. 2012-11-15T18:52:24.418Z,1353005544.418 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-15T18:52:24.422Z,1353005544.422 [Default:F.Wait](DEBUG): Construct Wait. 2012-11-15T18:52:24.426Z,1353005544.426 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2012-11-15T18:52:24.427Z,1353005544.427 [CommandLine](IMPORTANT): Running ./Missions/Default.xml 2012-11-15T18:52:25.296Z,1353005545.296 [Default] Stopped 2012-11-15T18:52:25.296Z,1353005545.296 [Default](INFO): Aggregate::uninitialize Default 2012-11-15T18:52:25.297Z,1353005545.297 [Default:D.SetSpeed] Stopped 2012-11-15T18:52:25.297Z,1353005545.297 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-11-15T18:52:25.297Z,1353005545.297 [Default:E.GoToSurface] Stopped 2012-11-15T18:52:25.297Z,1353005545.297 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-11-15T18:52:25.297Z,1353005545.297 [Default:F.Wait] Stopped 2012-11-15T18:52:25.297Z,1353005545.297 [Default:F.Wait](DEBUG): Uninitialize Wait Component. 2012-11-15T18:52:25.297Z,1353005545.297 [MissionManager](IMPORTANT): Started mission Default 2012-11-15T18:52:25.297Z,1353005545.297 [Default] Running Loop=1 2012-11-15T18:52:25.297Z,1353005545.297 [Default](INFO): Aggregate::initialize Default 2012-11-15T18:52:25.297Z,1353005545.297 [Default:D.SetSpeed] Running Loop=1 2012-11-15T18:52:25.297Z,1353005545.297 [Default:D.SetSpeed](DEBUG): Initialize. 2012-11-15T18:52:25.298Z,1353005545.298 [Default:E.GoToSurface] Running Loop=1 2012-11-15T18:52:25.298Z,1353005545.298 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-11-15T18:52:25.298Z,1353005545.298 [Default:Iridium] Running Loop=1 2012-11-15T18:52:25.298Z,1353005545.298 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-11-15T18:52:25.298Z,1353005545.298 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-11-15T18:52:25.298Z,1353005545.298 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-11-15T18:52:25.298Z,1353005545.298 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-11-15T18:52:25.298Z,1353005545.298 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-11-15T18:52:25.299Z,1353005545.299 [Default:E.GoToSurface] Running Loop=1 2012-11-15T18:52:25.300Z,1353005545.300 [Default:D.SetSpeed] Running Loop=1 2012-11-15T18:52:25.302Z,1353005545.302 [Default:Iridium:B.GoToSurface] Stopped 2012-11-15T18:52:25.302Z,1353005545.302 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-11-15T18:52:25.302Z,1353005545.302 [Default:Iridium:Read_Iridium] Running Loop=1 2012-11-15T18:52:25.302Z,1353005545.302 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-11-15T18:52:25.303Z,1353005545.303 [Default:GPS] Running Loop=1 2012-11-15T18:52:25.303Z,1353005545.303 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-11-15T18:52:25.303Z,1353005545.303 [Default:GPS:A.SetSpeed] Running Loop=1 2012-11-15T18:52:25.303Z,1353005545.303 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-11-15T18:52:25.304Z,1353005545.304 [Default:GPS:B.GoToSurface] Running Loop=1 2012-11-15T18:52:25.304Z,1353005545.304 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-11-15T18:52:25.305Z,1353005545.305 [Default:GPS:B.GoToSurface] Stopped 2012-11-15T18:52:25.305Z,1353005545.305 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-11-15T18:52:25.305Z,1353005545.305 [Default:GPS:Read_GPS] Running Loop=1 2012-11-15T18:52:25.305Z,1353005545.305 [Default:GPS:A.SetSpeed] Running Loop=1 2012-11-15T18:52:25.536Z,1353005545.536 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-11-15T18:52:25.538Z,1353005545.538 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-11-15T18:52:25.904Z,1353005545.904 [NAL9602](INFO): Powering up 2012-11-15T18:52:36.741Z,1353005556.741 [NAL9602](INFO): NAL9602 initialized 2012-11-15T18:52:41.475Z,1353005561.475 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:52:43.435Z,1353005563.435 [Radio_Freewave](INFO): Powering down 2012-11-15T18:52:46.221Z,1353005566.221 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:52:50.564Z,1353005570.565 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:52:50.582Z,1353005570.582 [Default:CallIridium] Running Loop=1 2012-11-15T18:52:50.583Z,1353005570.583 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-11-15T18:52:50.583Z,1353005570.583 [Default:CallIridium:A] Running Loop=1 2012-11-15T18:52:50.584Z,1353005570.584 [Default:CallIridium:A] Stopped 2012-11-15T18:52:50.584Z,1353005570.584 [Default:CallIridium:B] Running Loop=1 2012-11-15T18:52:50.584Z,1353005570.584 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-11-15T18:52:55.309Z,1353005575.309 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:52:59.410Z,1353005579.410 [Radio_Freewave](INFO): Powering up 2012-11-15T18:52:59.665Z,1353005579.665 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:04.561Z,1353005584.560 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:09.345Z,1353005589.345 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:13.750Z,1353005593.750 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:18.390Z,1353005598.390 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:23.126Z,1353005603.126 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:27.478Z,1353005607.478 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:27.484Z,1353005607.484 [Radio_Freewave](INFO): Powering down 2012-11-15T18:53:31.894Z,1353005611.894 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:36.304Z,1353005616.304 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:40.997Z,1353005620.997 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:45.394Z,1353005625.394 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:49.843Z,1353005629.843 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:52.592Z,1353005632.592 [Radio_Freewave](INFO): Powering up 2012-11-15T18:53:54.074Z,1353005634.074 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:53:58.830Z,1353005638.830 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:54:03.114Z,1353005643.114 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:54:07.161Z,1353005647.161 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:54:11.549Z,1353005651.549 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:54:15.609Z,1353005655.609 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 3.000000 centimeter 2012-11-15T18:54:15.935Z,1353005655.935 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:54:18.977Z,1353005658.977 [CommandLine](IMPORTANT): got command run ./Missions/Default.xml 2012-11-15T18:54:18.977Z,1353005658.977 [Default] Stopped 2012-11-15T18:54:18.977Z,1353005658.977 [Default](INFO): Aggregate::uninitialize Default 2012-11-15T18:54:18.977Z,1353005658.977 [Default:GPS] Stopped 2012-11-15T18:54:18.977Z,1353005658.977 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-11-15T18:54:18.977Z,1353005658.977 [Default:GPS:A.SetSpeed] Stopped 2012-11-15T18:54:18.977Z,1353005658.977 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-11-15T18:54:18.978Z,1353005658.977 [Default:GPS:Read_GPS] Stopped 2012-11-15T18:54:18.978Z,1353005658.978 [Default:Iridium] Stopped 2012-11-15T18:54:18.978Z,1353005658.978 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-11-15T18:54:18.978Z,1353005658.978 [Default:Iridium:A.SetSpeed] Stopped 2012-11-15T18:54:18.978Z,1353005658.978 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-11-15T18:54:18.978Z,1353005658.978 [Default:Iridium:Read_Iridium] Stopped 2012-11-15T18:54:18.978Z,1353005658.978 [Default:CallIridium] Stopped 2012-11-15T18:54:18.978Z,1353005658.978 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-11-15T18:54:18.978Z,1353005658.978 [Default:CallIridium:B] Stopped 2012-11-15T18:54:18.978Z,1353005658.978 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2012-11-15T18:54:18.978Z,1353005658.978 [Default:D.SetSpeed] Stopped 2012-11-15T18:54:18.978Z,1353005658.978 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-11-15T18:54:18.979Z,1353005658.978 [Default:E.GoToSurface] Stopped 2012-11-15T18:54:18.979Z,1353005658.978 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-11-15T18:54:18.982Z,1353005658.982 [MissionManager](INFO): Loading Mission: ./Missions/Default.xml 2012-11-15T18:54:19.008Z,1353005659.008 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2012-11-15T18:54:19.010Z,1353005659.010 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2012-11-15T18:54:19.012Z,1353005659.012 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-15T18:54:19.018Z,1353005659.018 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2012-11-15T18:54:19.020Z,1353005659.020 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-15T18:54:19.025Z,1353005659.026 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2012-11-15T18:54:19.033Z,1353005659.033 [Default:D.SetSpeed](DEBUG): Construct. 2012-11-15T18:54:19.035Z,1353005659.035 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2012-11-15T18:54:19.039Z,1353005659.039 [Default:F.Wait](DEBUG): Construct Wait. 2012-11-15T18:54:19.043Z,1353005659.043 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2012-11-15T18:54:19.043Z,1353005659.043 [CommandLine](IMPORTANT): Running ./Missions/Default.xml 2012-11-15T18:54:19.147Z,1353005659.147 [MissionManager](IMPORTANT): Started mission Default 2012-11-15T18:54:19.147Z,1353005659.147 [Default] Running Loop=1 2012-11-15T18:54:19.147Z,1353005659.148 [Default](INFO): Aggregate::initialize Default 2012-11-15T18:54:19.148Z,1353005659.148 [Default:D.SetSpeed] Running Loop=1 2012-11-15T18:54:19.148Z,1353005659.148 [Default:D.SetSpeed](DEBUG): Initialize. 2012-11-15T18:54:19.148Z,1353005659.148 [Default:E.GoToSurface] Running Loop=1 2012-11-15T18:54:19.148Z,1353005659.148 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-11-15T18:54:19.148Z,1353005659.148 [Default:Iridium] Running Loop=1 2012-11-15T18:54:19.148Z,1353005659.148 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-11-15T18:54:19.148Z,1353005659.148 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-11-15T18:54:19.148Z,1353005659.148 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-11-15T18:54:19.149Z,1353005659.149 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-11-15T18:54:19.149Z,1353005659.149 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-11-15T18:54:19.149Z,1353005659.149 [Default:E.GoToSurface] Running Loop=1 2012-11-15T18:54:19.150Z,1353005659.150 [Default:D.SetSpeed] Running Loop=1 2012-11-15T18:54:19.151Z,1353005659.151 [Default:CallIridium] Running Loop=1 2012-11-15T18:54:19.151Z,1353005659.151 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-11-15T18:54:19.152Z,1353005659.151 [Default:CallIridium:A] Running Loop=1 2012-11-15T18:54:19.152Z,1353005659.152 [Default:CallIridium:A] Stopped 2012-11-15T18:54:19.153Z,1353005659.152 [Default:CallIridium:B] Running Loop=1 2012-11-15T18:54:19.153Z,1353005659.153 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-11-15T18:54:19.154Z,1353005659.154 [Default:Iridium:B.GoToSurface] Stopped 2012-11-15T18:54:19.154Z,1353005659.154 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-11-15T18:54:19.154Z,1353005659.154 [Default:Iridium:Read_Iridium] Running Loop=1 2012-11-15T18:54:19.154Z,1353005659.154 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-11-15T18:54:19.155Z,1353005659.155 [Default:GPS] Running Loop=1 2012-11-15T18:54:19.155Z,1353005659.155 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-11-15T18:54:19.155Z,1353005659.155 [Default:GPS:A.SetSpeed] Running Loop=1 2012-11-15T18:54:19.155Z,1353005659.155 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-11-15T18:54:19.155Z,1353005659.155 [Default:GPS:B.GoToSurface] Running Loop=1 2012-11-15T18:54:19.156Z,1353005659.156 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-11-15T18:54:19.170Z,1353005659.170 [Default:GPS:B.GoToSurface] Stopped 2012-11-15T18:54:19.170Z,1353005659.170 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-11-15T18:54:19.170Z,1353005659.170 [Default:GPS:Read_GPS] Running Loop=1 2012-11-15T18:54:19.170Z,1353005659.170 [Default:GPS:A.SetSpeed] Running Loop=1 2012-11-15T18:54:19.535Z,1353005659.535 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-11-15T18:54:19.536Z,1353005659.536 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-11-15T18:54:23.898Z,1353005663.898 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:54:28.298Z,1353005668.298 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:54:30.317Z,1353005670.317 [MassServo](FAULT): Overload Error 2012-11-15T18:54:30.317Z,1353005670.317 [MassServo] Hardware Fault, FailCount= 1 2012-11-15T18:54:30.317Z,1353005670.317 [MassServo](ERROR): Hardware Fault 2012-11-15T18:54:30.356Z,1353005670.356 [CBIT](ERROR): Hardware Fault in component: MassServo 2012-11-15T18:54:30.687Z,1353005670.687 [MassServo](DEBUG): Uninitialize Mass Servo. 2012-11-15T18:54:30.688Z,1353005670.688 [MassServo](INFO): Powering down 2012-11-15T18:54:30.691Z,1353005670.691 [CBIT](INFO): Clearing failed state for component MassServo 2012-11-15T18:54:30.691Z,1353005670.691 [MassServo] No Fault, FailCount= 1 2012-11-15T18:54:31.182Z,1353005671.182 [MassServo](DEBUG): Initializing EZServoServo. 2012-11-15T18:54:31.301Z,1353005671.301 [MassServo](DEBUG): Initializing MassServo. 2012-11-15T18:54:33.026Z,1353005673.026 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:54:37.366Z,1353005677.366 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:54:46.567Z,1353005686.567 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:54:55.261Z,1353005695.261 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:55:03.651Z,1353005703.651 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:55:08.030Z,1353005708.030 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:55:10.830Z,1353005710.830 [Radio_Freewave](INFO): Powering down 2012-11-15T18:55:12.535Z,1353005712.535 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:55:17.010Z,1353005717.010 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:55:21.046Z,1353005721.046 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:55:23.087Z,1353005723.087 [Radio_Freewave](INFO): Powering up 2012-11-15T18:55:25.078Z,1353005725.078 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:55:29.891Z,1353005729.891 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:55:33.913Z,1353005733.913 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:55:45.426Z,1353005745.426 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:55:49.811Z,1353005749.811 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:55:51.472Z,1353005751.472 [MassServo](FAULT): Overload Error 2012-11-15T18:55:51.472Z,1353005751.472 [MassServo] Hardware Fault, FailCount= 2 2012-11-15T18:55:51.472Z,1353005751.472 [MassServo](ERROR): Hardware Fault 2012-11-15T18:55:51.512Z,1353005751.512 [CBIT](ERROR): Hardware Fault in component: MassServo 2012-11-15T18:55:51.811Z,1353005751.811 [MassServo](DEBUG): Uninitialize Mass Servo. 2012-11-15T18:55:51.811Z,1353005751.811 [MassServo](INFO): Powering down 2012-11-15T18:55:51.815Z,1353005751.815 [CBIT](INFO): Clearing failed state for component MassServo 2012-11-15T18:55:51.815Z,1353005751.815 [MassServo] No Fault, FailCount= 2 2012-11-15T18:55:52.210Z,1353005752.210 [MassServo](DEBUG): Initializing EZServoServo. 2012-11-15T18:55:52.326Z,1353005752.326 [MassServo](DEBUG): Initializing MassServo. 2012-11-15T18:55:54.190Z,1353005754.190 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:56:05.265Z,1353005765.265 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:56:15.295Z,1353005775.295 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:56:19.703Z,1353005779.703 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:56:24.002Z,1353005784.003 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:56:28.787Z,1353005788.787 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-11-15T18:56:33.190Z,1353005793.190 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0