2013-02-20T01:07:45.914Z,1361322465.914 [Supervisor](DEBUG): Initializing supervisor.
2013-02-20T01:07:45.917Z,1361322465.917 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-02-20T01:07:45.917Z,1361322465.917 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-02-20T01:07:45.918Z,1361322465.918 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-02-20T01:07:45.922Z,1361322465.922 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-02-20T01:07:45.933Z,1361322465.933 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-02-20T01:07:45.934Z,1361322465.934 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-02-20T01:07:45.934Z,1361322465.934 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-02-20T01:07:45.936Z,1361322465.935 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-02-20T01:07:45.936Z,1361322465.936 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-02-20T01:07:45.937Z,1361322465.937 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-02-20T01:07:46.212Z,1361322466.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-02-20T01:07:46.212Z,1361322466.212 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-02-20T01:07:46.398Z,1361322466.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-02-20T01:07:46.398Z,1361322466.398 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-02-20T01:07:46.482Z,1361322466.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-02-20T01:07:46.482Z,1361322466.482 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-02-20T01:07:46.675Z,1361322466.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-02-20T01:07:46.676Z,1361322466.676 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-02-20T01:07:46.808Z,1361322466.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-02-20T01:07:46.808Z,1361322466.808 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-02-20T01:07:47.037Z,1361322467.037 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-02-20T01:07:47.038Z,1361322467.037 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-02-20T01:07:47.206Z,1361322467.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-02-20T01:07:47.206Z,1361322467.206 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-02-20T01:07:47.453Z,1361322467.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-02-20T01:07:47.454Z,1361322467.454 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-02-20T01:07:47.551Z,1361322467.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-02-20T01:07:47.551Z,1361322467.551 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-02-20T01:07:47.946Z,1361322467.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-02-20T01:07:47.947Z,1361322467.947 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-02-20T01:07:48.060Z,1361322468.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-02-20T01:07:48.060Z,1361322468.060 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-02-20T01:07:48.145Z,1361322468.145 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-02-20T01:07:48.146Z,1361322468.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-02-20T01:07:48.246Z,1361322468.246 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-02-20T01:07:48.373Z,1361322468.374 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-02-20T01:07:48.458Z,1361322468.458 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-02-20T01:07:48.558Z,1361322468.559 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-02-20T01:07:48.657Z,1361322468.657 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-02-20T01:07:48.775Z,1361322468.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-02-20T01:07:48.867Z,1361322468.867 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-02-20T01:07:48.952Z,1361322468.952 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-02-20T01:07:48.981Z,1361322468.981 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-02-20T01:07:49.133Z,1361322469.133 [InternalSim] Loaded
2013-02-20T01:07:49.133Z,1361322469.133 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-02-20T01:07:49.134Z,1361322469.134 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-02-20T01:07:49.135Z,1361322469.135 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-02-20T01:07:49.191Z,1361322469.191 [SBIT](DEBUG): Construct Startup Built In Test.
2013-02-20T01:07:49.220Z,1361322469.220 [SBIT] Loaded
2013-02-20T01:07:49.220Z,1361322469.220 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-02-20T01:07:49.221Z,1361322469.221 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-02-20T01:07:49.250Z,1361322469.250 [IBIT] Loaded
2013-02-20T01:07:49.250Z,1361322469.250 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-02-20T01:07:49.254Z,1361322469.253 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-02-20T01:07:49.373Z,1361322469.373 [CBIT] Loaded
2013-02-20T01:07:49.373Z,1361322469.374 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-02-20T01:07:49.374Z,1361322469.374 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-02-20T01:07:49.375Z,1361322469.375 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-02-20T01:07:49.524Z,1361322469.524 [BuoyancyServo] Loaded
2013-02-20T01:07:49.524Z,1361322469.524 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-02-20T01:07:49.537Z,1361322469.537 [ElevatorServo] Loaded
2013-02-20T01:07:49.537Z,1361322469.537 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-02-20T01:07:49.549Z,1361322469.549 [MassServo] Loaded
2013-02-20T01:07:49.549Z,1361322469.549 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-02-20T01:07:49.561Z,1361322469.561 [RudderServo] Loaded
2013-02-20T01:07:49.562Z,1361322469.562 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-02-20T01:07:49.573Z,1361322469.573 [ThrusterServo] Loaded
2013-02-20T01:07:49.574Z,1361322469.574 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-02-20T01:07:49.574Z,1361322469.574 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-02-20T01:07:49.575Z,1361322469.575 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-02-20T01:07:49.592Z,1361322469.592 [DepthRateCalculator] Loaded
2013-02-20T01:07:49.592Z,1361322469.592 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-02-20T01:07:52.932Z,1361322472.932 [HFRadarModelCalc] Loaded
2013-02-20T01:07:52.932Z,1361322472.932 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-02-20T01:07:52.948Z,1361322472.948 [NavChart] Loaded
2013-02-20T01:07:52.948Z,1361322472.948 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-02-20T01:07:52.954Z,1361322472.954 [PitchRateCalculator] Loaded
2013-02-20T01:07:52.955Z,1361322472.955 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-02-20T01:07:52.961Z,1361322472.961 [SpeedCalculator] Loaded
2013-02-20T01:07:52.961Z,1361322472.961 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-02-20T01:07:52.976Z,1361322472.976 [TempGradientCalculator] Loaded
2013-02-20T01:07:52.976Z,1361322472.976 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-02-20T01:07:52.982Z,1361322472.982 [YawRateCalculator] Loaded
2013-02-20T01:07:52.983Z,1361322472.983 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-02-20T01:07:53.023Z,1361322473.023 [Navigation] Loaded
2013-02-20T01:07:53.024Z,1361322473.024 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-02-20T01:07:53.024Z,1361322473.024 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-02-20T01:07:53.025Z,1361322473.025 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-02-20T01:07:53.246Z,1361322473.246 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-02-20T01:07:53.246Z,1361322473.246 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-02-20T01:07:53.268Z,1361322473.268 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-02-20T01:07:53.268Z,1361322473.268 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-02-20T01:07:53.307Z,1361322473.307 [VerticalControl](DEBUG): Construct VerticalControl.
2013-02-20T01:07:53.402Z,1361322473.402 [VerticalControl] Loaded
2013-02-20T01:07:53.403Z,1361322473.403 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-02-20T01:07:53.404Z,1361322473.404 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-02-20T01:07:53.461Z,1361322473.461 [HorizontalControl] Loaded
2013-02-20T01:07:53.461Z,1361322473.461 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-02-20T01:07:53.462Z,1361322473.462 [SpeedControl](DEBUG): Construct SpeedControl.
2013-02-20T01:07:53.464Z,1361322473.464 [SpeedControl] Loaded
2013-02-20T01:07:53.464Z,1361322473.464 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-02-20T01:07:53.465Z,1361322473.465 [LoopControl](DEBUG): Construct LoopControl.
2013-02-20T01:07:53.466Z,1361322473.466 [LoopControl] Loaded
2013-02-20T01:07:53.466Z,1361322473.466 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-02-20T01:07:53.466Z,1361322473.466 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-02-20T01:07:53.467Z,1361322473.467 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-02-20T01:07:53.472Z,1361322473.472 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-02-20T01:07:53.477Z,1361322473.477 [AsyncPiEstimator] Loaded
2013-02-20T01:07:53.477Z,1361322473.477 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-02-20T01:07:53.479Z,1361322473.479 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406A44E0
2013-02-20T01:07:53.479Z,1361322473.479 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-02-20T01:07:53.480Z,1361322473.480 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-02-20T01:07:53.607Z,1361322473.607 [AHRS_sp3003D] Loaded
2013-02-20T01:07:53.607Z,1361322473.607 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-02-20T01:07:53.870Z,1361322473.870 [Batt_Ocean_Server] Loaded
2013-02-20T01:07:53.871Z,1361322473.871 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-02-20T01:07:53.884Z,1361322473.884 [Depth_Keller] Loaded
2013-02-20T01:07:53.885Z,1361322473.885 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-02-20T01:07:53.890Z,1361322473.890 [DropWeight] Loaded
2013-02-20T01:07:53.891Z,1361322473.891 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-02-20T01:07:54.053Z,1361322474.053 [DVL_micro] Loaded
2013-02-20T01:07:54.053Z,1361322474.053 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-02-20T01:07:54.054Z,1361322474.054 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 407284E0
2013-02-20T01:07:54.137Z,1361322474.137 [NAL9602] Loaded
2013-02-20T01:07:54.138Z,1361322474.138 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-02-20T01:07:54.188Z,1361322474.188 [Onboard] Loaded
2013-02-20T01:07:54.189Z,1361322474.189 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-02-20T01:07:54.196Z,1361322474.195 [Radio_Freewave] Loaded
2013-02-20T01:07:54.196Z,1361322474.196 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-02-20T01:07:54.197Z,1361322474.197 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-02-20T01:07:54.197Z,1361322474.197 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-02-20T01:07:54.255Z,1361322474.254 [Aanderaa_O2] Loaded
2013-02-20T01:07:54.255Z,1361322474.255 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2013-02-20T01:07:54.288Z,1361322474.288 [CTD_NeilBrown] Loaded
2013-02-20T01:07:54.288Z,1361322474.288 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-02-20T01:07:54.290Z,1361322474.290 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0
2013-02-20T01:07:54.303Z,1361322474.303 [ISUS] Loaded
2013-02-20T01:07:54.304Z,1361322474.304 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2013-02-20T01:07:54.319Z,1361322474.319 [PAR_Licor] Loaded
2013-02-20T01:07:54.320Z,1361322474.320 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2013-02-20T01:07:54.350Z,1361322474.350 [WetLabsBB2FL] Loaded
2013-02-20T01:07:54.350Z,1361322474.350 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-02-20T01:07:54.351Z,1361322474.351 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0
2013-02-20T01:07:54.352Z,1361322474.352 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-02-20T01:07:54.354Z,1361322474.354 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-02-20T01:07:54.355Z,1361322474.355 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-02-20T01:07:54.361Z,1361322474.362 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-02-20T01:07:54.363Z,1361322474.363 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0
2013-02-20T01:07:54.367Z,1361322474.367 [Supervisor](DEBUG): Running supervisor.
2013-02-20T01:07:54.368Z,1361322474.368 [CommandLine](INFO): Thread ID is 5586
2013-02-20T01:07:54.371Z,1361322474.371 [controlThread](INFO): Thread ID is 5585
2013-02-20T01:07:54.371Z,1361322474.371 [controlThread](DEBUG): Initializing ControlThread
2013-02-20T01:07:54.371Z,1361322474.371 [CycleStarter](INFO): Thread ID is 5584
2013-02-20T01:07:54.372Z,1361322474.372 [InternalSim](DEBUG):  InternalSim initializing...
2013-02-20T01:07:54.407Z,1361322474.407 [logger](INFO): Thread ID is 5587
2013-02-20T01:07:54.432Z,1361322474.432 [SBIT](INFO): Initialize SBIT Component.
2013-02-20T01:07:54.433Z,1361322474.432 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 10131
2013-02-20T01:07:54.433Z,1361322474.433 [IBIT](INFO): Initialize IBIT Component.
2013-02-20T01:07:54.434Z,1361322474.434 [CBIT](DEBUG): Initialize CBIT Component.
2013-02-20T01:07:54.434Z,1361322474.434 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-02-20T01:07:54.439Z,1361322474.439 [AsyncPiEstimator](INFO): Thread ID is 5648
2013-02-20T01:07:54.439Z,1361322474.439 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-02-20T01:07:54.459Z,1361322474.459 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-02-20T01:07:54.460Z,1361322474.460 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-02-20T01:07:54.460Z,1361322474.460 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-02-20T01:07:54.461Z,1361322474.461 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-02-20T01:07:54.461Z,1361322474.461 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-02-20T01:07:54.463Z,1361322474.463 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-02-20T01:07:54.463Z,1361322474.463 [Navigation](DEBUG): Initializing Navigation.
2013-02-20T01:07:54.463Z,1361322474.463 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-02-20T01:07:54.465Z,1361322474.465 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-02-20T01:07:54.466Z,1361322474.466 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-02-20T01:07:54.467Z,1361322474.467 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-02-20T01:07:54.469Z,1361322474.469 [DVL_micro](INFO): Thread ID is 5649
2013-02-20T01:07:54.479Z,1361322474.479 [DVL_micro](INFO): Initializing
2013-02-20T01:07:54.479Z,1361322474.479 [DVL_micro](INFO): start:Powering up
2013-02-20T01:07:54.480Z,1361322474.480 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-02-20T01:07:54.480Z,1361322474.480 [DVL_micro](INFO): Cycling power to configure device.
2013-02-20T01:07:54.497Z,1361322474.497 [CTD_NeilBrown](INFO): Thread ID is 5650
2013-02-20T01:07:54.498Z,1361322474.498 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-02-20T01:07:54.528Z,1361322474.528 [WetLabsBB2FL](INFO): Thread ID is 5651
2013-02-20T01:07:54.529Z,1361322474.529 [WetLabsBB2FL](INFO): Powering down
2013-02-20T01:07:54.556Z,1361322474.556 [NavChartDb](INFO): Thread ID is 5652
2013-02-20T01:07:54.560Z,1361322474.560 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2013-02-20T01:07:54.564Z,1361322474.564 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-02-20T01:07:54.567Z,1361322474.567 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2013-02-20T01:07:54.568Z,1361322474.569 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2013-02-20T01:07:54.570Z,1361322474.570 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2013-02-20T01:07:54.572Z,1361322474.572 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2013-02-20T01:07:54.574Z,1361322474.574 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2013-02-20T01:07:54.578Z,1361322474.578 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2013-02-20T01:07:54.580Z,1361322474.580 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2013-02-20T01:07:54.582Z,1361322474.582 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2013-02-20T01:07:54.767Z,1361322474.767 [NavChartDb](INFO): Setup scan of Resources/US5CA62M.000
2013-02-20T01:07:54.906Z,1361322474.906 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.000000 psu
2013-02-20T01:07:56.349Z,1361322476.349 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2013-02-20T01:07:56.380Z,1361322476.379 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-02-20T01:07:56.391Z,1361322476.391 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-20T01:07:56.402Z,1361322476.402 [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>


2013-02-20T01:07:56.403Z,1361322476.402 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-02-20T01:07:56.482Z,1361322476.482 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-02-20T01:07:56.484Z,1361322476.484 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-02-20T01:07:56.487Z,1361322476.487 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-20T01:07:56.495Z,1361322476.495 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-02-20T01:07:56.498Z,1361322476.498 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-20T01:07:56.504Z,1361322476.504 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-02-20T01:07:56.535Z,1361322476.534 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-02-20T01:07:56.535Z,1361322476.535 [DVL_micro](INFO): Querying output modes
2013-02-20T01:07:56.535Z,1361322476.535 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-02-20T01:07:56.546Z,1361322476.546 [DVL_micro](DEBUG): cmdResponse: 01
2013-02-20T01:07:56.546Z,1361322476.546 [DVL_micro](INFO): NQ1 output enabled
2013-02-20T01:07:56.547Z,1361322476.547 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-02-20T01:07:56.548Z,1361322476.548 [Default:D.SetSpeed](DEBUG): Construct.
2013-02-20T01:07:56.551Z,1361322476.551 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-20T01:07:56.556Z,1361322476.556 [Default:F.Wait](DEBUG): Construct Wait.
2013-02-20T01:07:56.558Z,1361322476.558 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-02-20T01:07:56.563Z,1361322476.563 [DVL_micro](INFO): pause:Powering down
2013-02-20T01:07:56.570Z,1361322476.570 [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>


2013-02-20T01:07:56.575Z,1361322476.575 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,Aanderaa_O2,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-02-20T01:07:56.594Z,1361322476.594 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-02-20T01:07:56.609Z,1361322476.609 [Depth_Keller](ERROR): Pressure reading out of range: 1831.078247 decibar
2013-02-20T01:07:56.676Z,1361322476.676 [Radio_Freewave](INFO): Powering up
2013-02-20T01:07:56.905Z,1361322476.905 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:07:56.907Z,1361322476.907 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-20T01:07:56.941Z,1361322476.941 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:07:56.946Z,1361322476.946 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-20T01:07:56.952Z,1361322476.952 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:07:56.958Z,1361322476.958 [MassServo](DEBUG): Initializing MassServo.
2013-02-20T01:07:56.964Z,1361322476.964 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:07:56.969Z,1361322476.968 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-20T01:07:56.974Z,1361322476.974 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:07:56.975Z,1361322476.975 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-20T01:07:57.506Z,1361322477.506 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0x420DA4090F
2013-02-20T01:07:57.506Z,1361322477.506 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-02-20T01:07:57.506Z,1361322477.506 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2013-02-20T01:07:57.506Z,1361322477.506 [AHRS_sp3003D](ERROR): Hardware Fault
2013-02-20T01:07:57.777Z,1361322477.777 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-02-20T01:07:57.778Z,1361322477.778 [DVL_micro](INFO): resume:Powering up
2013-02-20T01:07:57.778Z,1361322477.778 [DVL_micro](INFO): Cycling power to configure device.
2013-02-20T01:07:58.094Z,1361322478.094 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-20T01:07:58.094Z,1361322478.094 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-20T01:07:58.094Z,1361322478.094 [BuoyancyServo] Communications Fault, FailCount= 1
2013-02-20T01:07:58.094Z,1361322478.095 [BuoyancyServo](ERROR): Communications Fault
2013-02-20T01:07:58.602Z,1361322478.602 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-02-20T01:07:58.602Z,1361322478.602 [ElevatorServo](FAULT): Elevator failed to initialize
2013-02-20T01:07:58.602Z,1361322478.602 [ElevatorServo] Communications Fault, FailCount= 1
2013-02-20T01:07:58.602Z,1361322478.602 [ElevatorServo](ERROR): Communications Fault
2013-02-20T01:07:59.130Z,1361322479.130 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2013-02-20T01:07:59.130Z,1361322479.130 [RudderServo](FAULT): Rudder failed to initialize
2013-02-20T01:07:59.130Z,1361322479.130 [RudderServo] Communications Fault, FailCount= 1
2013-02-20T01:07:59.130Z,1361322479.130 [RudderServo](ERROR): Communications Fault
2013-02-20T01:07:59.646Z,1361322479.646 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-20T01:07:59.664Z,1361322479.664 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-20T01:07:59.664Z,1361322479.664 [ThrusterServo] Communications Fault, FailCount= 1
2013-02-20T01:07:59.665Z,1361322479.665 [ThrusterServo](ERROR): Communications Fault
2013-02-20T01:07:59.682Z,1361322479.682 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-20T01:07:59.684Z,1361322479.684 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2013-02-20T01:07:59.686Z,1361322479.685 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-02-20T01:07:59.688Z,1361322479.688 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-20T01:07:59.689Z,1361322479.689 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-02-20T01:07:59.762Z,1361322479.762 [AHRS_sp3003D](INFO): Powering down
2013-02-20T01:07:59.786Z,1361322479.786 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-02-20T01:07:59.787Z,1361322479.787 [DVL_micro](INFO): Querying output modes
2013-02-20T01:07:59.787Z,1361322479.787 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-02-20T01:07:59.798Z,1361322479.798 [DVL_micro](DEBUG): cmdResponse: 01
2013-02-20T01:07:59.798Z,1361322479.798 [DVL_micro](INFO): NQ1 output enabled
2013-02-20T01:07:59.798Z,1361322479.798 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-02-20T01:07:59.810Z,1361322479.810 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-02-20T01:07:59.929Z,1361322479.929 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-20T01:07:59.931Z,1361322479.931 [BuoyancyServo](INFO): Powering down
2013-02-20T01:07:59.970Z,1361322479.970 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-02-20T01:07:59.971Z,1361322479.971 [ElevatorServo](INFO): Powering down
2013-02-20T01:07:59.992Z,1361322479.992 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-20T01:07:59.992Z,1361322479.992 [RudderServo](INFO): Powering down
2013-02-20T01:08:00.028Z,1361322480.028 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-20T01:08:00.028Z,1361322480.028 [ThrusterServo](INFO): Powering down
2013-02-20T01:08:00.414Z,1361322480.414 [MassServo](FAULT): Mass initialization uart error:serial timeout
2013-02-20T01:08:00.414Z,1361322480.414 [MassServo] Communications Fault, FailCount= 1
2013-02-20T01:08:00.414Z,1361322480.414 [MassServo](ERROR): Communications Fault
2013-02-20T01:08:00.418Z,1361322480.418 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-20T01:08:00.547Z,1361322480.547 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:08:00.664Z,1361322480.664 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-20T01:08:00.665Z,1361322480.665 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-20T01:08:00.665Z,1361322480.665 [MassServo](INFO): Powering down
2013-02-20T01:08:00.687Z,1361322480.687 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:08:00.803Z,1361322480.803 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-20T01:08:00.924Z,1361322480.924 [CBIT](INFO): Clearing failed state for component ElevatorServo
2013-02-20T01:08:00.924Z,1361322480.924 [ElevatorServo] No Fault, FailCount= 1
2013-02-20T01:08:00.924Z,1361322480.924 [CBIT](INFO): Clearing failed state for component RudderServo
2013-02-20T01:08:00.924Z,1361322480.924 [RudderServo] No Fault, FailCount= 1
2013-02-20T01:08:00.925Z,1361322480.925 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-20T01:08:00.925Z,1361322480.925 [ThrusterServo] No Fault, FailCount= 1
2013-02-20T01:08:00.971Z,1361322480.971 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:08:00.971Z,1361322480.971 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-20T01:08:00.974Z,1361322480.974 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-02-20T01:08:00.974Z,1361322480.974 [AHRS_sp3003D] No Fault, FailCount= 1
2013-02-20T01:08:01.341Z,1361322481.341 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-02-20T01:08:01.886Z,1361322481.886 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-02-20T01:08:01.886Z,1361322481.886 [ElevatorServo](FAULT): Elevator failed to initialize
2013-02-20T01:08:01.886Z,1361322481.886 [ElevatorServo] Communications Fault, FailCount= 2
2013-02-20T01:08:01.886Z,1361322481.886 [ElevatorServo](ERROR): Communications Fault
2013-02-20T01:08:01.887Z,1361322481.887 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:08:01.920Z,1361322481.920 [DVL_micro](INFO): pause:Powering down
2013-02-20T01:08:02.007Z,1361322482.007 [MassServo](DEBUG): Initializing MassServo.
2013-02-20T01:08:02.518Z,1361322482.518 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2013-02-20T01:08:02.518Z,1361322482.518 [RudderServo](FAULT): Rudder failed to initialize
2013-02-20T01:08:02.518Z,1361322482.518 [RudderServo] Communications Fault, FailCount= 2
2013-02-20T01:08:02.518Z,1361322482.518 [RudderServo](ERROR): Communications Fault
2013-02-20T01:08:03.034Z,1361322483.034 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-20T01:08:03.052Z,1361322483.052 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-20T01:08:03.052Z,1361322483.052 [ThrusterServo] Communications Fault, FailCount= 2
2013-02-20T01:08:03.053Z,1361322483.053 [ThrusterServo](ERROR): Communications Fault
2013-02-20T01:08:03.065Z,1361322483.065 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2013-02-20T01:08:03.071Z,1361322483.070 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-20T01:08:03.071Z,1361322483.071 [MassServo] No Fault, FailCount= 1
2013-02-20T01:08:03.073Z,1361322483.073 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-02-20T01:08:03.074Z,1361322483.074 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-20T01:08:03.369Z,1361322483.369 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-02-20T01:08:03.369Z,1361322483.369 [ElevatorServo](INFO): Powering down
2013-02-20T01:08:03.377Z,1361322483.377 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-20T01:08:03.377Z,1361322483.377 [RudderServo](INFO): Powering down
2013-02-20T01:08:03.384Z,1361322483.384 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-20T01:08:03.384Z,1361322483.384 [ThrusterServo](INFO): Powering down
2013-02-20T01:08:03.691Z,1361322483.691 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-02-20T01:08:03.691Z,1361322483.691 [BuoyancyServo] No Fault, FailCount= 1
2013-02-20T01:08:04.082Z,1361322484.082 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:08:04.203Z,1361322484.203 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-20T01:08:04.204Z,1361322484.204 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:08:04.318Z,1361322484.318 [CommandLine](IMPORTANT): got command quit
2013-02-20T01:08:04.323Z,1361322484.323 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-20T01:08:04.325Z,1361322484.325 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-20T01:08:04.445Z,1361322484.445 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-20T01:08:04.569Z,1361322484.569 [CBIT](INFO): Clearing failed state for component ElevatorServo
2013-02-20T01:08:04.569Z,1361322484.569 [ElevatorServo] No Fault, FailCount= 2
2013-02-20T01:08:04.569Z,1361322484.569 [CBIT](INFO): Clearing failed state for component RudderServo
2013-02-20T01:08:04.569Z,1361322484.569 [RudderServo] No Fault, FailCount= 2
2013-02-20T01:08:04.570Z,1361322484.569 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-20T01:08:04.570Z,1361322484.569 [ThrusterServo] No Fault, FailCount= 2
2013-02-20T01:08:05.314Z,1361322485.314 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-20T01:08:05.314Z,1361322485.314 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-20T01:08:05.314Z,1361322485.314 [BuoyancyServo] Communications Fault, FailCount= 2
2013-02-20T01:08:05.314Z,1361322485.314 [BuoyancyServo](ERROR): Communications Fault
2013-02-20T01:08:05.402Z,1361322485.402 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2013-02-20T01:08:05.402Z,1361322485.402 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-02-20T01:08:05.482Z,1361322485.482 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-02-20T01:08:05.510Z,1361322485.510 [WetLabsBB2FL](INFO): Powering down
2013-02-20T01:08:05.522Z,1361322485.522 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2013-02-20T01:08:05.830Z,1361322485.830 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-02-20T01:08:05.830Z,1361322485.830 [ElevatorServo](FAULT): Elevator failed to initialize
2013-02-20T01:08:05.830Z,1361322485.830 [ElevatorServo] Communications Fault, FailCount= 3
2013-02-20T01:08:05.830Z,1361322485.830 [ElevatorServo](ERROR): Communications Fault
2013-02-20T01:08:05.843Z,1361322485.843 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-02-20T01:08:05.959Z,1361322485.958 [DVL_micro](INFO): uninitialize:Powering down
2013-02-20T01:08:05.962Z,1361322485.962 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-02-20T01:08:06.106Z,1361322486.106 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.