2012-05-22T22:25:10.880Z,1337725510.880 [Supervisor](DEBUG): Initializing supervisor.
2012-05-22T22:25:10.883Z,1337725510.883 [SyncHandler](DEBUG): Created PCaller Thread at 1077138656
2012-05-22T22:25:10.883Z,1337725510.884 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2012-05-22T22:25:10.885Z,1337725510.885 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 1077335264
2012-05-22T22:25:10.886Z,1337725510.885 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2012-05-22T22:25:10.896Z,1337725510.896 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2012-05-22T22:25:10.897Z,1337725510.897 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 1077531872
2012-05-22T22:25:10.898Z,1337725510.898 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread.
2012-05-22T22:25:10.899Z,1337725510.899 [Supervisor](INFO): Looking for Config files in directory: Config/
2012-05-22T22:25:10.900Z,1337725510.900 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2012-05-22T22:25:11.194Z,1337725511.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2012-05-22T22:25:11.196Z,1337725511.196 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2012-05-22T22:25:11.372Z,1337725511.372 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2012-05-22T22:25:11.373Z,1337725511.373 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2012-05-22T22:25:11.452Z,1337725511.452 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2012-05-22T22:25:11.453Z,1337725511.453 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2012-05-22T22:25:11.632Z,1337725511.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2012-05-22T22:25:11.632Z,1337725511.632 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2012-05-22T22:25:11.752Z,1337725511.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2012-05-22T22:25:11.753Z,1337725511.753 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2012-05-22T22:25:11.962Z,1337725511.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2012-05-22T22:25:11.963Z,1337725511.963 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2012-05-22T22:25:12.103Z,1337725512.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2012-05-22T22:25:12.104Z,1337725512.104 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2012-05-22T22:25:12.355Z,1337725512.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2012-05-22T22:25:12.356Z,1337725512.356 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2012-05-22T22:25:12.448Z,1337725512.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2012-05-22T22:25:12.449Z,1337725512.449 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2012-05-22T22:25:12.819Z,1337725512.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2012-05-22T22:25:12.820Z,1337725512.820 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2012-05-22T22:25:12.924Z,1337725512.924 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2012-05-22T22:25:12.924Z,1337725512.924 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2012-05-22T22:25:13.004Z,1337725513.004 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2012-05-22T22:25:13.005Z,1337725513.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2012-05-22T22:25:13.103Z,1337725513.103 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2012-05-22T22:25:13.229Z,1337725513.229 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2012-05-22T22:25:13.324Z,1337725513.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2012-05-22T22:25:13.414Z,1337725513.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2012-05-22T22:25:13.517Z,1337725513.517 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2012-05-22T22:25:13.600Z,1337725513.600 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2012-05-22T22:25:13.682Z,1337725513.682 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2012-05-22T22:25:13.789Z,1337725513.789 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2012-05-22T22:25:13.917Z,1337725513.917 [InternalSim] Loaded
2012-05-22T22:25:13.917Z,1337725513.917 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2012-05-22T22:25:13.918Z,1337725513.918 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2012-05-22T22:25:13.919Z,1337725513.919 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2012-05-22T22:25:13.971Z,1337725513.971 [SBIT](DEBUG): Construct Startup Built In Test.
2012-05-22T22:25:13.983Z,1337725513.983 [SBIT] Loaded
2012-05-22T22:25:13.983Z,1337725513.983 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2012-05-22T22:25:13.984Z,1337725513.984 [IBIT](DEBUG): Construct Initiated Built In Test.
2012-05-22T22:25:14.007Z,1337725514.007 [IBIT] Loaded
2012-05-22T22:25:14.007Z,1337725514.007 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2012-05-22T22:25:14.014Z,1337725514.014 [CBIT](DEBUG): Construct CBIT Built In Test.
2012-05-22T22:25:14.123Z,1337725514.123 [CBIT] Loaded
2012-05-22T22:25:14.123Z,1337725514.123 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2012-05-22T22:25:14.123Z,1337725514.123 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2012-05-22T22:25:14.124Z,1337725514.124 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2012-05-22T22:25:14.257Z,1337725514.257 [BuoyancyServo] Loaded
2012-05-22T22:25:14.257Z,1337725514.257 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2012-05-22T22:25:14.264Z,1337725514.264 [ElevatorServo] Loaded
2012-05-22T22:25:14.264Z,1337725514.264 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2012-05-22T22:25:14.271Z,1337725514.271 [MassServo] Loaded
2012-05-22T22:25:14.271Z,1337725514.271 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2012-05-22T22:25:14.278Z,1337725514.278 [RudderServo] Loaded
2012-05-22T22:25:14.278Z,1337725514.278 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2012-05-22T22:25:14.285Z,1337725514.285 [ThrusterServo] Loaded
2012-05-22T22:25:14.285Z,1337725514.285 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2012-05-22T22:25:14.286Z,1337725514.286 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2012-05-22T22:25:14.286Z,1337725514.286 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2012-05-22T22:25:14.306Z,1337725514.306 [DepthRateCalculator] Loaded
2012-05-22T22:25:14.307Z,1337725514.307 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2012-05-22T22:25:14.323Z,1337725514.323 [NavChart] Loaded
2012-05-22T22:25:14.323Z,1337725514.323 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2012-05-22T22:25:14.329Z,1337725514.329 [PitchRateCalculator] Loaded
2012-05-22T22:25:14.329Z,1337725514.329 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2012-05-22T22:25:14.335Z,1337725514.335 [SpeedCalculator] Loaded
2012-05-22T22:25:14.335Z,1337725514.335 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2012-05-22T22:25:14.350Z,1337725514.350 [TempGradientCalculator] Loaded
2012-05-22T22:25:14.350Z,1337725514.350 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2012-05-22T22:25:14.356Z,1337725514.356 [YawRateCalculator] Loaded
2012-05-22T22:25:14.356Z,1337725514.356 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2012-05-22T22:25:14.386Z,1337725514.386 [Navigation] Loaded
2012-05-22T22:25:14.386Z,1337725514.386 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2012-05-22T22:25:14.387Z,1337725514.387 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2012-05-22T22:25:14.387Z,1337725514.387 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2012-05-22T22:25:14.614Z,1337725514.614 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2012-05-22T22:25:14.614Z,1337725514.614 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2012-05-22T22:25:14.646Z,1337725514.646 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2012-05-22T22:25:14.647Z,1337725514.647 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2012-05-22T22:25:14.697Z,1337725514.697 [VerticalControl](DEBUG): Construct VerticalControl.
2012-05-22T22:25:14.739Z,1337725514.739 [VerticalControl] Loaded
2012-05-22T22:25:14.740Z,1337725514.740 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2012-05-22T22:25:14.741Z,1337725514.741 [HorizontalControl](DEBUG): Construct HorizontalControl.
2012-05-22T22:25:14.760Z,1337725514.760 [HorizontalControl] Loaded
2012-05-22T22:25:14.760Z,1337725514.760 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2012-05-22T22:25:14.761Z,1337725514.761 [SpeedControl](DEBUG): Construct SpeedControl.
2012-05-22T22:25:14.763Z,1337725514.763 [SpeedControl] Loaded
2012-05-22T22:25:14.763Z,1337725514.763 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2012-05-22T22:25:14.764Z,1337725514.764 [LoopControl](DEBUG): Construct LoopControl.
2012-05-22T22:25:14.765Z,1337725514.764 [LoopControl] Loaded
2012-05-22T22:25:14.765Z,1337725514.765 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2012-05-22T22:25:14.765Z,1337725514.765 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2012-05-22T22:25:14.766Z,1337725514.766 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2012-05-22T22:25:14.771Z,1337725514.772 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2012-05-22T22:25:14.776Z,1337725514.776 [AsyncPiEstimator] Loaded
2012-05-22T22:25:14.776Z,1337725514.776 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2012-05-22T22:25:14.778Z,1337725514.778 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 1078994144
2012-05-22T22:25:14.778Z,1337725514.778 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2012-05-22T22:25:14.779Z,1337725514.779 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2012-05-22T22:25:14.876Z,1337725514.876 [AHRS_sp3003D] Loaded
2012-05-22T22:25:14.876Z,1337725514.876 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2012-05-22T22:25:15.123Z,1337725515.123 [Batt_Ocean_Server] Loaded
2012-05-22T22:25:15.123Z,1337725515.124 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2012-05-22T22:25:15.136Z,1337725515.136 [Depth_Keller] Loaded
2012-05-22T22:25:15.136Z,1337725515.136 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2012-05-22T22:25:15.141Z,1337725515.141 [DropWeight] Loaded
2012-05-22T22:25:15.142Z,1337725515.142 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2012-05-22T22:25:15.236Z,1337725515.236 [DVL_micro] Loaded
2012-05-22T22:25:15.236Z,1337725515.236 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2012-05-22T22:25:15.315Z,1337725515.315 [NAL9601] Loaded
2012-05-22T22:25:15.315Z,1337725515.315 [ComponentRegistry](DEBUG): SyncComponent "NAL9601" handled in the control thread.
2012-05-22T22:25:15.364Z,1337725515.364 [Onboard] Loaded
2012-05-22T22:25:15.364Z,1337725515.364 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2012-05-22T22:25:15.370Z,1337725515.370 [Radio_Freewave] Loaded
2012-05-22T22:25:15.370Z,1337725515.370 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2012-05-22T22:25:15.371Z,1337725515.371 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2012-05-22T22:25:15.372Z,1337725515.372 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2012-05-22T22:25:15.428Z,1337725515.428 [CTD_NeilBrown] Loaded
2012-05-22T22:25:15.428Z,1337725515.428 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2012-05-22T22:25:15.429Z,1337725515.429 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 1079645408
2012-05-22T22:25:15.460Z,1337725515.460 [WetLabsBB2FL] Loaded
2012-05-22T22:25:15.460Z,1337725515.460 [ComponentRegistry](DEBUG): SyncComponent "WetLabsBB2FL" handled in the control thread.
2012-05-22T22:25:15.460Z,1337725515.460 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2012-05-22T22:25:15.463Z,1337725515.463 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2012-05-22T22:25:15.463Z,1337725515.463 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2012-05-22T22:25:15.469Z,1337725515.469 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2012-05-22T22:25:15.470Z,1337725515.470 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 1079842016
2012-05-22T22:25:15.470Z,1337725515.470 [Supervisor](DEBUG): Running supervisor.
2012-05-22T22:25:15.475Z,1337725515.475 [controlThread](DEBUG): Initializing ControlThread
2012-05-22T22:25:15.476Z,1337725515.476 [InternalSim](DEBUG):  InternalSim initializing...
2012-05-22T22:25:15.510Z,1337725515.510 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2012-05-22T22:25:15.519Z,1337725515.519 [SBIT](INFO): Initialize SBIT Component.
2012-05-22T22:25:15.520Z,1337725515.520 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 9636 $
2012-05-22T22:25:15.520Z,1337725515.520 [IBIT](INFO): Initialize IBIT Component.
2012-05-22T22:25:15.523Z,1337725515.523 [CBIT](DEBUG): Initialize CBIT Component.
2012-05-22T22:25:15.524Z,1337725515.524 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2012-05-22T22:25:15.525Z,1337725515.525 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2012-05-22T22:25:15.548Z,1337725515.548 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-05-22T22:25:15.548Z,1337725515.548 [NavChart](DEBUG): Initialize NavChart Derivation.
2012-05-22T22:25:15.549Z,1337725515.549 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2012-05-22T22:25:15.549Z,1337725515.549 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2012-05-22T22:25:15.549Z,1337725515.549 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2012-05-22T22:25:15.551Z,1337725515.551 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2012-05-22T22:25:15.551Z,1337725515.551 [Navigation](DEBUG): Initializing Navigation.
2012-05-22T22:25:15.552Z,1337725515.552 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2012-05-22T22:25:15.554Z,1337725515.554 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2012-05-22T22:25:15.554Z,1337725515.554 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2012-05-22T22:25:15.555Z,1337725515.555 [LoopControl](DEBUG): Initialize LoopControlComponent.
2012-05-22T22:25:15.637Z,1337725515.637 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2012-05-22T22:25:15.643Z,1337725515.643 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2012-05-22T22:25:15.647Z,1337725515.647 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2012-05-22T22:25:15.651Z,1337725515.651 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2012-05-22T22:25:15.655Z,1337725515.655 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2012-05-22T22:25:15.656Z,1337725515.656 [NavChartDb](INFO): Creating index for Soundings
2012-05-22T22:25:15.659Z,1337725515.659 [NavChartDb](INFO): Creating index for Contour 0p0
2012-05-22T22:25:15.661Z,1337725515.661 [NavChartDb](INFO): Creating index for Contour 1p8
2012-05-22T22:25:15.663Z,1337725515.663 [NavChartDb](INFO): Creating index for Contour 3p6
2012-05-22T22:25:15.665Z,1337725515.665 [NavChartDb](INFO): Creating index for Contour 5p4
2012-05-22T22:25:15.667Z,1337725515.667 [NavChartDb](INFO): Creating index for Contour 9p1
2012-05-22T22:25:15.669Z,1337725515.669 [NavChartDb](INFO): Creating index for Contour 10p9
2012-05-22T22:25:15.671Z,1337725515.671 [NavChartDb](INFO): Creating index for Contour 18p2
2012-05-22T22:25:15.673Z,1337725515.673 [NavChartDb](INFO): Creating index for Contour 36p5
2012-05-22T22:25:15.675Z,1337725515.675 [NavChartDb](INFO): Creating index for Contour 54p8
2012-05-22T22:25:15.677Z,1337725515.677 [NavChartDb](INFO): Creating index for Contour 73p1
2012-05-22T22:25:15.679Z,1337725515.679 [NavChartDb](INFO): Creating index for Contour 91p4
2012-05-22T22:25:15.681Z,1337725515.681 [NavChartDb](INFO): Creating index for Contour 182p8
2012-05-22T22:25:15.683Z,1337725515.683 [NavChartDb](INFO): Creating index for Contour 365p7
2012-05-22T22:25:15.685Z,1337725515.685 [NavChartDb](INFO): Creating index for Contour 548p6
2012-05-22T22:25:15.687Z,1337725515.687 [NavChartDb](INFO): Creating index for Contour 731p5
2012-05-22T22:25:15.689Z,1337725515.689 [NavChartDb](INFO): Creating index for Contour 914p4
2012-05-22T22:25:15.691Z,1337725515.691 [NavChartDb](INFO): Creating index for Contour 1097p2
2012-05-22T22:25:15.693Z,1337725515.693 [NavChartDb](INFO): Creating index for Contour 1280p1
2012-05-22T22:25:15.695Z,1337725515.695 [NavChartDb](INFO): Creating index for Contour 1463p0
2012-05-22T22:25:15.697Z,1337725515.697 [NavChartDb](INFO): Creating index for Contour 1645p9
2012-05-22T22:25:15.699Z,1337725515.699 [NavChartDb](INFO): Creating index for Contour 1828p8
2012-05-22T22:25:15.701Z,1337725515.701 [NavChartDb](INFO): Creating index for Contour 2011p6
2012-05-22T22:25:15.702Z,1337725515.703 [NavChartDb](INFO): Creating index for Contour 2194p5
2012-05-22T22:25:15.704Z,1337725515.704 [NavChartDb](INFO): Creating index for Contour 2377p4
2012-05-22T22:25:15.706Z,1337725515.706 [NavChartDb](INFO): Creating index for Contour 2560p3
2012-05-22T22:25:15.708Z,1337725515.708 [NavChartDb](INFO): Creating index for Contour 2743p2
2012-05-22T22:25:15.710Z,1337725515.710 [NavChartDb](INFO): Creating index for Contour 2926p0
2012-05-22T22:25:15.712Z,1337725515.712 [NavChartDb](INFO): Creating index for Contour 3108p9
2012-05-22T22:25:15.714Z,1337725515.714 [NavChartDb](INFO): Creating index for Contour 3291p8
2012-05-22T22:25:15.716Z,1337725515.716 [NavChartDb](INFO): Creating index for Contour 3474p7
2012-05-22T22:25:15.718Z,1337725515.718 [NavChartDb](INFO): Creating index for Contour 3657p6
2012-05-22T22:25:17.307Z,1337725517.307 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2012-05-22T22:25:17.312Z,1337725517.312 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2012-05-22T22:25:17.322Z,1337725517.322 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-22T22:25:17.332Z,1337725517.332 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface RunIn="Progression"/>

    <Aggregate Id="StartupSatComms" >

        <ReadDatum>
            <Timeout Duration="P1M" /><Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" /><Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2012-05-22T22:25:17.332Z,1337725517.332 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2012-05-22T22:25:17.359Z,1337725517.359 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-05-22T22:25:17.361Z,1337725517.362 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-05-22T22:25:17.365Z,1337725517.365 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-22T22:25:17.372Z,1337725517.372 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-05-22T22:25:17.375Z,1337725517.375 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-22T22:25:17.382Z,1337725517.382 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-05-22T22:25:17.390Z,1337725517.390 [Default:D.SetSpeed](DEBUG): Construct.
