2013-02-25T18:53:36.445Z,1361818416.445 [Supervisor](DEBUG): Initializing supervisor.
2013-02-25T18:53:36.447Z,1361818416.447 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-02-25T18:53:36.448Z,1361818416.448 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-02-25T18:53:36.449Z,1361818416.449 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-02-25T18:53:36.452Z,1361818416.453 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-02-25T18:53:36.463Z,1361818416.463 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-02-25T18:53:36.464Z,1361818416.464 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-02-25T18:53:36.465Z,1361818416.465 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-02-25T18:53:36.466Z,1361818416.466 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-02-25T18:53:36.466Z,1361818416.466 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-02-25T18:53:36.468Z,1361818416.467 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-02-25T18:53:36.744Z,1361818416.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-02-25T18:53:36.745Z,1361818416.745 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-02-25T18:53:36.931Z,1361818416.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-02-25T18:53:36.932Z,1361818416.931 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-02-25T18:53:37.016Z,1361818417.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-02-25T18:53:37.016Z,1361818417.016 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-02-25T18:53:37.211Z,1361818417.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-02-25T18:53:37.211Z,1361818417.211 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-02-25T18:53:37.342Z,1361818417.342 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-02-25T18:53:37.343Z,1361818417.343 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-02-25T18:53:37.575Z,1361818417.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-02-25T18:53:37.575Z,1361818417.575 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-02-25T18:53:37.744Z,1361818417.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-02-25T18:53:37.744Z,1361818417.744 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-02-25T18:53:37.991Z,1361818417.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-02-25T18:53:37.992Z,1361818417.992 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-02-25T18:53:38.089Z,1361818418.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-02-25T18:53:38.089Z,1361818418.089 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-02-25T18:53:38.486Z,1361818418.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-02-25T18:53:38.486Z,1361818418.486 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-02-25T18:53:38.600Z,1361818418.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-02-25T18:53:38.600Z,1361818418.600 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-02-25T18:53:38.684Z,1361818418.684 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-02-25T18:53:38.685Z,1361818418.685 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-02-25T18:53:38.786Z,1361818418.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-02-25T18:53:38.913Z,1361818418.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-02-25T18:53:38.997Z,1361818418.997 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-02-25T18:53:39.096Z,1361818419.096 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-02-25T18:53:39.196Z,1361818419.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-02-25T18:53:39.312Z,1361818419.312 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-02-25T18:53:39.403Z,1361818419.403 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-02-25T18:53:39.489Z,1361818419.489 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-02-25T18:53:39.518Z,1361818419.518 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-02-25T18:53:39.671Z,1361818419.671 [InternalSim] Loaded
2013-02-25T18:53:39.671Z,1361818419.671 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-02-25T18:53:39.672Z,1361818419.672 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-02-25T18:53:39.673Z,1361818419.673 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-02-25T18:53:39.729Z,1361818419.729 [SBIT](DEBUG): Construct Startup Built In Test.
2013-02-25T18:53:39.758Z,1361818419.758 [SBIT] Loaded
2013-02-25T18:53:39.758Z,1361818419.759 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-02-25T18:53:39.760Z,1361818419.760 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-02-25T18:53:39.788Z,1361818419.788 [IBIT] Loaded
2013-02-25T18:53:39.789Z,1361818419.789 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-02-25T18:53:39.792Z,1361818419.792 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-02-25T18:53:39.913Z,1361818419.913 [CBIT] Loaded
2013-02-25T18:53:39.913Z,1361818419.913 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-02-25T18:53:39.914Z,1361818419.914 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-02-25T18:53:39.914Z,1361818419.914 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-02-25T18:53:40.064Z,1361818420.064 [BuoyancyServo] Loaded
2013-02-25T18:53:40.064Z,1361818420.064 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-02-25T18:53:40.077Z,1361818420.077 [ElevatorServo] Loaded
2013-02-25T18:53:40.077Z,1361818420.077 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-02-25T18:53:40.089Z,1361818420.089 [MassServo] Loaded
2013-02-25T18:53:40.089Z,1361818420.089 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-02-25T18:53:40.101Z,1361818420.101 [RudderServo] Loaded
2013-02-25T18:53:40.101Z,1361818420.101 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-02-25T18:53:40.113Z,1361818420.113 [ThrusterServo] Loaded
2013-02-25T18:53:40.114Z,1361818420.114 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-02-25T18:53:40.114Z,1361818420.114 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-02-25T18:53:40.115Z,1361818420.115 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-02-25T18:53:40.131Z,1361818420.131 [DepthRateCalculator] Loaded
2013-02-25T18:53:40.132Z,1361818420.132 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-02-25T18:53:43.701Z,1361818423.701 [HFRadarModelCalc] Loaded
2013-02-25T18:53:43.701Z,1361818423.701 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-02-25T18:53:43.717Z,1361818423.717 [NavChart] Loaded
2013-02-25T18:53:43.717Z,1361818423.717 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-02-25T18:53:43.724Z,1361818423.724 [PitchRateCalculator] Loaded
2013-02-25T18:53:43.724Z,1361818423.724 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-02-25T18:53:43.730Z,1361818423.730 [SpeedCalculator] Loaded
2013-02-25T18:53:43.730Z,1361818423.730 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-02-25T18:53:43.745Z,1361818423.745 [TempGradientCalculator] Loaded
2013-02-25T18:53:43.746Z,1361818423.746 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-02-25T18:53:43.752Z,1361818423.751 [YawRateCalculator] Loaded
2013-02-25T18:53:43.752Z,1361818423.752 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-02-25T18:53:43.793Z,1361818423.793 [Navigation] Loaded
2013-02-25T18:53:43.793Z,1361818423.793 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-02-25T18:53:43.793Z,1361818423.793 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-02-25T18:53:43.794Z,1361818423.794 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-02-25T18:53:44.015Z,1361818424.015 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-02-25T18:53:44.016Z,1361818424.016 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-02-25T18:53:44.037Z,1361818424.037 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-02-25T18:53:44.038Z,1361818424.038 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-02-25T18:53:44.077Z,1361818424.077 [VerticalControl](DEBUG): Construct VerticalControl.
2013-02-25T18:53:44.171Z,1361818424.171 [VerticalControl] Loaded
2013-02-25T18:53:44.172Z,1361818424.172 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-02-25T18:53:44.173Z,1361818424.173 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-02-25T18:53:44.230Z,1361818424.230 [HorizontalControl] Loaded
2013-02-25T18:53:44.230Z,1361818424.230 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-02-25T18:53:44.231Z,1361818424.231 [SpeedControl](DEBUG): Construct SpeedControl.
2013-02-25T18:53:44.233Z,1361818424.233 [SpeedControl] Loaded
2013-02-25T18:53:44.233Z,1361818424.233 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-02-25T18:53:44.234Z,1361818424.234 [LoopControl](DEBUG): Construct LoopControl.
2013-02-25T18:53:44.235Z,1361818424.235 [LoopControl] Loaded
2013-02-25T18:53:44.235Z,1361818424.235 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-02-25T18:53:44.235Z,1361818424.235 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-02-25T18:53:44.236Z,1361818424.236 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-02-25T18:53:44.241Z,1361818424.241 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-02-25T18:53:44.246Z,1361818424.246 [AsyncPiEstimator] Loaded
2013-02-25T18:53:44.246Z,1361818424.246 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-02-25T18:53:44.248Z,1361818424.247 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406A44E0
2013-02-25T18:53:44.248Z,1361818424.248 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-02-25T18:53:44.249Z,1361818424.249 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-02-25T18:53:44.376Z,1361818424.376 [AHRS_sp3003D] Loaded
2013-02-25T18:53:44.377Z,1361818424.376 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-02-25T18:53:44.642Z,1361818424.642 [Batt_Ocean_Server] Loaded
2013-02-25T18:53:44.642Z,1361818424.642 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-02-25T18:53:44.656Z,1361818424.656 [Depth_Keller] Loaded
2013-02-25T18:53:44.656Z,1361818424.656 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-02-25T18:53:44.662Z,1361818424.662 [DropWeight] Loaded
2013-02-25T18:53:44.662Z,1361818424.662 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-02-25T18:53:44.826Z,1361818424.826 [DVL_micro] Loaded
2013-02-25T18:53:44.826Z,1361818424.826 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-02-25T18:53:44.828Z,1361818424.828 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 407284E0
2013-02-25T18:53:44.911Z,1361818424.911 [NAL9602] Loaded
2013-02-25T18:53:44.912Z,1361818424.912 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-02-25T18:53:44.965Z,1361818424.966 [Onboard] Loaded
2013-02-25T18:53:44.966Z,1361818424.966 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-02-25T18:53:44.973Z,1361818424.973 [Radio_Freewave] Loaded
2013-02-25T18:53:44.973Z,1361818424.973 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-02-25T18:53:44.974Z,1361818424.974 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-02-25T18:53:44.975Z,1361818424.974 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-02-25T18:53:45.031Z,1361818425.031 [Aanderaa_O2] Loaded
2013-02-25T18:53:45.031Z,1361818425.031 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2013-02-25T18:53:45.045Z,1361818425.045 [ISUS] Loaded
2013-02-25T18:53:45.045Z,1361818425.045 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2013-02-25T18:53:45.061Z,1361818425.061 [PAR_Licor] Loaded
2013-02-25T18:53:45.061Z,1361818425.061 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2013-02-25T18:53:45.092Z,1361818425.092 [WetLabsBB2FL] Loaded
2013-02-25T18:53:45.093Z,1361818425.093 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-02-25T18:53:45.094Z,1361818425.094 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0
2013-02-25T18:53:45.094Z,1361818425.094 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-02-25T18:53:45.097Z,1361818425.097 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-02-25T18:53:45.098Z,1361818425.098 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-02-25T18:53:45.104Z,1361818425.104 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-02-25T18:53:45.105Z,1361818425.105 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0
2013-02-25T18:53:45.110Z,1361818425.110 [Supervisor](DEBUG): Running supervisor.
2013-02-25T18:53:45.111Z,1361818425.111 [CommandLine](INFO): Thread ID is 529
2013-02-25T18:53:45.113Z,1361818425.113 [controlThread](INFO): Thread ID is 528
2013-02-25T18:53:45.113Z,1361818425.113 [controlThread](DEBUG): Initializing ControlThread
2013-02-25T18:53:45.114Z,1361818425.114 [CycleStarter](INFO): Thread ID is 527
2013-02-25T18:53:45.115Z,1361818425.115 [InternalSim](DEBUG):  InternalSim initializing...
2013-02-25T18:53:45.151Z,1361818425.151 [logger](INFO): Thread ID is 530
2013-02-25T18:53:45.176Z,1361818425.176 [SBIT](INFO): Initialize SBIT Component.
2013-02-25T18:53:45.176Z,1361818425.176 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 10150
2013-02-25T18:53:45.177Z,1361818425.177 [IBIT](INFO): Initialize IBIT Component.
2013-02-25T18:53:45.178Z,1361818425.178 [CBIT](DEBUG): Initialize CBIT Component.
2013-02-25T18:53:45.178Z,1361818425.178 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-02-25T18:53:45.191Z,1361818425.191 [AsyncPiEstimator](INFO): Thread ID is 591
2013-02-25T18:53:45.191Z,1361818425.191 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-02-25T18:53:45.203Z,1361818425.203 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-02-25T18:53:45.204Z,1361818425.204 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-02-25T18:53:45.204Z,1361818425.204 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-02-25T18:53:45.205Z,1361818425.205 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-02-25T18:53:45.205Z,1361818425.205 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-02-25T18:53:45.207Z,1361818425.207 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-02-25T18:53:45.207Z,1361818425.207 [Navigation](DEBUG): Initializing Navigation.
2013-02-25T18:53:45.207Z,1361818425.207 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-02-25T18:53:45.209Z,1361818425.209 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-02-25T18:53:45.210Z,1361818425.210 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-02-25T18:53:45.211Z,1361818425.211 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-02-25T18:53:45.217Z,1361818425.217 [DVL_micro](INFO): Thread ID is 592
2013-02-25T18:53:45.227Z,1361818425.227 [DVL_micro](INFO): Initializing
2013-02-25T18:53:45.227Z,1361818425.227 [DVL_micro](INFO): start:Powering up
2013-02-25T18:53:45.228Z,1361818425.228 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-02-25T18:53:45.229Z,1361818425.229 [DVL_micro](INFO): Cycling power to configure device.
2013-02-25T18:53:45.239Z,1361818425.239 [WetLabsBB2FL](INFO): Thread ID is 593
2013-02-25T18:53:45.240Z,1361818425.240 [WetLabsBB2FL](INFO): Powering down
2013-02-25T18:53:45.275Z,1361818425.275 [NavChartDb](INFO): Thread ID is 594
2013-02-25T18:53:45.278Z,1361818425.278 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2013-02-25T18:53:45.281Z,1361818425.281 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-02-25T18:53:45.283Z,1361818425.283 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2013-02-25T18:53:45.285Z,1361818425.285 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2013-02-25T18:53:45.286Z,1361818425.286 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2013-02-25T18:53:45.288Z,1361818425.288 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2013-02-25T18:53:45.290Z,1361818425.290 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2013-02-25T18:53:45.291Z,1361818425.291 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2013-02-25T18:53:45.293Z,1361818425.293 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2013-02-25T18:53:45.295Z,1361818425.295 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2013-02-25T18:53:45.482Z,1361818425.482 [NavChartDb](INFO): Setup scan of Resources/US5CA62M.000
2013-02-25T18:53:47.006Z,1361818427.006 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2013-02-25T18:53:47.035Z,1361818427.035 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-02-25T18:53:47.045Z,1361818427.045 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-25T18:53:47.063Z,1361818427.063 [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-25T18:53:47.064Z,1361818427.064 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-02-25T18:53:47.113Z,1361818427.113 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-02-25T18:53:47.115Z,1361818427.115 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-02-25T18:53:47.119Z,1361818427.119 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-25T18:53:47.127Z,1361818427.127 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-02-25T18:53:47.130Z,1361818427.130 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-25T18:53:47.136Z,1361818427.136 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-02-25T18:53:47.145Z,1361818427.145 [Default:D.SetSpeed](DEBUG): Construct.
2013-02-25T18:53:47.148Z,1361818427.148 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-25T18:53:47.153Z,1361818427.152 [Default:F.Wait](DEBUG): Construct Wait.
2013-02-25T18:53:47.185Z,1361818427.185 [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-25T18:53:47.200Z,1361818427.200 [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-25T18:53:47.222Z,1361818427.222 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-02-25T18:53:47.302Z,1361818427.302 [Radio_Freewave](INFO): Powering up
2013-02-25T18:53:47.315Z,1361818427.315 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-02-25T18:53:47.315Z,1361818427.315 [DVL_micro](INFO): Querying output modes
2013-02-25T18:53:47.315Z,1361818427.315 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-02-25T18:53:47.326Z,1361818427.326 [DVL_micro](DEBUG): cmdResponse: 01
2013-02-25T18:53:47.326Z,1361818427.326 [DVL_micro](INFO): NQ1 output enabled
2013-02-25T18:53:47.327Z,1361818427.327 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-02-25T18:53:47.338Z,1361818427.338 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-02-25T18:53:47.343Z,1361818427.343 [DVL_micro](INFO): pause:Powering down
2013-02-25T18:53:47.498Z,1361818427.498 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:47.505Z,1361818427.505 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-25T18:53:47.512Z,1361818427.512 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:47.513Z,1361818427.513 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-25T18:53:47.520Z,1361818427.520 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:47.521Z,1361818427.521 [MassServo](DEBUG): Initializing MassServo.
2013-02-25T18:53:47.529Z,1361818427.529 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:47.530Z,1361818427.530 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-25T18:53:47.536Z,1361818427.536 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:47.537Z,1361818427.537 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-25T18:53:48.690Z,1361818428.690 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-25T18:53:48.690Z,1361818428.690 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-25T18:53:48.691Z,1361818428.691 [BuoyancyServo] Communications Fault, FailCount= 1
2013-02-25T18:53:48.691Z,1361818428.691 [BuoyancyServo](ERROR): Communications Fault
2013-02-25T18:53:49.202Z,1361818429.202 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-02-25T18:53:49.202Z,1361818429.202 [ElevatorServo](FAULT): Elevator failed to initialize
2013-02-25T18:53:49.202Z,1361818429.202 [ElevatorServo] Communications Fault, FailCount= 1
2013-02-25T18:53:49.203Z,1361818429.203 [ElevatorServo](ERROR): Communications Fault
2013-02-25T18:53:49.775Z,1361818429.774 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-25T18:53:49.800Z,1361818429.800 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-25T18:53:49.801Z,1361818429.801 [ThrusterServo] Communications Fault, FailCount= 1
2013-02-25T18:53:49.801Z,1361818429.801 [ThrusterServo](ERROR): Communications Fault
2013-02-25T18:53:49.814Z,1361818429.814 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-25T18:53:49.816Z,1361818429.816 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2013-02-25T18:53:49.817Z,1361818429.818 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-25T18:53:50.131Z,1361818430.131 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-25T18:53:50.132Z,1361818430.132 [BuoyancyServo](INFO): Powering down
2013-02-25T18:53:50.206Z,1361818430.206 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-02-25T18:53:50.206Z,1361818430.206 [ElevatorServo](INFO): Powering down
2013-02-25T18:53:50.227Z,1361818430.227 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-25T18:53:50.227Z,1361818430.227 [ThrusterServo](INFO): Powering down
2013-02-25T18:53:50.755Z,1361818430.755 [MassServo](FAULT): Mass initialization uart error:serial timeout
2013-02-25T18:53:50.755Z,1361818430.755 [MassServo] Communications Fault, FailCount= 1
2013-02-25T18:53:50.755Z,1361818430.755 [MassServo](ERROR): Communications Fault
2013-02-25T18:53:50.758Z,1361818430.758 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-25T18:53:50.974Z,1361818430.974 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:51.096Z,1361818431.096 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-25T18:53:51.097Z,1361818431.097 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-25T18:53:51.097Z,1361818431.097 [MassServo](INFO): Powering down
2013-02-25T18:53:51.634Z,1361818431.634 [RudderServo](FAULT): Rudder initialization uart error:serial timeout
2013-02-25T18:53:51.634Z,1361818431.634 [RudderServo] Communications Fault, FailCount= 1
2013-02-25T18:53:51.634Z,1361818431.634 [RudderServo](ERROR): Communications Fault
2013-02-25T18:53:51.752Z,1361818431.752 [CBIT](INFO): Clearing failed state for component ElevatorServo
2013-02-25T18:53:51.752Z,1361818431.752 [ElevatorServo] No Fault, FailCount= 1
2013-02-25T18:53:51.753Z,1361818431.753 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-02-25T18:53:51.753Z,1361818431.753 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-25T18:53:51.753Z,1361818431.753 [ThrusterServo] No Fault, FailCount= 1
2013-02-25T18:53:52.522Z,1361818432.522 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-02-25T18:53:52.522Z,1361818432.522 [ElevatorServo](FAULT): Elevator failed to initialize
2013-02-25T18:53:52.522Z,1361818432.522 [ElevatorServo] Communications Fault, FailCount= 2
2013-02-25T18:53:52.522Z,1361818432.522 [ElevatorServo](ERROR): Communications Fault
2013-02-25T18:53:52.523Z,1361818432.523 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-25T18:53:52.523Z,1361818432.523 [RudderServo](INFO): Powering down
2013-02-25T18:53:52.548Z,1361818432.548 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:52.552Z,1361818432.552 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-25T18:53:52.554Z,1361818432.554 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2013-02-25T18:53:52.884Z,1361818432.884 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-02-25T18:53:52.885Z,1361818432.885 [ElevatorServo](INFO): Powering down
2013-02-25T18:53:52.886Z,1361818432.886 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:53.004Z,1361818433.004 [MassServo](DEBUG): Initializing MassServo.
2013-02-25T18:53:53.007Z,1361818433.007 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-02-25T18:53:53.008Z,1361818433.008 [BuoyancyServo] No Fault, FailCount= 1
2013-02-25T18:53:53.008Z,1361818433.008 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-25T18:53:53.008Z,1361818433.008 [MassServo] No Fault, FailCount= 1
2013-02-25T18:53:53.274Z,1361818433.274 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:53.395Z,1361818433.395 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-25T18:53:53.397Z,1361818433.397 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:53.515Z,1361818433.515 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-25T18:53:54.034Z,1361818434.034 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-25T18:53:54.052Z,1361818434.052 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-25T18:53:54.052Z,1361818434.052 [ThrusterServo] Communications Fault, FailCount= 2
2013-02-25T18:53:54.053Z,1361818434.053 [ThrusterServo](ERROR): Communications Fault
2013-02-25T18:53:54.096Z,1361818434.096 [CBIT](INFO): Clearing failed state for component RudderServo
2013-02-25T18:53:54.096Z,1361818434.096 [RudderServo] No Fault, FailCount= 1
2013-02-25T18:53:54.096Z,1361818434.096 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-25T18:53:54.842Z,1361818434.842 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-25T18:53:54.842Z,1361818434.842 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-25T18:53:54.842Z,1361818434.842 [BuoyancyServo] Communications Fault, FailCount= 2
2013-02-25T18:53:54.842Z,1361818434.842 [BuoyancyServo](ERROR): Communications Fault
2013-02-25T18:53:54.843Z,1361818434.843 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:54.959Z,1361818434.959 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-25T18:53:55.470Z,1361818435.470 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2013-02-25T18:53:55.470Z,1361818435.470 [RudderServo](FAULT): Rudder failed to initialize
2013-02-25T18:53:55.470Z,1361818435.470 [RudderServo] Communications Fault, FailCount= 2
2013-02-25T18:53:55.470Z,1361818435.470 [RudderServo](ERROR): Communications Fault
2013-02-25T18:53:55.471Z,1361818435.471 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-25T18:53:55.471Z,1361818435.471 [ThrusterServo](INFO): Powering down
2013-02-25T18:53:55.474Z,1361818435.474 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-25T18:53:55.474Z,1361818435.474 [CBIT](INFO): Clearing failed state for component ElevatorServo
2013-02-25T18:53:55.474Z,1361818435.474 [ElevatorServo] No Fault, FailCount= 2
2013-02-25T18:53:55.474Z,1361818435.474 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-02-25T18:53:55.714Z,1361818435.714 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-25T18:53:55.720Z,1361818435.720 [BuoyancyServo](INFO): Powering down
2013-02-25T18:53:56.246Z,1361818436.246 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-02-25T18:53:56.246Z,1361818436.246 [ElevatorServo](FAULT): Elevator failed to initialize
2013-02-25T18:53:56.246Z,1361818436.246 [ElevatorServo] Communications Fault, FailCount= 3
2013-02-25T18:53:56.246Z,1361818436.246 [ElevatorServo](ERROR): Communications Fault
2013-02-25T18:53:56.248Z,1361818436.248 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-25T18:53:56.248Z,1361818436.248 [RudderServo](INFO): Powering down
2013-02-25T18:53:56.250Z,1361818436.250 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2013-02-25T18:53:56.251Z,1361818436.251 [CBIT](CRITICAL): Communications Fault in component: ElevatorServo
2013-02-25T18:53:56.600Z,1361818436.600 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-02-25T18:53:56.600Z,1361818436.600 [ElevatorServo](INFO): Powering down
2013-02-25T18:53:56.886Z,1361818436.886 [MassServo](FAULT): Mass initialization uart error:serial timeout
2013-02-25T18:53:56.886Z,1361818436.886 [MassServo] Communications Fault, FailCount= 2
2013-02-25T18:53:56.886Z,1361818436.886 [MassServo](ERROR): Communications Fault
2013-02-25T18:53:57.005Z,1361818437.005 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-25T18:53:57.005Z,1361818437.005 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-25T18:53:57.005Z,1361818437.005 [ThrusterServo] No Fault, FailCount= 2
2013-02-25T18:53:57.245Z,1361818437.245 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-25T18:53:57.245Z,1361818437.245 [MassServo](INFO): Powering down
2013-02-25T18:53:57.250Z,1361818437.250 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:57.371Z,1361818437.371 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-25T18:53:57.372Z,1361818437.372 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:57.378Z,1361818437.378 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-25T18:53:57.381Z,1361818437.381 [CBIT](INFO): Clearing failed state for component RudderServo
2013-02-25T18:53:57.381Z,1361818437.381 [RudderServo] No Fault, FailCount= 2
2013-02-25T18:53:57.669Z,1361818437.669 [Aanderaa_O2](FAULT): Timed out starting
2013-02-25T18:53:57.669Z,1361818437.669 [Aanderaa_O2] Communications Fault, FailCount= 1
2013-02-25T18:53:57.669Z,1361818437.669 [Aanderaa_O2](ERROR): Communications Fault
2013-02-25T18:53:57.682Z,1361818437.682 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:53:57.811Z,1361818437.811 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-25T18:53:57.815Z,1361818437.815 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-02-25T18:53:57.815Z,1361818437.815 [BuoyancyServo] No Fault, FailCount= 2
2013-02-25T18:53:57.815Z,1361818437.815 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-25T18:54:11.814Z,1361818451.814 [CommandLine](IMPORTANT): got command restart application
2013-02-25T18:54:12.898Z,1361818452.898 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2013-02-25T18:54:12.898Z,1361818452.898 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-02-25T18:54:12.998Z,1361818452.998 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-02-25T18:54:13.150Z,1361818453.150 [WetLabsBB2FL](INFO): Powering down
2013-02-25T18:54:13.158Z,1361818453.158 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-02-25T18:54:13.202Z,1361818453.202 [DVL_micro](INFO): uninitialize:Powering down
2013-02-25T18:54:13.218Z,1361818453.218 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-02-25T18:54:13.526Z,1361818453.526 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2013-02-25T18:54:13.538Z,1361818453.538 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-02-25T18:54:13.559Z,1361818453.559 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-02-25T18:54:13.560Z,1361818453.560 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-02-25T18:54:28.142Z,1361818468.142 [Aanderaa_O2](INFO): Powering down
2013-02-25T18:54:28.175Z,1361818468.175 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:54:28.295Z,1361818468.295 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-25T18:54:28.296Z,1361818468.296 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-25T18:54:28.415Z,1361818468.415 [MassServo](DEBUG): Initializing MassServo.
2013-02-25T18:54:28.576Z,1361818468.576 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-25T18:54:28.576Z,1361818468.576 [MassServo] No Fault, FailCount= 2
2013-02-25T18:54:28.577Z,1361818468.578 [controlThread](DEBUG): Uninitializing ControlThread
2013-02-25T18:54:28.578Z,1361818468.578 [AHRS_sp3003D](INFO): Powering down
2013-02-25T18:54:28.667Z,1361818468.667 [NAL9602](INFO): Powering down
2013-02-25T18:54:28.668Z,1361818468.668 [Aanderaa_O2](INFO): Powering down
2013-02-25T18:54:28.670Z,1361818468.670 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-02-25T18:54:28.671Z,1361818468.671 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2013-02-25T18:54:28.676Z,1361818468.676 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-02-25T18:54:28.676Z,1361818468.676 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-02-25T18:54:28.677Z,1361818468.677 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-02-25T18:54:28.677Z,1361818468.677 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-02-25T18:54:28.677Z,1361818468.677 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-25T18:54:28.678Z,1361818468.678 [BuoyancyServo](INFO): Powering down
2013-02-25T18:54:28.694Z,1361818468.694 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-02-25T18:54:28.694Z,1361818468.694 [ElevatorServo](INFO): Powering down
2013-02-25T18:54:28.695Z,1361818468.695 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-25T18:54:28.695Z,1361818468.695 [MassServo](INFO): Powering down
2013-02-25T18:54:28.696Z,1361818468.696 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-25T18:54:28.696Z,1361818468.696 [RudderServo](INFO): Powering down
2013-02-25T18:54:28.697Z,1361818468.697 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-25T18:54:28.697Z,1361818468.697 [ThrusterServo](INFO): Powering down
2013-02-25T18:54:28.698Z,1361818468.698 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-02-25T18:54:28.698Z,1361818468.698 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-02-25T18:54:28.698Z,1361818468.698 [CBIT](DEBUG): Uninitialize CBIT Component.