2012-05-22T22:25:17.393Z,1337725517.393 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-22T22:25:17.398Z,1337725517.398 [Default:F.Wait](DEBUG): Construct Wait.
2012-05-22T22:25:17.401Z,1337725517.401 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="NeedGPS"><True/></DefineArg>

    <Aggregate Id="GPS" >

        <When><Arg Name="NeedGPS"/>
        </When>

<!-- Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

        <ReadDatum Id="Read_GPS"><Universal:latitude_fix/>
        </ReadDatum>

        <Assign><Arg Name="NeedGPS"/><False/></Assign>

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute RunIn="Sequence">
                    <Setting>
                    <Guidance:Execute.command/><String>Burn 300</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped drop weight due to communications timeout</Syslog>
            </Timeout><Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

    <Aggregate Id="CallIridium" >

        <When>
            <Elapsed><Universal:platform_communications/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
            <And><Universal:platform_conversation/><Eq><True/></Eq></And>
            <Or>
            <Elapsed><Universal:platform_communications/></Elapsed><Gt><Units:hour/><Value>1.0</Value></Gt></Or>
        </When>

        <Assign><Arg Name="NeedGPS"/><True/></Assign>

        <Call RefId="Iridium"/>

    </Aggregate>

<!-- Wait forever at the surface-->

<!-- Run at low speed -->

    <Guidance:SetSpeed RunIn="Parallel">
        <Setting><Guidance:SetSpeed.period/><Units:second/><Value>5</Value></Setting>
    </Guidance:SetSpeed>

    <Guidance:GoToSurface RunIn="Parallel"/>

    <Guidance:Wait RunIn="Sequence"></Guidance:Wait>

</Mission>


2012-05-22T22:25:17.406Z,1337725517.406 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-05-22T22:25:17.425Z,1337725517.425 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-05-22T22:25:17.536Z,1337725517.536 [DVL_micro](DEBUG): Initializing DVL_micro.
2012-05-22T22:25:17.559Z,1337725517.559 [Radio_Freewave](INFO): Powering up
2012-05-22T22:25:17.565Z,1337725517.565 [WetLabsBB2FL](INFO): Powering down
2012-05-22T22:25:17.632Z,1337725517.632 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2012-05-22T22:25:17.633Z,1337725517.633 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2012-05-22T22:25:17.642Z,1337725517.642 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-05-22T22:25:17.643Z,1337725517.644 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-05-22T22:25:17.653Z,1337725517.653 [MassServo](DEBUG): Initializing EZServoServo.
2012-05-22T22:25:17.654Z,1337725517.654 [MassServo](DEBUG): Initializing MassServo.
2012-05-22T22:25:17.663Z,1337725517.662 [RudderServo](DEBUG): Initializing EZServoServo.
2012-05-22T22:25:17.664Z,1337725517.664 [RudderServo](DEBUG): Initializing RudderServo.
2012-05-22T22:25:17.671Z,1337725517.671 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-05-22T22:25:17.672Z,1337725517.672 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-05-22T22:25:20.628Z,1337725520.628 [NAL9601](INFO): Powering up NAL9601
2012-05-22T22:25:31.038Z,1337725531.038 [SBIT](IMPORTANT): Beginning Startup BIT
2012-05-22T22:25:31.040Z,1337725531.040 [CBIT](IMPORTANT): Beginning GF scan
2012-05-22T22:25:57.156Z,1337725557.156 [CBIT](IMPORTANT): No ground fault detected
2012-05-22T22:26:13.105Z,1337725573.105 [SBIT](IMPORTANT): SBIT PASSED
2012-05-22T22:26:13.500Z,1337725573.500 [MissionManager](IMPORTANT): Started mission Startup
2012-05-22T22:26:13.500Z,1337725573.500 [Startup] Running Loop=1
2012-05-22T22:26:13.500Z,1337725573.500 [Startup](INFO): Aggregate::initialize Startup
2012-05-22T22:26:13.500Z,1337725573.500 [Startup:A.GoToSurface] Running Loop=1
2012-05-22T22:26:13.500Z,1337725573.500 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-22T22:26:13.507Z,1337725573.507 [Startup:StartupSatComms] Running Loop=1
2012-05-22T22:26:13.507Z,1337725573.507 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2012-05-22T22:26:13.507Z,1337725573.507 [Startup:StartupSatComms:A] Running Loop=1
2012-05-22T22:26:13.900Z,1337725573.900 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-05-22T22:26:26.371Z,1337725586.371 [NAL9601](INFO): NAL9601 initialized
2012-05-22T22:27:13.900Z,1337725633.900 [Startup:StartupSatComms:A](INFO): Timed out from 2012-05-22T22:26:13.5Z
2012-05-22T22:27:13.900Z,1337725633.901 [Startup:StartupSatComms:A_Timeout] Running Loop=1
2012-05-22T22:27:13.901Z,1337725633.901 [Startup:StartupSatComms:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A_Timeout
2012-05-22T22:27:13.901Z,1337725633.901 [Startup:StartupSatComms:A_Timeout](INFO): Completed Startup:StartupSatComms:A_Timeout
2012-05-22T22:27:13.901Z,1337725633.901 [Startup:StartupSatComms:A] Stopped
2012-05-22T22:27:13.901Z,1337725633.901 [Startup:StartupSatComms:B] Running Loop=1
2012-05-22T22:27:14.315Z,1337725634.315 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-05-22T22:28:14.153Z,1337725694.153 [Startup:StartupSatComms:B](INFO): Timed out from 2012-05-22T22:27:13.9Z
2012-05-22T22:28:14.153Z,1337725694.153 [Startup:StartupSatComms:A_Timeout] Running Loop=1
2012-05-22T22:28:14.153Z,1337725694.153 [Startup:StartupSatComms:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A_Timeout
2012-05-22T22:28:14.153Z,1337725694.153 [Startup:StartupSatComms:A_Timeout](INFO): Completed Startup:StartupSatComms:A_Timeout
2012-05-22T22:28:14.153Z,1337725694.153 [Startup:StartupSatComms:B] Stopped
2012-05-22T22:28:14.154Z,1337725694.154 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2012-05-22T22:28:14.154Z,1337725694.154 [Startup:StartupSatComms] Stopped
2012-05-22T22:28:14.154Z,1337725694.154 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2012-05-22T22:28:14.155Z,1337725694.155 [Startup](INFO): Completed Startup
2012-05-22T22:28:14.155Z,1337725694.155 [Startup] Stopped
2012-05-22T22:28:14.155Z,1337725694.155 [Startup](INFO): Aggregate::uninitialize Startup
2012-05-22T22:28:14.155Z,1337725694.155 [Startup:A.GoToSurface] Stopped
2012-05-22T22:28:14.155Z,1337725694.155 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-22T22:28:14.551Z,1337725694.551 [MissionManager](IMPORTANT): Started mission Default
2012-05-22T22:28:14.551Z,1337725694.551 [Default] Running Loop=1
2012-05-22T22:28:14.551Z,1337725694.551 [Default](INFO): Aggregate::initialize Default
2012-05-22T22:28:14.551Z,1337725694.551 [Default:D.SetSpeed] Running Loop=1
2012-05-22T22:28:14.552Z,1337725694.552 [Default:D.SetSpeed](DEBUG): Initialize.
2012-05-22T22:28:14.552Z,1337725694.552 [Default:E.GoToSurface] Running Loop=1
2012-05-22T22:28:14.552Z,1337725694.552 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-22T22:28:14.552Z,1337725694.552 [Default:Iridium] Running Loop=1
2012-05-22T22:28:14.552Z,1337725694.552 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-05-22T22:28:14.552Z,1337725694.552 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-22T22:28:14.553Z,1337725694.553 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-05-22T22:28:14.553Z,1337725694.553 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-05-22T22:28:14.553Z,1337725694.553 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-22T22:28:14.553Z,1337725694.553 [Default:E.GoToSurface] Running Loop=1
2012-05-22T22:28:14.558Z,1337725694.559 [Default:D.SetSpeed] Running Loop=1
2012-05-22T22:28:14.563Z,1337725694.563 [Default:CallIridium] Running Loop=1
2012-05-22T22:28:14.563Z,1337725694.563 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-05-22T22:28:14.564Z,1337725694.564 [Default:CallIridium:A] Running Loop=1
2012-05-22T22:28:14.565Z,1337725694.565 [Default:CallIridium:A] Stopped
2012-05-22T22:28:14.565Z,1337725694.565 [Default:CallIridium:B] Running Loop=1
2012-05-22T22:28:14.565Z,1337725694.565 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-05-22T22:28:14.571Z,1337725694.570 [Default:Iridium:B.GoToSurface] Stopped
2012-05-22T22:28:14.571Z,1337725694.571 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-22T22:28:14.571Z,1337725694.571 [Default:Iridium:Read_Iridium] Running Loop=1
2012-05-22T22:28:14.571Z,1337725694.571 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-22T22:28:14.575Z,1337725694.575 [Default:GPS] Running Loop=1
2012-05-22T22:28:14.576Z,1337725694.576 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-05-22T22:28:14.576Z,1337725694.576 [Default:GPS:A.SetSpeed] Running Loop=1
2012-05-22T22:28:14.576Z,1337725694.576 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-05-22T22:28:14.576Z,1337725694.576 [Default:GPS:B.GoToSurface] Running Loop=1
2012-05-22T22:28:14.576Z,1337725694.576 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-22T22:28:14.581Z,1337725694.581 [Default:GPS:B.GoToSurface] Stopped
2012-05-22T22:28:14.581Z,1337725694.582 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-22T22:28:14.582Z,1337725694.582 [Default:GPS:Read_GPS] Running Loop=1
2012-05-22T22:28:14.582Z,1337725694.582 [Default:GPS:A.SetSpeed] Running Loop=1
2012-05-22T22:28:14.959Z,1337725694.959 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-05-22T22:28:14.961Z,1337725694.961 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-05-22T22:29:28.443Z,1337725768.443 [CommandLine](IMPORTANT): got command show variable elevator
2012-05-22T22:29:28.448Z,1337725768.448 [CommandLine](INFO): platform_elevator_angle (radian)
2012-05-22T22:29:28.454Z,1337725768.454 [CommandLine](INFO): Config/vehicle.ElevatorServo_card (none)
2012-05-22T22:29:28.455Z,1337725768.455 [CommandLine](INFO): Config/vehicle.ElevatorServo_uart (none)
2012-05-22T22:29:28.455Z,1337725768.455 [CommandLine](INFO): Config/vehicle.ElevatorServo_baud (bit_per_second)
2012-05-22T22:29:28.471Z,1337725768.471 [CommandLine](INFO): Config/Servo.ElevatorServo_enable (bool)
2012-05-22T22:29:28.471Z,1337725768.471 [CommandLine](INFO): Config/Servo.ElevatorServo_useHardware (bool)
2012-05-22T22:29:28.471Z,1337725768.471 [CommandLine](INFO): Config/Servo.ElevatorServo_offset (degree)
2012-05-22T22:29:28.479Z,1337725768.479 [CommandLine](INFO): VerticalControl.kdPitchElevator (second)
2012-05-22T22:29:28.480Z,1337725768.480 [CommandLine](INFO): VerticalControl.kiPitchElevator (reciprocal_second)
2012-05-22T22:29:28.480Z,1337725768.480 [CommandLine](INFO): VerticalControl.kpPitchElevator (none)
2012-05-22T22:29:28.482Z,1337725768.482 [CommandLine](INFO): VerticalControl.maxPitchElevatorInt (angular_degree)
2012-05-22T22:29:28.489Z,1337725768.489 [CommandLine](INFO): VerticalControl.elevatorAngleAction (radian)
2012-05-22T22:29:28.490Z,1337725768.490 [CommandLine](INFO): InternalSim.platform_elevator_angle (degree)
2012-05-22T22:29:28.491Z,1337725768.490 [CommandLine](INFO): VerticalControl.elevatorAngleCmd (radian)
2012-05-22T22:29:28.493Z,1337725768.493 [CommandLine](INFO): ElevatorServo.platform_elevator_angle (radian)
2012-05-22T22:29:28.495Z,1337725768.495 [CommandLine](INFO): VerticalControl.elevatorIntegralInternal (radian)
2012-05-22T22:29:28.506Z,1337725768.506 [CommandLine](INFO): ElevatorServo.durationOfLastRun (second)
2012-05-22T22:29:28.507Z,1337725768.507 [CommandLine](INFO): ElevatorServo.component_voltage (volt)
2012-05-22T22:29:28.508Z,1337725768.508 [CommandLine](INFO): ElevatorServo.component_avgVoltage (volt)
2012-05-22T22:29:28.508Z,1337725768.508 [CommandLine](INFO): ElevatorServo.component_current (milliampere)
2012-05-22T22:29:28.508Z,1337725768.509 [CommandLine](INFO): ElevatorServo.component_avgCurrent (milliampere)
2012-05-22T22:30:03.512Z,1337725803.512 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 0.000000 degree
2012-05-22T22:30:03.513Z,1337725803.513 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2012-05-22T22:30:03.881Z,1337725803.881 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-05-22T22:30:14.118Z,1337725814.118 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 5.000000 
2012-05-22T22:30:14.118Z,1337725814.118 [CommandLine](FAULT): Incomplete syntax. Try: help maintain
2012-05-22T22:30:20.382Z,1337725820.382 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 5.000000 degree
2012-05-22T22:30:23.702Z,1337725823.703 [ElevatorServo](FAULT): Overload Error
2012-05-22T22:30:23.703Z,1337725823.703 [ElevatorServo] Hardware Fault, FailCount= 1
2012-05-22T22:30:23.703Z,1337725823.703 [ElevatorServo](ERROR): Hardware Fault
2012-05-22T22:30:23.730Z,1337725823.730 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2012-05-22T22:30:24.152Z,1337725824.152 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-05-22T22:30:24.152Z,1337725824.152 [ElevatorServo](INFO): Powering down
2012-05-22T22:30:24.155Z,1337725824.155 [CBIT](INFO): Clearing failed state for component ElevatorServo
2012-05-22T22:30:24.155Z,1337725824.155 [ElevatorServo] No Fault, FailCount= 1
2012-05-22T22:30:25.333Z,1337725825.333 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-05-22T22:30:25.452Z,1337725825.452 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-05-22T22:30:30.167Z,1337725830.167 [ElevatorServo](FAULT): Overload Error
2012-05-22T22:30:30.167Z,1337725830.167 [ElevatorServo] Hardware Fault, FailCount= 2
2012-05-22T22:30:30.167Z,1337725830.167 [ElevatorServo](ERROR): Hardware Fault
2012-05-22T22:30:30.197Z,1337725830.197 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2012-05-22T22:30:30.548Z,1337725830.548 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-05-22T22:30:30.548Z,1337725830.548 [ElevatorServo](INFO): Powering down
2012-05-22T22:30:30.552Z,1337725830.552 [CBIT](INFO): Clearing failed state for component ElevatorServo
2012-05-22T22:30:30.552Z,1337725830.552 [ElevatorServo] No Fault, FailCount= 2
2012-05-22T22:30:31.343Z,1337725831.343 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-05-22T22:30:31.463Z,1337725831.463 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-05-22T22:30:36.167Z,1337725836.167 [ElevatorServo](FAULT): Overload Error
2012-05-22T22:30:36.167Z,1337725836.167 [ElevatorServo] Hardware Fault, FailCount= 3
2012-05-22T22:30:36.167Z,1337725836.167 [ElevatorServo](ERROR): Hardware Fault
2012-05-22T22:30:36.194Z,1337725836.194 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2012-05-22T22:30:36.194Z,1337725836.194 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2012-05-22T22:30:36.548Z,1337725836.548 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-05-22T22:30:36.548Z,1337725836.548 [ElevatorServo](INFO): Powering down
2012-05-22T22:31:18.962Z,1337725878.962 [NAL9601](INFO): SBD MO Status=2, MOMSN=6758, MT Status=2, MTMSN=0
2012-05-22T22:31:18.962Z,1337725878.962 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-22T22:32:12.954Z,1337725932.954 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 0.000000 degree
2012-05-22T22:32:53.723Z,1337725973.723 [CommandLine](IMPORTANT): got command report mod platform_elevator_angle
2012-05-22T22:32:54.369Z,1337725974.369 [Reporter](INFO): platform_elevator_angle 0.000000 arcdeg
2012-05-22T22:33:01.373Z,1337725981.373 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 5.000000 degree
2012-05-22T22:33:02.052Z,1337725982.052 [Reporter](INFO): platform_elevator_angle 4.550250 arcdeg
2012-05-22T22:33:02.451Z,1337725982.451 [Reporter](INFO): platform_elevator_angle 5.000000 arcdeg
2012-05-22T22:33:24.438Z,1337726004.438 [CommandLine](IMPORTANT): got command show variable elevator
2012-05-22T22:33:24.441Z,1337726004.441 [CommandLine](INFO): platform_elevator_angle (degree)
2012-05-22T22:33:24.447Z,1337726004.447 [CommandLine](INFO): Config/vehicle.ElevatorServo_card (none)
2012-05-22T22:33:24.448Z,1337726004.448 [CommandLine](INFO): Config/vehicle.ElevatorServo_uart (none)
2012-05-22T22:33:24.448Z,1337726004.448 [CommandLine](INFO): Config/vehicle.ElevatorServo_baud (bit_per_second)
2012-05-22T22:33:24.465Z,1337726004.465 [CommandLine](INFO): Config/Servo.ElevatorServo_enable (bool)
2012-05-22T22:33:24.465Z,1337726004.465 [CommandLine](INFO): Config/Servo.ElevatorServo_useHardware (bool)
2012-05-22T22:33:24.465Z,1337726004.465 [CommandLine](INFO): Config/Servo.ElevatorServo_offset (degree)
2012-05-22T22:33:24.473Z,1337726004.473 [CommandLine](INFO): VerticalControl.kdPitchElevator (second)
2012-05-22T22:33:24.474Z,1337726004.474 [CommandLine](INFO): VerticalControl.kiPitchElevator (reciprocal_second)
2012-05-22T22:33:24.475Z,1337726004.474 [CommandLine](INFO): VerticalControl.kpPitchElevator (none)
2012-05-22T22:33:24.476Z,1337726004.476 [CommandLine](INFO): VerticalControl.maxPitchElevatorInt (angular_degree)
2012-05-22T22:33:24.483Z,1337726004.483 [CommandLine](INFO): VerticalControl.elevatorAngleAction (radian)
2012-05-22T22:33:24.484Z,1337726004.484 [CommandLine](INFO): InternalSim.platform_elevator_angle (degree)
2012-05-22T22:33:24.484Z,1337726004.484 [CommandLine](INFO): VerticalControl.elevatorAngleCmd (radian)
2012-05-22T22:33:24.487Z,1337726004.487 [CommandLine](INFO): ElevatorServo.platform_elevator_angle (radian)
2012-05-22T22:33:24.489Z,1337726004.489 [CommandLine](INFO): VerticalControl.elevatorIntegralInternal (radian)
2012-05-22T22:33:24.500Z,1337726004.500 [CommandLine](INFO): ElevatorServo.durationOfLastRun (second)
2012-05-22T22:33:24.502Z,1337726004.501 [CommandLine](INFO): ElevatorServo.component_voltage (volt)
2012-05-22T22:33:24.502Z,1337726004.502 [CommandLine](INFO): ElevatorServo.component_avgVoltage (volt)
2012-05-22T22:33:24.502Z,1337726004.502 [CommandLine](INFO): ElevatorServo.component_current (milliampere)
2012-05-22T22:33:24.503Z,1337726004.503 [CommandLine](INFO): ElevatorServo.component_avgCurrent (milliampere)
2012-05-22T22:33:24.505Z,1337726004.505 [CommandLine](INFO): Maintain_VerticalControl.elevatorAngleAction.durationOfLastRun (second)
2012-05-22T22:36:28.988Z,1337726188.988 [NAL9601](FAULT): GPS failed to acquire within timeout.
2012-05-22T22:36:28.988Z,1337726188.988 [NAL9601] Data Fault, FailCount= 1
2012-05-22T22:36:28.988Z,1337726188.988 [NAL9601](ERROR): Data Fault
2012-05-22T22:36:29.010Z,1337726189.010 [CBIT](ERROR): Data Fault in component: NAL9601
2012-05-22T22:36:29.388Z,1337726189.388 [NAL9601](INFO): Powering down
2012-05-22T22:36:29.410Z,1337726189.410 [CBIT](INFO): Clearing failed state for component NAL9601
2012-05-22T22:36:29.410Z,1337726189.411 [NAL9601] No Fault, FailCount= 1
2012-05-22T22:36:34.590Z,1337726194.590 [NAL9601](INFO): Powering up NAL9601
2012-05-22T22:37:40.223Z,1337726260.223 [NAL9601](INFO): NAL9601 initialized
2012-05-22T22:41:57.228Z,1337726517.228 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -10.000000 degree
2012-05-22T22:41:57.804Z,1337726517.804 [Reporter](INFO): platform_elevator_angle 0.560750 arcdeg
2012-05-22T22:41:58.206Z,1337726518.206 [Reporter](INFO): platform_elevator_angle -2.470000 arcdeg
2012-05-22T22:41:58.618Z,1337726518.618 [Reporter](INFO): platform_elevator_angle -5.470000 arcdeg
2012-05-22T22:41:59.004Z,1337726519.004 [Reporter](INFO): platform_elevator_angle -8.469250 arcdeg
2012-05-22T22:41:59.403Z,1337726519.403 [Reporter](INFO): platform_elevator_angle -10.000000 arcdeg
2012-05-22T22:45:36.448Z,1337726736.448 [CBIT](INFO): Clearing failed count for component ElevatorServo
2012-05-22T22:45:36.448Z,1337726736.448 [ElevatorServo] No Fault, FailCount= 3
2012-05-22T22:45:37.236Z,1337726737.236 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-05-22T22:45:37.356Z,1337726737.355 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-05-22T22:45:40.257Z,1337726740.257 [Reporter](INFO): platform_elevator_angle -0.003416 rad
2012-05-22T22:45:40.736Z,1337726740.736 [Reporter](INFO): platform_elevator_angle -0.003455 rad
2012-05-22T22:45:41.075Z,1337726741.075 [Reporter](INFO): platform_elevator_angle -0.003416 rad
2012-05-22T22:45:42.275Z,1337726742.274 [ElevatorServo](FAULT): Overload Error
2012-05-22T22:45:42.275Z,1337726742.275 [ElevatorServo] Hardware Fault, FailCount= 1
2012-05-22T22:45:42.275Z,1337726742.275 [ElevatorServo](ERROR): Hardware Fault
2012-05-22T22:45:42.302Z,1337726742.302 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2012-05-22T22:45:42.303Z,1337726742.303 [Reporter](INFO): platform_elevator_angle -10.000000 arcdeg
2012-05-22T22:45:42.656Z,1337726742.656 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-05-22T22:45:42.656Z,1337726742.657 [ElevatorServo](INFO): Powering down
2012-05-22T22:45:42.660Z,1337726742.660 [CBIT](INFO): Clearing failed state for component ElevatorServo
2012-05-22T22:45:42.660Z,1337726742.660 [ElevatorServo] No Fault, FailCount= 1
2012-05-22T22:45:43.662Z,1337726743.662 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-05-22T22:45:43.779Z,1337726743.779 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-05-22T22:45:46.471Z,1337726746.471 [Reporter](INFO): platform_elevator_angle -0.003570 rad
2012-05-22T22:45:46.903Z,1337726746.903 [Reporter](INFO): platform_elevator_angle -0.003416 rad
2012-05-22T22:45:47.780Z,1337726747.780 [Reporter](INFO): platform_elevator_angle -0.003493 rad
2012-05-22T22:45:47.895Z,1337726747.895 [Reporter](INFO): platform_elevator_angle -0.003416 rad
2012-05-22T22:45:48.282Z,1337726748.282 [ElevatorServo](FAULT): Overload Error
2012-05-22T22:45:48.283Z,1337726748.283 [ElevatorServo] Hardware Fault, FailCount= 2
2012-05-22T22:45:48.283Z,1337726748.283 [ElevatorServo](ERROR): Hardware Fault
2012-05-22T22:45:48.310Z,1337726748.310 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2012-05-22T22:45:48.311Z,1337726748.311 [Reporter](INFO): platform_elevator_angle -10.000000 arcdeg
2012-05-22T22:45:48.664Z,1337726748.664 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-05-22T22:45:48.665Z,1337726748.665 [ElevatorServo](INFO): Powering down
2012-05-22T22:45:48.668Z,1337726748.668 [CBIT](INFO): Clearing failed state for component ElevatorServo
2012-05-22T22:45:48.668Z,1337726748.668 [ElevatorServo] No Fault, FailCount= 2
2012-05-22T22:45:49.477Z,1337726749.477 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-05-22T22:45:49.595Z,1337726749.595 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-05-22T22:45:52.492Z,1337726752.492 [Reporter](INFO): platform_elevator_angle -0.003416 rad
2012-05-22T22:45:53.715Z,1337726753.715 [Reporter](INFO): platform_elevator_angle -0.003455 rad
2012-05-22T22:45:54.119Z,1337726754.119 [Reporter](INFO): platform_elevator_angle -0.003493 rad
2012-05-22T22:45:54.513Z,1337726754.513 [ElevatorServo](FAULT): Overload Error
2012-05-22T22:45:54.513Z,1337726754.513 [ElevatorServo] Hardware Fault, FailCount= 3
2012-05-22T22:45:54.513Z,1337726754.513 [ElevatorServo](ERROR): Hardware Fault
2012-05-22T22:45:54.538Z,1337726754.538 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2012-05-22T22:45:54.539Z,1337726754.539 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2012-05-22T22:45:54.540Z,1337726754.540 [Reporter](INFO): platform_elevator_angle -10.000000 arcdeg
2012-05-22T22:45:55.389Z,1337726755.389 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-05-22T22:45:55.389Z,1337726755.389 [ElevatorServo](INFO): Powering down
2012-05-22T22:46:12.237Z,1337726772.237 [CommandLine](IMPORTANT): got command maintain clear
2012-05-22T22:46:12.296Z,1337726772.296 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-05-22T22:46:13.043Z,1337726773.043 [Reporter](INFO): platform_elevator_angle -6.999250 arcdeg
2012-05-22T22:46:13.438Z,1337726773.438 [Reporter](INFO): platform_elevator_angle -4.000000 arcdeg
2012-05-22T22:46:14.344Z,1337726774.344 [Reporter](INFO): platform_elevator_angle -0.999999 arcdeg
2012-05-22T22:46:14.432Z,1337726774.432 [Reporter](INFO): platform_elevator_angle 0.000000 arcdeg
2012-05-22T22:47:44.087Z,1337726864.087 [NAL9601](FAULT): GPS failed to acquire within timeout.
2012-05-22T22:47:44.087Z,1337726864.087 [NAL9601] Data Fault, FailCount= 2
2012-05-22T22:47:44.087Z,1337726864.087 [NAL9601](ERROR): Data Fault
2012-05-22T22:47:44.108Z,1337726864.108 [CBIT](ERROR): Data Fault in component: NAL9601
2012-05-22T22:47:44.108Z,1337726864.108 [CBIT](CRITICAL): Data Fault in component: NAL9601
2012-05-22T22:47:44.447Z,1337726864.447 [NAL9601](INFO): Powering down
2012-05-22T22:48:26.624Z,1337726906.624 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -10.000000 degree
2012-05-22T22:48:26.625Z,1337726906.625 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2012-05-22T22:48:26.910Z,1337726906.910 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-05-22T22:48:27.675Z,1337726907.675 [Reporter](INFO): platform_elevator_angle -3.000750 arcdeg
2012-05-22T22:48:28.076Z,1337726908.076 [Reporter](INFO): platform_elevator_angle -6.001500 arcdeg
2012-05-22T22:48:28.488Z,1337726908.489 [Reporter](INFO): platform_elevator_angle -9.000751 arcdeg
2012-05-22T22:48:28.883Z,1337726908.883 [Reporter](INFO): platform_elevator_angle -10.000000 arcdeg
2012-05-22T22:48:37.070Z,1337726917.070 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 5.000000 degree
2012-05-22T22:48:37.677Z,1337726917.677 [Reporter](INFO): platform_elevator_angle -6.999250 arcdeg
2012-05-22T22:48:38.089Z,1337726918.089 [Reporter](INFO): platform_elevator_angle -4.000000 arcdeg
2012-05-22T22:48:38.475Z,1337726918.475 [Reporter](INFO): platform_elevator_angle -0.999249 arcdeg
2012-05-22T22:48:38.879Z,1337726918.879 [Reporter](INFO): platform_elevator_angle 2.000751 arcdeg
2012-05-22T22:48:39.276Z,1337726919.276 [Reporter](INFO): platform_elevator_angle 5.000000 arcdeg
2012-05-22T22:48:51.174Z,1337726931.174 [CommandLine](IMPORTANT): got command maintain clear
2012-05-22T22:48:51.321Z,1337726931.321 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-05-22T22:48:52.076Z,1337726932.076 [Reporter](INFO): platform_elevator_angle 2.000000 arcdeg
2012-05-22T22:48:52.475Z,1337726932.475 [Reporter](INFO): platform_elevator_angle 0.000000 arcdeg
2012-05-22T22:49:04.294Z,1337726944.294 [CommandLine](IMPORTANT): got command help failComponent
2012-05-22T22:49:14.871Z,1337726954.871 [CommandLine](IMPORTANT): got command failComponent
2012-05-22T22:49:14.873Z,1337726954.874 [CommandLine](IMPORTANT): Failed components:
2012-05-22T22:49:14.874Z,1337726954.874 [CommandLine](IMPORTANT): ElevatorServo: Hardware Fault
2012-05-22T22:49:14.874Z,1337726954.874 [CommandLine](IMPORTANT): NAL9601: Data Fault
2012-05-22T22:49:40.192Z,1337726980.193 [CommandLine](IMPORTANT): got command failComponent none ElevatorServo
2012-05-22T22:49:40.193Z,1337726980.193 [ElevatorServo] No Fault, FailCount= 3
2012-05-22T22:49:40.193Z,1337726980.193 [CommandLine](IMPORTANT): ElevatorServo failureMode is No Fault
2012-05-22T22:49:40.874Z,1337726980.874 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-05-22T22:49:40.992Z,1337726980.992 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-05-22T22:49:43.676Z,1337726983.676 [Reporter](INFO): platform_elevator_angle -0.003455 rad
2012-05-22T22:49:44.122Z,1337726984.122 [CBIT](INFO): Clearing failed count for component NAL9601
2012-05-22T22:49:44.122Z,1337726984.122 [NAL9601] No Fault, FailCount= 2
2012-05-22T22:49:44.124Z,1337726984.124 [Reporter](INFO): platform_elevator_angle 0.294736 rad
2012-05-22T22:49:44.448Z,1337726984.448 [NAL9601](INFO): Powering up NAL9601
2012-05-22T22:49:44.495Z,1337726984.495 [Reporter](INFO): platform_elevator_angle 0.279151 rad
2012-05-22T22:49:44.899Z,1337726984.899 [Reporter](INFO): platform_elevator_angle 0.234161 rad
2012-05-22T22:49:45.299Z,1337726985.299 [Reporter](INFO): platform_elevator_angle 0.187367 rad
2012-05-22T22:49:45.695Z,1337726985.695 [Reporter](INFO): platform_elevator_angle 0.140381 rad
2012-05-22T22:49:46.115Z,1337726986.115 [Reporter](INFO): platform_elevator_angle 0.091284 rad
2012-05-22T22:49:46.499Z,1337726986.499 [Reporter](INFO): platform_elevator_angle 0.046333 rad
2012-05-22T22:49:46.963Z,1337726986.963 [Reporter](INFO): platform_elevator_angle 0.004031 rad
2012-05-22T22:49:47.356Z,1337726987.356 [Reporter](INFO): platform_elevator_angle -0.000345 rad
2012-05-22T22:49:59.021Z,1337726999.021 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 5.000000 degree
2012-05-22T22:49:59.022Z,1337726999.022 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2012-05-22T22:49:59.325Z,1337726999.325 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-05-22T22:50:00.927Z,1337727000.927 [Reporter](INFO): platform_elevator_angle 0.000269 rad
2012-05-22T22:50:01.299Z,1337727001.299 [Reporter](INFO): platform_elevator_angle 0.015662 rad
2012-05-22T22:50:01.699Z,1337727001.699 [Reporter](INFO): platform_elevator_angle 0.060536 rad
2012-05-22T22:50:02.099Z,1337727002.099 [Reporter](INFO): platform_elevator_angle 0.086870 rad
2012-05-22T22:50:12.712Z,1337727012.712 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -10.000000 degree
2012-05-22T22:50:14.123Z,1337727014.124 [Reporter](INFO): platform_elevator_angle 0.087523 rad
2012-05-22T22:50:14.499Z,1337727014.499 [Reporter](INFO): platform_elevator_angle 0.071515 rad
2012-05-22T22:50:14.899Z,1337727014.899 [Reporter](INFO): platform_elevator_angle 0.027293 rad
2012-05-22T22:50:15.299Z,1337727015.299 [Reporter](INFO): platform_elevator_angle -0.020767 rad
2012-05-22T22:50:15.712Z,1337727015.712 [Reporter](INFO): platform_elevator_angle -0.069097 rad
2012-05-22T22:50:16.099Z,1337727016.099 [Reporter](INFO): platform_elevator_angle -0.114278 rad
2012-05-22T22:50:16.499Z,1337727016.499 [Reporter](INFO): platform_elevator_angle -0.159345 rad
2012-05-22T22:50:16.919Z,1337727016.920 [Reporter](INFO): platform_elevator_angle -0.174776 rad
2012-05-22T22:50:29.135Z,1337727029.135 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 10.000000 degree
2012-05-22T22:50:30.531Z,1337727030.531 [Reporter](INFO): platform_elevator_angle -0.174700 rad
2012-05-22T22:50:30.899Z,1337727030.899 [Reporter](INFO): platform_elevator_angle -0.160036 rad
2012-05-22T22:50:31.303Z,1337727031.303 [Reporter](INFO): platform_elevator_angle -0.113971 rad
2012-05-22T22:50:31.699Z,1337727031.699 [Reporter](INFO): platform_elevator_angle -0.066947 rad
2012-05-22T22:50:32.112Z,1337727032.112 [Reporter](INFO): platform_elevator_angle -0.018771 rad
2012-05-22T22:50:32.499Z,1337727032.499 [Reporter](INFO): platform_elevator_angle 0.026525 rad
2012-05-22T22:50:32.899Z,1337727032.899 [Reporter](INFO): platform_elevator_angle 0.072782 rad
2012-05-22T22:50:33.332Z,1337727033.332 [Reporter](INFO): platform_elevator_angle 0.123760 rad
2012-05-22T22:50:33.703Z,1337727033.703 [Reporter](INFO): platform_elevator_angle 0.163644 rad
2012-05-22T22:50:34.131Z,1337727034.131 [Reporter](INFO): platform_elevator_angle 0.174700 rad
2012-05-22T22:50:48.939Z,1337727048.939 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15.000000 degree
2012-05-22T22:50:50.087Z,1337727050.087 [NAL9601](INFO): NAL9601 initialized
2012-05-22T22:50:50.899Z,1337727050.899 [Reporter](INFO): platform_elevator_angle 0.158308 rad
2012-05-22T22:50:51.315Z,1337727051.315 [Reporter](INFO): platform_elevator_angle 0.111476 rad
2012-05-22T22:50:51.699Z,1337727051.699 [Reporter](INFO): platform_elevator_angle 0.067216 rad
2012-05-22T22:50:52.099Z,1337727052.099 [Reporter](INFO): platform_elevator_angle 0.019347 rad
2012-05-22T22:50:52.499Z,1337727052.499 [Reporter](INFO): platform_elevator_angle -0.027370 rad
2012-05-22T22:50:53.400Z,1337727053.399 [Reporter](INFO): platform_elevator_angle -0.133587 rad
2012-05-22T22:50:53.518Z,1337727053.518 [Reporter](INFO): platform_elevator_angle -0.146523 rad
2012-05-22T22:50:53.948Z,1337727053.948 [Reporter](INFO): platform_elevator_angle -0.196849 rad
2012-05-22T22:50:54.312Z,1337727054.312 [Reporter](INFO): platform_elevator_angle -0.239919 rad
2012-05-22T22:50:54.727Z,1337727054.727 [Reporter](INFO): platform_elevator_angle -0.262606 rad
2012-05-22T22:51:22.315Z,1337727082.315 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15.000000 degree
2012-05-22T22:51:23.675Z,1337727083.675 [Reporter](INFO): platform_elevator_angle -0.262568 rad
2012-05-22T22:51:24.531Z,1337727084.532 [Reporter](INFO): platform_elevator_angle -0.192550 rad
2012-05-22T22:51:24.643Z,1337727084.643 [Reporter](INFO): platform_elevator_angle -0.178999 rad
2012-05-22T22:51:25.076Z,1337727085.076 [Reporter](INFO): platform_elevator_angle -0.128673 rad
2012-05-22T22:51:25.443Z,1337727085.443 [Reporter](INFO): platform_elevator_angle -0.085219 rad
2012-05-22T22:51:25.843Z,1337727085.843 [Reporter](INFO): platform_elevator_angle -0.038310 rad
2012-05-22T22:51:26.247Z,1337727086.247 [Reporter](INFO): platform_elevator_angle 0.008944 rad
2012-05-22T22:51:26.643Z,1337727086.643 [Reporter](INFO): platform_elevator_angle 0.055431 rad
2012-05-22T22:51:27.056Z,1337727087.056 [Reporter](INFO): platform_elevator_angle 0.104067 rad
2012-05-22T22:51:27.443Z,1337727087.443 [Reporter](INFO): platform_elevator_angle 0.148980 rad
2012-05-22T22:51:27.850Z,1337727087.850 [Reporter](INFO): platform_elevator_angle 0.196810 rad
2012-05-22T22:51:28.771Z,1337727088.771 [Reporter](INFO): platform_elevator_angle 0.262260 rad
2012-05-22T22:51:41.400Z,1337727101.400 [CommandLine](IMPORTANT): got command maintain clear
2012-05-22T22:51:41.483Z,1337727101.483 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-05-22T22:51:43.091Z,1337727103.092 [Reporter](INFO): platform_elevator_angle 0.262529 rad
2012-05-22T22:51:43.995Z,1337727103.995 [Reporter](INFO): platform_elevator_angle 0.187598 rad
2012-05-22T22:51:44.107Z,1337727104.107 [Reporter](INFO): platform_elevator_angle 0.173663 rad
2012-05-22T22:51:44.507Z,1337727104.507 [Reporter](INFO): platform_elevator_angle 0.126792 rad
2012-05-22T22:51:44.908Z,1337727104.908 [Reporter](INFO): platform_elevator_angle 0.079538 rad
2012-05-22T22:51:45.307Z,1337727105.307 [Reporter](INFO): platform_elevator_angle 0.032783 rad
2012-05-22T22:51:45.748Z,1337727105.747 [Reporter](INFO): platform_elevator_angle -0.000537 rad
2012-05-22T22:53:48.366Z,1337727228.366 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:53:52.378Z,1337727232.378 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:53:56.563Z,1337727236.563 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:54:01.137Z,1337727241.137 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:54:05.335Z,1337727245.335 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:54:09.536Z,1337727249.536 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:54:13.718Z,1337727253.718 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:54:17.590Z,1337727257.590 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:54:22.171Z,1337727262.171 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:54:26.354Z,1337727266.354 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:54:30.537Z,1337727270.537 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:54:34.775Z,1337727274.775 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:54:38.793Z,1337727278.793 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:54:42.987Z,1337727282.987 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-05-22T22:58:17.786Z,1337727497.786 [NAL9601](INFO): SBD MO Status=2, MOMSN=6758, MT Status=2, MTMSN=0
2012-05-22T22:58:17.786Z,1337727497.786 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-22T22:58:55.747Z,1337727535.747 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15.000000 degree
2012-05-22T22:58:55.747Z,1337727535.747 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2012-05-22T22:58:56.394Z,1337727536.394 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-05-22T22:58:57.699Z,1337727537.699 [Reporter](INFO): platform_elevator_angle 0.145257 rad
2012-05-22T22:58:58.079Z,1337727538.079 [Reporter](INFO): platform_elevator_angle 0.161571 rad
2012-05-22T22:58:58.479Z,1337727538.480 [Reporter](INFO): platform_elevator_angle 0.205755 rad
2012-05-22T22:58:58.883Z,1337727538.883 [Reporter](INFO): platform_elevator_angle 0.250245 rad
2012-05-22T22:58:59.307Z,1337727539.307 [Reporter](INFO): platform_elevator_angle 0.261915 rad
2012-05-22T22:59:05.668Z,1337727545.668 [NAL9601](INFO): SBD MO Status=2, MOMSN=6758, MT Status=2, MTMSN=0
2012-05-22T22:59:05.668Z,1337727545.668 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-22T22:59:14.443Z,1337727554.443 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15.000000 degree
2012-05-22T22:59:16.108Z,1337727556.108 [Reporter](INFO): platform_elevator_angle 0.249017 rad
2012-05-22T22:59:16.519Z,1337727556.519 [Reporter](INFO): platform_elevator_angle 0.202991 rad
2012-05-22T22:59:16.907Z,1337727556.907 [Reporter](INFO): platform_elevator_angle 0.157541 rad
2012-05-22T22:59:17.803Z,1337727557.803 [Reporter](INFO): platform_elevator_angle 0.053013 rad
2012-05-22T22:59:17.919Z,1337727557.919 [Reporter](INFO): platform_elevator_angle 0.038349 rad
2012-05-22T22:59:18.319Z,1337727558.319 [Reporter](INFO): platform_elevator_angle -0.008215 rad
2012-05-22T22:59:18.715Z,1337727558.715 [Reporter](INFO): platform_elevator_angle -0.054471 rad
2012-05-22T22:59:19.115Z,1337727559.115 [Reporter](INFO): platform_elevator_angle -0.101611 rad
2012-05-22T22:59:19.527Z,1337727559.527 [Reporter](INFO): platform_elevator_angle -0.150170 rad
2012-05-22T22:59:19.919Z,1337727559.919 [Reporter](INFO): platform_elevator_angle -0.195006 rad
2012-05-22T22:59:20.315Z,1337727560.315 [Reporter](INFO): platform_elevator_angle -0.241109 rad
2012-05-22T22:59:21.239Z,1337727561.239 [Reporter](INFO): platform_elevator_angle -0.261416 rad
2012-05-22T22:59:30.662Z,1337727570.662 [CommandLine](IMPORTANT): got command maintain clear
2012-05-22T22:59:30.929Z,1337727570.929 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-05-22T22:59:32.552Z,1337727572.552 [Reporter](INFO): platform_elevator_angle -0.261838 rad
2012-05-22T22:59:33.443Z,1337727573.443 [Reporter](INFO): platform_elevator_angle -0.185832 rad
2012-05-22T22:59:33.556Z,1337727573.556 [Reporter](INFO): platform_elevator_angle -0.174776 rad
2012-05-22T22:59:33.956Z,1337727573.956 [Reporter](INFO): platform_elevator_angle -0.128635 rad
2012-05-22T22:59:34.352Z,1337727574.352 [Reporter](INFO): platform_elevator_angle -0.081419 rad
2012-05-22T22:59:34.756Z,1337727574.756 [Reporter](INFO): platform_elevator_angle -0.034126 rad
2012-05-22T22:59:35.159Z,1337727575.159 [Reporter](INFO): platform_elevator_angle -0.000921 rad
2012-05-22T22:59:35.579Z,1337727575.579 [Reporter](INFO): platform_elevator_angle 0.000307 rad
2012-05-22T23:00:52.493Z,1337727652.493 [NAL9601](FAULT): GPS failed to acquire within timeout.
2012-05-22T23:00:52.493Z,1337727652.493 [NAL9601] Data Fault, FailCount= 1
2012-05-22T23:00:52.493Z,1337727652.493 [NAL9601](ERROR): Data Fault
2012-05-22T23:00:52.516Z,1337727652.516 [CBIT](ERROR): Data Fault in component: NAL9601
2012-05-22T23:00:52.927Z,1337727652.927 [NAL9601](INFO): Powering down
2012-05-22T23:00:52.948Z,1337727652.948 [CBIT](INFO): Clearing failed state for component NAL9601
2012-05-22T23:00:52.948Z,1337727652.948 [NAL9601] No Fault, FailCount= 1
2012-05-22T23:00:58.126Z,1337727658.126 [NAL9601](INFO): Powering up NAL9601
2012-05-22T23:02:03.763Z,1337727723.763 [NAL9601](INFO): NAL9601 initialized
2012-05-22T23:07:44.248Z,1337728064.248 [NAL9601](INFO): SBD MO Status=2, MOMSN=6758, MT Status=2, MTMSN=0
2012-05-22T23:07:44.248Z,1337728064.248 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-22T23:12:07.124Z,1337728327.124 [NAL9601](FAULT): GPS failed to acquire within timeout.
2012-05-22T23:12:07.124Z,1337728327.124 [NAL9601] Data Fault, FailCount= 2
2012-05-22T23:12:07.124Z,1337728327.125 [NAL9601](ERROR): Data Fault
2012-05-22T23:12:07.146Z,1337728327.145 [CBIT](ERROR): Data Fault in component: NAL9601
2012-05-22T23:12:07.146Z,1337728327.146 [CBIT](CRITICAL): Data Fault in component: NAL9601
2012-05-22T23:12:07.538Z,1337728327.538 [NAL9601](INFO): Powering down
2012-05-22T23:14:07.145Z,1337728447.145 [CBIT](INFO): Clearing failed count for component NAL9601
2012-05-22T23:14:07.145Z,1337728447.145 [NAL9601] No Fault, FailCount= 2
2012-05-22T23:14:07.530Z,1337728447.530 [NAL9601](INFO): Powering up NAL9601
2012-05-22T23:15:13.271Z,1337728513.271 [NAL9601](INFO): NAL9601 initialized
2012-05-22T23:24:56.668Z,1337729096.668 [CommandLine](IMPORTANT): got command quit
2012-05-22T23:24:56.702Z,1337729096.702 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-05-22T23:24:56.702Z,1337729096.703 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-05-22T23:24:56.859Z,1337729096.859 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-05-22T23:24:56.952Z,1337729096.952 [CTD_NeilBrown](INFO): Powering down
2012-05-22T23:24:56.953Z,1337729096.953 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-05-22T23:24:57.177Z,1337729097.177 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-05-22T23:24:57.178Z,1337729097.178 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-05-22T23:24:57.178Z,1337729097.178 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-05-22T23:24:57.528Z,1337729097.528 [controlThread](DEBUG): Uninitializing ControlThread
2012-05-22T23:24:57.529Z,1337729097.529 [AHRS_sp3003D](INFO): Powering down
2012-05-22T23:24:57.615Z,1337729097.615 [DVL_micro](INFO): Powering down
2012-05-22T23:24:57.616Z,1337729097.616 [NAL9601](INFO): Powering down
2012-05-22T23:24:57.617Z,1337729097.617 [WetLabsBB2FL](INFO): Powering down
2012-05-22T23:24:57.617Z,1337729097.617 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-05-22T23:24:57.618Z,1337729097.618 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-05-22T23:24:57.619Z,1337729097.619 [Default] Stopped
2012-05-22T23:24:57.619Z,1337729097.619 [Default](INFO): Aggregate::uninitialize Default
2012-05-22T23:24:57.619Z,1337729097.619 [Default:GPS] Stopped
2012-05-22T23:24:57.619Z,1337729097.619 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-05-22T23:24:57.619Z,1337729097.619 [Default:GPS:A.SetSpeed] Stopped
2012-05-22T23:24:57.619Z,1337729097.619 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-05-22T23:24:57.619Z,1337729097.619 [Default:GPS:Read_GPS] Stopped
2012-05-22T23:24:57.620Z,1337729097.620 [Default:Iridium] Stopped
2012-05-22T23:24:57.620Z,1337729097.620 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-05-22T23:24:57.620Z,1337729097.620 [Default:Iridium:A.SetSpeed] Stopped
2012-05-22T23:24:57.620Z,1337729097.620 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-05-22T23:24:57.620Z,1337729097.620 [Default:Iridium:Read_Iridium] Stopped
2012-05-22T23:24:57.620Z,1337729097.620 [Default:CallIridium] Stopped
2012-05-22T23:24:57.620Z,1337729097.620 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-05-22T23:24:57.620Z,1337729097.620 [Default:CallIridium:B] Stopped
2012-05-22T23:24:57.620Z,1337729097.620 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-05-22T23:24:57.620Z,1337729097.620 [Default:D.SetSpeed] Stopped
2012-05-22T23:24:57.620Z,1337729097.620 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-05-22T23:24:57.621Z,1337729097.621 [Default:E.GoToSurface] Stopped
2012-05-22T23:24:57.621Z,1337729097.621 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-22T23:24:57.625Z,1337729097.625 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-05-22T23:24:57.625Z,1337729097.625 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-05-22T23:24:57.625Z,1337729097.625 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-05-22T23:24:57.626Z,1337729097.626 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-05-22T23:24:57.626Z,1337729097.626 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-05-22T23:24:57.626Z,1337729097.626 [BuoyancyServo](INFO): Powering down
2012-05-22T23:24:57.627Z,1337729097.627 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-05-22T23:24:57.627Z,1337729097.627 [ElevatorServo](INFO): Powering down
2012-05-22T23:24:57.627Z,1337729097.627 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-05-22T23:24:57.627Z,1337729097.627 [MassServo](INFO): Powering down
2012-05-22T23:24:57.627Z,1337729097.628 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-05-22T23:24:57.628Z,1337729097.628 [RudderServo](INFO): Powering down
2012-05-22T23:24:57.628Z,1337729097.628 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-05-22T23:24:57.628Z,1337729097.628 [ThrusterServo](INFO): Powering down
2012-05-22T23:24:57.628Z,1337729097.628 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-05-22T23:24:57.629Z,1337729097.629 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-05-22T23:24:57.629Z,1337729097.629 [CBIT](DEBUG): Uninitialize CBIT Component.