2012-09-20T14:14:13.924Z,1348150453.924 [Supervisor](DEBUG): Initializing supervisor.
2012-09-20T14:14:13.927Z,1348150453.927 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2012-09-20T14:14:13.927Z,1348150453.927 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2012-09-20T14:14:13.928Z,1348150453.928 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2012-09-20T14:14:13.933Z,1348150453.933 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2012-09-20T14:14:13.944Z,1348150453.944 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2012-09-20T14:14:13.945Z,1348150453.945 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2012-09-20T14:14:13.946Z,1348150453.946 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread.
2012-09-20T14:14:13.947Z,1348150453.947 [Supervisor](INFO): Looking for Config files in directory: Config/
2012-09-20T14:14:13.950Z,1348150453.950 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2012-09-20T14:14:14.416Z,1348150454.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2012-09-20T14:14:14.418Z,1348150454.418 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2012-09-20T14:14:14.597Z,1348150454.597 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2012-09-20T14:14:14.597Z,1348150454.597 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2012-09-20T14:14:14.683Z,1348150454.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2012-09-20T14:14:14.685Z,1348150454.685 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2012-09-20T14:14:14.883Z,1348150454.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2012-09-20T14:14:14.883Z,1348150454.883 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2012-09-20T14:14:15.018Z,1348150455.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2012-09-20T14:14:15.020Z,1348150455.020 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2012-09-20T14:14:15.253Z,1348150455.253 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2012-09-20T14:14:15.254Z,1348150455.254 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2012-09-20T14:14:15.424Z,1348150455.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2012-09-20T14:14:15.426Z,1348150455.426 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2012-09-20T14:14:15.684Z,1348150455.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2012-09-20T14:14:15.686Z,1348150455.686 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2012-09-20T14:14:15.786Z,1348150455.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2012-09-20T14:14:15.786Z,1348150455.786 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2012-09-20T14:14:16.239Z,1348150456.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2012-09-20T14:14:16.240Z,1348150456.240 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2012-09-20T14:14:16.354Z,1348150456.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2012-09-20T14:14:16.355Z,1348150456.355 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2012-09-20T14:14:16.442Z,1348150456.442 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2012-09-20T14:14:16.445Z,1348150456.445 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2012-09-20T14:14:16.547Z,1348150456.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2012-09-20T14:14:16.676Z,1348150456.676 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2012-09-20T14:14:16.763Z,1348150456.763 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2012-09-20T14:14:16.867Z,1348150456.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2012-09-20T14:14:16.968Z,1348150456.968 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2012-09-20T14:14:17.088Z,1348150457.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2012-09-20T14:14:17.179Z,1348150457.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2012-09-20T14:14:17.320Z,1348150457.320 [Supervisor](IMPORTANT): Reading configuration overrides from Data/config.db
2012-09-20T14:14:17.332Z,1348150457.332 [ComponentRegistry](DEBUG): Loaded Config Component "configDb
2012-09-20T14:14:17.409Z,1348150457.409 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2012-09-20T14:14:17.575Z,1348150457.575 [InternalSim] Loaded
2012-09-20T14:14:17.575Z,1348150457.575 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2012-09-20T14:14:17.576Z,1348150457.576 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2012-09-20T14:14:17.576Z,1348150457.576 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2012-09-20T14:14:17.633Z,1348150457.633 [SBIT](DEBUG): Construct Startup Built In Test.
2012-09-20T14:14:17.646Z,1348150457.646 [SBIT] Loaded
2012-09-20T14:14:17.646Z,1348150457.646 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2012-09-20T14:14:17.647Z,1348150457.647 [IBIT](DEBUG): Construct Initiated Built In Test.
2012-09-20T14:14:17.674Z,1348150457.674 [IBIT] Loaded
2012-09-20T14:14:17.674Z,1348150457.674 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2012-09-20T14:14:17.677Z,1348150457.677 [CBIT](DEBUG): Construct CBIT Built In Test.
2012-09-20T14:14:17.789Z,1348150457.789 [CBIT] Loaded
2012-09-20T14:14:17.789Z,1348150457.789 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2012-09-20T14:14:17.790Z,1348150457.790 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2012-09-20T14:14:17.790Z,1348150457.790 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2012-09-20T14:14:17.933Z,1348150457.932 [BuoyancyServo] Loaded
2012-09-20T14:14:17.933Z,1348150457.933 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2012-09-20T14:14:17.942Z,1348150457.942 [ElevatorServo] Loaded
2012-09-20T14:14:17.943Z,1348150457.943 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2012-09-20T14:14:17.954Z,1348150457.954 [MassServo] Loaded
2012-09-20T14:14:17.954Z,1348150457.954 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2012-09-20T14:14:17.966Z,1348150457.966 [RudderServo] Loaded
2012-09-20T14:14:17.966Z,1348150457.966 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2012-09-20T14:14:17.977Z,1348150457.977 [ThrusterServo] Loaded
2012-09-20T14:14:17.978Z,1348150457.978 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2012-09-20T14:14:17.978Z,1348150457.978 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2012-09-20T14:14:17.979Z,1348150457.979 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2012-09-20T14:14:17.997Z,1348150457.997 [DepthRateCalculator] Loaded
2012-09-20T14:14:17.997Z,1348150457.997 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2012-09-20T14:14:20.599Z,1348150460.599 [HFRadarModelCalc] Loaded
2012-09-20T14:14:20.599Z,1348150460.599 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2012-09-20T14:14:20.615Z,1348150460.615 [NavChart] Loaded
2012-09-20T14:14:20.615Z,1348150460.615 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2012-09-20T14:14:20.621Z,1348150460.621 [PitchRateCalculator] Loaded
2012-09-20T14:14:20.621Z,1348150460.621 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2012-09-20T14:14:20.627Z,1348150460.628 [SpeedCalculator] Loaded
2012-09-20T14:14:20.628Z,1348150460.628 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2012-09-20T14:14:20.642Z,1348150460.642 [TempGradientCalculator] Loaded
2012-09-20T14:14:20.643Z,1348150460.643 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2012-09-20T14:14:20.648Z,1348150460.648 [YawRateCalculator] Loaded
2012-09-20T14:14:20.649Z,1348150460.648 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2012-09-20T14:14:20.668Z,1348150460.668 [Navigation] Loaded
2012-09-20T14:14:20.668Z,1348150460.668 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2012-09-20T14:14:20.669Z,1348150460.669 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2012-09-20T14:14:20.669Z,1348150460.669 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2012-09-20T14:14:20.891Z,1348150460.891 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2012-09-20T14:14:20.892Z,1348150460.892 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2012-09-20T14:14:20.915Z,1348150460.915 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2012-09-20T14:14:20.915Z,1348150460.915 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2012-09-20T14:14:20.956Z,1348150460.956 [VerticalControl](DEBUG): Construct VerticalControl.
2012-09-20T14:14:21.001Z,1348150461.001 [VerticalControl] Loaded
2012-09-20T14:14:21.001Z,1348150461.001 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2012-09-20T14:14:21.002Z,1348150461.002 [HorizontalControl](DEBUG): Construct HorizontalControl.
2012-09-20T14:14:21.022Z,1348150461.022 [HorizontalControl] Loaded
2012-09-20T14:14:21.023Z,1348150461.023 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2012-09-20T14:14:21.023Z,1348150461.023 [SpeedControl](DEBUG): Construct SpeedControl.
2012-09-20T14:14:21.025Z,1348150461.025 [SpeedControl] Loaded
2012-09-20T14:14:21.025Z,1348150461.025 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2012-09-20T14:14:21.026Z,1348150461.026 [LoopControl](DEBUG): Construct LoopControl.
2012-09-20T14:14:21.027Z,1348150461.027 [LoopControl] Loaded
2012-09-20T14:14:21.027Z,1348150461.027 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2012-09-20T14:14:21.028Z,1348150461.028 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2012-09-20T14:14:21.028Z,1348150461.028 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2012-09-20T14:14:21.034Z,1348150461.033 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2012-09-20T14:14:21.038Z,1348150461.038 [AsyncPiEstimator] Loaded
2012-09-20T14:14:21.038Z,1348150461.039 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2012-09-20T14:14:21.040Z,1348150461.040 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4060A4E0
2012-09-20T14:14:21.040Z,1348150461.040 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2012-09-20T14:14:21.041Z,1348150461.041 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2012-09-20T14:14:21.202Z,1348150461.202 [AHRS_sp3003D] Loaded
2012-09-20T14:14:21.203Z,1348150461.203 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2012-09-20T14:14:21.216Z,1348150461.216 [Depth_Keller] Loaded
2012-09-20T14:14:21.216Z,1348150461.216 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2012-09-20T14:14:21.221Z,1348150461.221 [DropWeight] Loaded
2012-09-20T14:14:21.222Z,1348150461.222 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2012-09-20T14:14:21.335Z,1348150461.335 [DVL_micro] Loaded
2012-09-20T14:14:21.335Z,1348150461.335 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2012-09-20T14:14:21.336Z,1348150461.336 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 4068A4E0
2012-09-20T14:14:21.416Z,1348150461.416 [NAL9602] Loaded
2012-09-20T14:14:21.416Z,1348150461.416 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2012-09-20T14:14:21.463Z,1348150461.463 [Onboard] Loaded
2012-09-20T14:14:21.464Z,1348150461.464 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2012-09-20T14:14:21.470Z,1348150461.470 [Radio_Freewave] Loaded
2012-09-20T14:14:21.471Z,1348150461.471 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2012-09-20T14:14:21.471Z,1348150461.471 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2012-09-20T14:14:21.472Z,1348150461.472 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2012-09-20T14:14:21.547Z,1348150461.546 [CTD_NeilBrown] Loaded
2012-09-20T14:14:21.547Z,1348150461.547 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2012-09-20T14:14:21.548Z,1348150461.548 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406E24E0
2012-09-20T14:14:21.558Z,1348150461.558 [PAR_Licor] Loaded
2012-09-20T14:14:21.558Z,1348150461.558 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2012-09-20T14:14:21.575Z,1348150461.575 [Turbulence_NPS] Loaded
2012-09-20T14:14:21.575Z,1348150461.575 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread.
2012-09-20T14:14:21.576Z,1348150461.576 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 407124E0
2012-09-20T14:14:21.605Z,1348150461.605 [WetLabsBB2FL] Loaded
2012-09-20T14:14:21.605Z,1348150461.605 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2012-09-20T14:14:21.606Z,1348150461.606 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407424E0
2012-09-20T14:14:21.607Z,1348150461.607 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2012-09-20T14:14:21.609Z,1348150461.609 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2012-09-20T14:14:21.610Z,1348150461.610 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2012-09-20T14:14:21.617Z,1348150461.617 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2012-09-20T14:14:21.618Z,1348150461.618 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407724E0
2012-09-20T14:14:21.622Z,1348150461.622 [Supervisor](DEBUG): Running supervisor.
2012-09-20T14:14:21.626Z,1348150461.626 [controlThread](DEBUG): Initializing ControlThread
2012-09-20T14:14:21.627Z,1348150461.627 [InternalSim](DEBUG):  InternalSim initializing...
2012-09-20T14:14:21.733Z,1348150461.733 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2012-09-20T14:14:21.883Z,1348150461.884 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2012-09-20T14:14:21.918Z,1348150461.918 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-09-20T14:14:21.932Z,1348150461.931 [WetLabsBB2FL](INFO): Powering down
2012-09-20T14:14:21.953Z,1348150461.954 [SBIT](INFO): Initialize SBIT Component.
2012-09-20T14:14:21.954Z,1348150461.954 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 9842
2012-09-20T14:14:21.956Z,1348150461.956 [DVL_micro](INFO): Initializing
2012-09-20T14:14:21.956Z,1348150461.956 [DVL_micro](INFO): start:Powering up
2012-09-20T14:14:21.959Z,1348150461.959 [IBIT](INFO): Initialize IBIT Component.
2012-09-20T14:14:21.960Z,1348150461.960 [CBIT](DEBUG): Initialize CBIT Component.
2012-09-20T14:14:21.960Z,1348150461.960 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2012-09-20T14:14:21.987Z,1348150461.987 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-09-20T14:14:21.988Z,1348150461.988 [NavChart](DEBUG): Initialize NavChart Derivation.
2012-09-20T14:14:21.988Z,1348150461.988 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2012-09-20T14:14:21.988Z,1348150461.989 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2012-09-20T14:14:21.989Z,1348150461.989 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2012-09-20T14:14:21.990Z,1348150461.990 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2012-09-20T14:14:21.990Z,1348150461.990 [Navigation](DEBUG): Initializing Navigation.
2012-09-20T14:14:21.991Z,1348150461.991 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2012-09-20T14:14:21.993Z,1348150461.993 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2012-09-20T14:14:21.993Z,1348150461.993 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2012-09-20T14:14:21.994Z,1348150461.994 [LoopControl](DEBUG): Initialize LoopControlComponent.
2012-09-20T14:14:22.003Z,1348150462.003 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2012-09-20T14:14:22.021Z,1348150462.021 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-20T14:14:22.052Z,1348150462.052 [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-09-20T14:14:22.053Z,1348150462.053 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2012-09-20T14:14:22.095Z,1348150462.095 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-09-20T14:14:22.097Z,1348150462.097 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-09-20T14:14:22.101Z,1348150462.101 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-20T14:14:22.109Z,1348150462.109 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-09-20T14:14:22.112Z,1348150462.112 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-20T14:14:22.120Z,1348150462.120 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-09-20T14:14:22.131Z,1348150462.131 [Default:D.SetSpeed](DEBUG): Construct.
2012-09-20T14:14:22.134Z,1348150462.134 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-20T14:14:22.139Z,1348150462.139 [Default:F.Wait](DEBUG): Construct Wait.
2012-09-20T14:14:22.142Z,1348150462.142 [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-09-20T14:14:22.147Z,1348150462.147 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-09-20T14:14:22.167Z,1348150462.167 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:14:22.883Z,1348150462.883 [Radio_Freewave](INFO): Powering up
2012-09-20T14:14:22.969Z,1348150462.969 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2012-09-20T14:14:22.971Z,1348150462.971 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2012-09-20T14:14:22.977Z,1348150462.977 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-09-20T14:14:22.978Z,1348150462.978 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-09-20T14:14:22.984Z,1348150462.984 [MassServo](DEBUG): Initializing MassServo.
2012-09-20T14:14:22.994Z,1348150462.994 [RudderServo](DEBUG): Initializing EZServoServo.
2012-09-20T14:14:22.995Z,1348150462.995 [RudderServo](DEBUG): Initializing RudderServo.
2012-09-20T14:14:23.001Z,1348150463.001 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-09-20T14:14:23.003Z,1348150463.003 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-09-20T14:14:23.887Z,1348150463.886 [DVL_micro](INFO): Querying output modes
2012-09-20T14:14:23.887Z,1348150463.887 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:14:23.891Z,1348150463.891 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:14:23.891Z,1348150463.891 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:14:23.891Z,1348150463.891 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:14:23.899Z,1348150463.899 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:14:23.904Z,1348150463.904 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:14:24.893Z,1348150464.893 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2012-09-20T14:14:24.910Z,1348150464.910 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2012-09-20T14:14:24.929Z,1348150464.929 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2012-09-20T14:14:24.933Z,1348150464.933 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2012-09-20T14:14:24.938Z,1348150464.938 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2012-09-20T14:14:24.939Z,1348150464.939 [NavChartDb](INFO): Creating index for Soundings
2012-09-20T14:14:24.948Z,1348150464.948 [NavChartDb](INFO): Creating index for Contour 0p0
2012-09-20T14:14:24.961Z,1348150464.961 [NavChartDb](INFO): Creating index for Contour 1p8
2012-09-20T14:14:24.972Z,1348150464.972 [NavChartDb](INFO): Creating index for Contour 3p6
2012-09-20T14:14:24.974Z,1348150464.974 [NavChartDb](INFO): Creating index for Contour 5p4
2012-09-20T14:14:24.976Z,1348150464.976 [NavChartDb](INFO): Creating index for Contour 9p1
2012-09-20T14:14:24.978Z,1348150464.977 [NavChartDb](INFO): Creating index for Contour 10p9
2012-09-20T14:14:24.980Z,1348150464.980 [NavChartDb](INFO): Creating index for Contour 18p2
2012-09-20T14:14:24.983Z,1348150464.983 [NavChartDb](INFO): Creating index for Contour 36p5
2012-09-20T14:14:24.985Z,1348150464.985 [NavChartDb](INFO): Creating index for Contour 54p8
2012-09-20T14:14:24.996Z,1348150464.996 [NavChartDb](INFO): Creating index for Contour 73p1
2012-09-20T14:14:25.008Z,1348150465.008 [NavChartDb](INFO): Creating index for Contour 91p4
2012-09-20T14:14:25.020Z,1348150465.020 [NavChartDb](INFO): Creating index for Contour 182p8
2012-09-20T14:14:25.025Z,1348150465.025 [NavChartDb](INFO): Creating index for Contour 365p7
2012-09-20T14:14:25.027Z,1348150465.027 [NavChartDb](INFO): Creating index for Contour 548p6
2012-09-20T14:14:25.029Z,1348150465.029 [NavChartDb](INFO): Creating index for Contour 731p5
2012-09-20T14:14:25.031Z,1348150465.031 [NavChartDb](INFO): Creating index for Contour 914p4
2012-09-20T14:14:25.034Z,1348150465.034 [NavChartDb](INFO): Creating index for Contour 1097p2
2012-09-20T14:14:25.037Z,1348150465.037 [NavChartDb](INFO): Creating index for Contour 1280p1
2012-09-20T14:14:25.064Z,1348150465.064 [NavChartDb](INFO): Creating index for Contour 1463p0
2012-09-20T14:14:25.131Z,1348150465.131 [NavChartDb](INFO): Creating index for Contour 1645p9
2012-09-20T14:14:25.133Z,1348150465.133 [NavChartDb](INFO): Creating index for Contour 1828p8
2012-09-20T14:14:25.135Z,1348150465.135 [NavChartDb](INFO): Creating index for Contour 2011p6
2012-09-20T14:14:25.137Z,1348150465.137 [NavChartDb](INFO): Creating index for Contour 2194p5
2012-09-20T14:14:25.140Z,1348150465.140 [NavChartDb](INFO): Creating index for Contour 2377p4
2012-09-20T14:14:25.142Z,1348150465.142 [NavChartDb](INFO): Creating index for Contour 2560p3
2012-09-20T14:14:25.162Z,1348150465.162 [NavChartDb](INFO): Creating index for Contour 2743p2
2012-09-20T14:14:25.668Z,1348150465.668 [NavChartDb](INFO): Creating index for Contour 2926p0
2012-09-20T14:14:25.910Z,1348150465.911 [NavChartDb](INFO): Creating index for Contour 3108p9
2012-09-20T14:14:25.912Z,1348150465.912 [NavChartDb](INFO): Creating index for Contour 3291p8
2012-09-20T14:14:25.930Z,1348150465.930 [NavChartDb](INFO): Creating index for Contour 3474p7
2012-09-20T14:14:25.932Z,1348150465.932 [NavChartDb](INFO): Creating index for Contour 3657p6
2012-09-20T14:14:26.433Z,1348150466.433 [NAL9602](INFO): Powering up NAL9602
2012-09-20T14:14:36.867Z,1348150476.867 [NAL9602](INFO): NAL9602 initialized
2012-09-20T14:14:37.665Z,1348150477.665 [SBIT](IMPORTANT): Beginning Startup BIT
2012-09-20T14:14:37.668Z,1348150477.668 [CBIT](IMPORTANT): Beginning GF scan
2012-09-20T14:14:39.707Z,1348150479.707 [ElevatorServo](FAULT): Overload Error
2012-09-20T14:14:39.707Z,1348150479.707 [ElevatorServo] Hardware Fault, FailCount= 1
2012-09-20T14:14:39.707Z,1348150479.707 [ElevatorServo](ERROR): Hardware Fault
2012-09-20T14:14:39.758Z,1348150479.758 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2012-09-20T14:14:40.048Z,1348150480.048 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-09-20T14:14:40.048Z,1348150480.048 [ElevatorServo](INFO): Powering down
2012-09-20T14:14:40.086Z,1348150480.086 [CBIT](INFO): Clearing failed state for component ElevatorServo
2012-09-20T14:14:40.086Z,1348150480.086 [ElevatorServo] No Fault, FailCount= 1
2012-09-20T14:14:40.851Z,1348150480.851 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-09-20T14:14:40.972Z,1348150480.972 [ElevatorServo](DEBUG): Initialization error opening port: port not yet opened
2012-09-20T14:14:40.972Z,1348150480.972 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-09-20T14:14:43.232Z,1348150483.232 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2012-09-20T14:14:43.232Z,1348150483.232 [DropWeight] Hardware Fault, FailCount= 1
2012-09-20T14:14:43.232Z,1348150483.232 [DropWeight](ERROR): Hardware Fault
2012-09-20T14:14:43.275Z,1348150483.275 [CBIT](ERROR): Hardware Fault in component: DropWeight
2012-09-20T14:14:43.275Z,1348150483.276 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2012-09-20T14:14:45.674Z,1348150485.674 [ElevatorServo](FAULT): Overload Error
2012-09-20T14:14:45.674Z,1348150485.674 [ElevatorServo] Hardware Fault, FailCount= 2
2012-09-20T14:14:45.675Z,1348150485.675 [ElevatorServo](ERROR): Hardware Fault
2012-09-20T14:14:45.703Z,1348150485.703 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2012-09-20T14:14:46.054Z,1348150486.054 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-09-20T14:14:46.054Z,1348150486.054 [ElevatorServo](INFO): Powering down
2012-09-20T14:14:46.059Z,1348150486.059 [CBIT](INFO): Clearing failed state for component ElevatorServo
2012-09-20T14:14:46.059Z,1348150486.059 [ElevatorServo] No Fault, FailCount= 2
2012-09-20T14:14:46.850Z,1348150486.851 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-09-20T14:14:46.973Z,1348150486.973 [ElevatorServo](DEBUG): Initialization error opening port: port not yet opened
2012-09-20T14:14:46.974Z,1348150486.974 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-09-20T14:14:50.874Z,1348150490.874 [SBIT](FAULT): Elevator: EXPECTED:15.000000 ACTUAL:-1.475809
2012-09-20T14:14:50.875Z,1348150490.875 [SBIT](FAULT): Mass: EXPECTED:0.004800 ACTUAL:-0.001040
2012-09-20T14:14:51.675Z,1348150491.675 [ElevatorServo](FAULT): Overload Error
2012-09-20T14:14:51.675Z,1348150491.675 [ElevatorServo] Hardware Fault, FailCount= 3
2012-09-20T14:14:51.675Z,1348150491.675 [ElevatorServo](ERROR): Hardware Fault
2012-09-20T14:14:51.703Z,1348150491.703 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2012-09-20T14:14:51.703Z,1348150491.703 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2012-09-20T14:14:52.047Z,1348150492.047 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-09-20T14:14:52.047Z,1348150492.047 [ElevatorServo](INFO): Powering down
2012-09-20T14:15:04.455Z,1348150504.455 [CBIT](IMPORTANT): No ground fault detected
2012-09-20T14:15:31.252Z,1348150531.252 [SBIT](CRITICAL): SBIT FAILED
2012-09-20T14:15:31.643Z,1348150531.643 [MissionManager](IMPORTANT): Started mission Startup
2012-09-20T14:15:31.643Z,1348150531.643 [Startup] Running Loop=1
2012-09-20T14:15:31.643Z,1348150531.643 [Startup](INFO): Aggregate::initialize Startup
2012-09-20T14:15:31.643Z,1348150531.643 [Startup:A.GoToSurface] Running Loop=1
2012-09-20T14:15:31.643Z,1348150531.643 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-20T14:15:31.649Z,1348150531.649 [Startup:StartupSatComms] Running Loop=1
2012-09-20T14:15:31.649Z,1348150531.649 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2012-09-20T14:15:31.649Z,1348150531.649 [Startup:StartupSatComms:A] Running Loop=1
2012-09-20T14:15:32.043Z,1348150532.043 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-09-20T14:15:52.191Z,1348150552.191 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2012-09-20T14:15:52.192Z,1348150552.192 [CommandLine](IMPORTANT): got command set NAL9602.longitude_fix -121.779999 degree
2012-09-20T14:15:52.341Z,1348150552.341 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-09-20T14:15:52.457Z,1348150552.457 [Startup:StartupSatComms:A] Stopped
2012-09-20T14:15:52.457Z,1348150552.457 [Startup:StartupSatComms:B] Running Loop=1
2012-09-20T14:15:52.754Z,1348150552.754 [NavChartDb](INFO): Charting Soundings
2012-09-20T14:15:53.401Z,1348150553.401 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-09-20T14:15:53.781Z,1348150553.781 [AHRS_sp3003D](ERROR): readPitchRollBin UART error: serial timeout
2012-09-20T14:15:53.781Z,1348150553.781 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2012-09-20T14:15:53.781Z,1348150553.781 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:15:53.781Z,1348150553.781 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:15:53.828Z,1348150553.829 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:15:53.875Z,1348150553.875 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:15:54.051Z,1348150554.051 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:15:54.051Z,1348150554.051 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:15:54.614Z,1348150554.614 [NavChartDb](INFO): Charting 0p0
2012-09-20T14:15:55.245Z,1348150555.245 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:15:58.096Z,1348150558.096 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-20T14:15:58.096Z,1348150558.096 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2012-09-20T14:15:58.096Z,1348150558.096 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:15:58.096Z,1348150558.096 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:15:58.405Z,1348150558.405 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:15:58.542Z,1348150558.542 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:15:58.660Z,1348150558.661 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:15:59.332Z,1348150559.332 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:15:59.332Z,1348150559.332 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:15:59.864Z,1348150559.864 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:16:00.186Z,1348150560.186 [DVL_micro](INFO): Querying output modes
2012-09-20T14:16:00.186Z,1348150560.187 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:16:00.214Z,1348150560.214 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:16:00.214Z,1348150560.214 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:16:00.215Z,1348150560.215 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:16:00.223Z,1348150560.223 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:16:00.225Z,1348150560.225 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:16:01.316Z,1348150561.316 [NavChartDb](INFO): Charting 1p8
2012-09-20T14:16:03.719Z,1348150563.719 [NavChartDb](INFO): Charting 3p6
2012-09-20T14:16:06.119Z,1348150566.118 [NavChartDb](INFO): Charting 5p4
2012-09-20T14:16:09.603Z,1348150569.603 [NavChartDb](INFO): Charting 9p1
2012-09-20T14:16:11.203Z,1348150571.203 [NavChartDb](INFO): Charting 10p9
2012-09-20T14:16:18.623Z,1348150578.623 [AHRS_sp3003D](ERROR): readMagneticsBin UART error: serial timeout
2012-09-20T14:16:18.623Z,1348150578.623 [AHRS_sp3003D](FAULT): Read Magnetics Failure.
2012-09-20T14:16:18.623Z,1348150578.623 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:16:18.623Z,1348150578.623 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:16:18.892Z,1348150578.892 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:16:19.086Z,1348150579.086 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:16:19.141Z,1348150579.141 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:16:19.259Z,1348150579.260 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:16:19.260Z,1348150579.260 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:16:20.337Z,1348150580.337 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:16:20.398Z,1348150580.398 [DVL_micro](INFO): Querying output modes
2012-09-20T14:16:20.399Z,1348150580.399 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:16:20.427Z,1348150580.427 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:16:20.427Z,1348150580.427 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:16:20.427Z,1348150580.427 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:16:20.435Z,1348150580.435 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:16:20.439Z,1348150580.439 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:16:24.699Z,1348150584.699 [AHRS_sp3003D](ERROR): readPitchRollBin UART error: serial timeout
2012-09-20T14:16:24.699Z,1348150584.699 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2012-09-20T14:16:24.699Z,1348150584.699 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:16:24.699Z,1348150584.699 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:16:24.753Z,1348150584.753 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:16:24.801Z,1348150584.801 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:16:25.111Z,1348150585.111 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:16:25.253Z,1348150585.253 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:16:25.254Z,1348150585.253 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:16:26.097Z,1348150586.097 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:16:26.618Z,1348150586.618 [DVL_micro](INFO): Querying output modes
2012-09-20T14:16:26.619Z,1348150586.619 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:16:26.626Z,1348150586.626 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:16:26.627Z,1348150586.626 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:16:26.627Z,1348150586.627 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:16:26.635Z,1348150586.635 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:16:26.637Z,1348150586.637 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:16:30.365Z,1348150590.365 [AHRS_sp3003D](ERROR): readMagneticsBin UART error: serial timeout
2012-09-20T14:16:30.365Z,1348150590.365 [AHRS_sp3003D](FAULT): Read Magnetics Failure.
2012-09-20T14:16:30.365Z,1348150590.365 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:16:30.365Z,1348150590.365 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:16:30.743Z,1348150590.743 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:16:30.919Z,1348150590.919 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:16:30.973Z,1348150590.973 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:16:31.150Z,1348150591.151 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:16:31.150Z,1348150591.151 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:16:32.550Z,1348150592.550 [DVL_micro](INFO): Querying output modes
2012-09-20T14:16:32.550Z,1348150592.550 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:16:32.570Z,1348150592.570 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:16:32.571Z,1348150592.570 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:16:32.571Z,1348150592.571 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:16:32.579Z,1348150592.579 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:16:32.581Z,1348150592.581 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:16:33.444Z,1348150593.444 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:16:36.423Z,1348150596.423 [AHRS_sp3003D](ERROR): readAccelVecBin UART error: serial timeout
2012-09-20T14:16:36.424Z,1348150596.424 [AHRS_sp3003D](FAULT): Read Acceleration Vector Failure.
2012-09-20T14:16:36.424Z,1348150596.424 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:16:36.424Z,1348150596.424 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:16:36.557Z,1348150596.557 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:16:36.610Z,1348150596.610 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:16:36.851Z,1348150596.851 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:16:37.015Z,1348150597.015 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:16:37.015Z,1348150597.015 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:16:38.033Z,1348150598.033 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:16:38.358Z,1348150598.358 [DVL_micro](INFO): Querying output modes
2012-09-20T14:16:38.359Z,1348150598.359 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:16:38.363Z,1348150598.362 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:16:38.363Z,1348150598.363 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:16:38.363Z,1348150598.363 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:16:38.367Z,1348150598.367 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:16:38.369Z,1348150598.369 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:16:41.214Z,1348150601.214 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-20T14:16:41.214Z,1348150601.214 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2012-09-20T14:16:41.214Z,1348150601.214 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:16:41.214Z,1348150601.214 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:16:41.511Z,1348150601.511 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:16:42.106Z,1348150602.106 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:16:42.178Z,1348150602.178 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:16:42.300Z,1348150602.300 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:16:42.300Z,1348150602.300 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:16:43.019Z,1348150603.019 [DVL_micro](INFO): Querying output modes
2012-09-20T14:16:43.019Z,1348150603.019 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:16:43.023Z,1348150603.023 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:16:43.023Z,1348150603.023 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:16:43.023Z,1348150603.023 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:16:43.031Z,1348150603.031 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:16:43.033Z,1348150603.033 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:16:43.525Z,1348150603.525 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:16:51.571Z,1348150611.571 [NavChartDb](INFO): Charting 1828p8
2012-09-20T14:16:53.334Z,1348150613.334 [Startup:StartupSatComms:B](INFO): Timed out from 2012-09-20T14:15:52.5Z
2012-09-20T14:16:53.335Z,1348150613.335 [Startup:StartupSatComms:A_Timeout] Running Loop=1
2012-09-20T14:16:53.335Z,1348150613.335 [Startup:StartupSatComms:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A_Timeout
2012-09-20T14:16:53.335Z,1348150613.335 [Startup:StartupSatComms:A_Timeout](INFO): Completed Startup:StartupSatComms:A_Timeout
2012-09-20T14:16:53.335Z,1348150613.335 [Startup:StartupSatComms:B] Stopped
2012-09-20T14:16:53.335Z,1348150613.335 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2012-09-20T14:16:53.335Z,1348150613.335 [Startup:StartupSatComms] Stopped
2012-09-20T14:16:53.335Z,1348150613.335 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2012-09-20T14:16:53.336Z,1348150613.336 [Startup](INFO): Completed Startup
2012-09-20T14:16:53.336Z,1348150613.336 [Startup] Stopped
2012-09-20T14:16:53.336Z,1348150613.336 [Startup](INFO): Aggregate::uninitialize Startup
2012-09-20T14:16:53.336Z,1348150613.336 [Startup:A.GoToSurface] Stopped
2012-09-20T14:16:53.336Z,1348150613.336 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-20T14:16:53.893Z,1348150613.893 [MissionManager](IMPORTANT): Started mission Default
2012-09-20T14:16:53.893Z,1348150613.894 [Default] Running Loop=1
2012-09-20T14:16:53.894Z,1348150613.894 [Default](INFO): Aggregate::initialize Default
2012-09-20T14:16:53.894Z,1348150613.894 [Default:D.SetSpeed] Running Loop=1
2012-09-20T14:16:53.894Z,1348150613.894 [Default:D.SetSpeed](DEBUG): Initialize.
2012-09-20T14:16:53.894Z,1348150613.894 [Default:E.GoToSurface] Running Loop=1
2012-09-20T14:16:53.894Z,1348150613.894 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-20T14:16:53.895Z,1348150613.895 [Default:Iridium] Running Loop=1
2012-09-20T14:16:53.895Z,1348150613.895 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-09-20T14:16:53.895Z,1348150613.895 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-09-20T14:16:53.895Z,1348150613.895 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-09-20T14:16:53.895Z,1348150613.895 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-09-20T14:16:53.895Z,1348150613.895 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-20T14:16:53.896Z,1348150613.895 [Default:E.GoToSurface] Running Loop=1
2012-09-20T14:16:53.905Z,1348150613.905 [Default:D.SetSpeed] Running Loop=1
2012-09-20T14:16:53.914Z,1348150613.914 [Default:Iridium:B.GoToSurface] Stopped
2012-09-20T14:16:53.914Z,1348150613.914 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-20T14:16:53.915Z,1348150613.915 [Default:Iridium:Read_Iridium] Running Loop=1
2012-09-20T14:16:53.915Z,1348150613.915 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-09-20T14:16:53.919Z,1348150613.919 [Default:GPS] Running Loop=1
2012-09-20T14:16:53.919Z,1348150613.920 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-09-20T14:16:53.920Z,1348150613.920 [Default:GPS:A.SetSpeed] Running Loop=1
2012-09-20T14:16:53.920Z,1348150613.920 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-09-20T14:16:53.920Z,1348150613.920 [Default:GPS:B.GoToSurface] Running Loop=1
2012-09-20T14:16:53.920Z,1348150613.920 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-20T14:16:53.925Z,1348150613.925 [Default:GPS:B.GoToSurface] Stopped
2012-09-20T14:16:53.925Z,1348150613.925 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-20T14:16:53.925Z,1348150613.925 [Default:GPS:Read_GPS] Running Loop=1
2012-09-20T14:16:53.925Z,1348150613.925 [Default:GPS:A.SetSpeed] Running Loop=1
2012-09-20T14:16:54.783Z,1348150614.783 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-09-20T14:16:54.786Z,1348150614.786 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-09-20T14:17:02.255Z,1348150622.255 [AHRS_sp3003D](ERROR): readPitchRollBin UART error: serial timeout
2012-09-20T14:17:02.255Z,1348150622.255 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2012-09-20T14:17:02.255Z,1348150622.255 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:17:02.255Z,1348150622.255 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:17:02.379Z,1348150622.379 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:17:02.448Z,1348150622.448 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:17:02.763Z,1348150622.763 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:17:03.255Z,1348150623.255 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:17:03.255Z,1348150623.255 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:17:04.466Z,1348150624.466 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:17:04.482Z,1348150624.482 [DVL_micro](INFO): Querying output modes
2012-09-20T14:17:04.483Z,1348150624.483 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:17:04.486Z,1348150624.487 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:17:04.487Z,1348150624.487 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:17:04.487Z,1348150624.487 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:17:04.495Z,1348150624.494 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:17:04.497Z,1348150624.497 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:17:05.903Z,1348150625.903 [AHRS_sp3003D](ERROR): readMagneticsBin UART error: serial timeout
2012-09-20T14:17:05.903Z,1348150625.903 [AHRS_sp3003D](FAULT): Read Magnetics Failure.
2012-09-20T14:17:05.903Z,1348150625.903 [AHRS_sp3003D] Communications Fault, FailCount= 2
2012-09-20T14:17:05.903Z,1348150625.903 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:17:06.048Z,1348150626.048 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:17:06.099Z,1348150626.099 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:17:06.371Z,1348150626.371 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:17:07.278Z,1348150627.278 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:17:07.278Z,1348150627.279 [AHRS_sp3003D] No Fault, FailCount= 2
2012-09-20T14:17:08.150Z,1348150628.150 [DVL_micro](INFO): Querying output modes
2012-09-20T14:17:08.151Z,1348150628.151 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:17:08.547Z,1348150628.547 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:17:08.547Z,1348150628.547 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:17:08.547Z,1348150628.547 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:17:08.718Z,1348150628.718 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:17:08.747Z,1348150628.747 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:17:08.749Z,1348150628.749 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:17:12.911Z,1348150632.911 [AHRS_sp3003D](ERROR): readAccelVecBin UART error: serial timeout
2012-09-20T14:17:12.911Z,1348150632.911 [AHRS_sp3003D](FAULT): Read Acceleration Vector Failure.
2012-09-20T14:17:12.912Z,1348150632.912 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:17:12.912Z,1348150632.912 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:17:13.207Z,1348150633.207 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:17:13.308Z,1348150633.308 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:17:13.369Z,1348150633.369 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:17:14.251Z,1348150634.251 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:17:14.251Z,1348150634.251 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:17:14.714Z,1348150634.714 [DVL_micro](INFO): Querying output modes
2012-09-20T14:17:14.715Z,1348150634.715 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:17:14.719Z,1348150634.718 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:17:14.719Z,1348150634.719 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:17:14.719Z,1348150634.719 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:17:14.723Z,1348150634.723 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:17:14.725Z,1348150634.725 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:17:15.322Z,1348150635.322 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:17:18.307Z,1348150638.307 [AHRS_sp3003D](ERROR): readPitchRollBin UART error: serial timeout
2012-09-20T14:17:18.307Z,1348150638.307 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2012-09-20T14:17:18.307Z,1348150638.307 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:17:18.307Z,1348150638.307 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:17:18.432Z,1348150638.432 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:17:18.480Z,1348150638.480 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:17:18.575Z,1348150638.575 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:17:19.332Z,1348150639.332 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:17:19.332Z,1348150639.332 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:17:19.874Z,1348150639.874 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:17:20.238Z,1348150640.238 [DVL_micro](INFO): Querying output modes
2012-09-20T14:17:20.239Z,1348150640.239 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:17:20.262Z,1348150640.262 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:17:20.262Z,1348150640.263 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:17:20.263Z,1348150640.263 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:17:20.267Z,1348150640.267 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:17:20.269Z,1348150640.269 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:17:23.103Z,1348150643.103 [AHRS_sp3003D](ERROR): readMagneticsBin UART error: serial timeout
2012-09-20T14:17:23.103Z,1348150643.103 [AHRS_sp3003D](FAULT): Read Magnetics Failure.
2012-09-20T14:17:23.103Z,1348150643.103 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:17:23.103Z,1348150643.103 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:17:23.503Z,1348150643.503 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:17:23.544Z,1348150643.544 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:17:23.602Z,1348150643.602 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:17:24.172Z,1348150644.172 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:17:24.172Z,1348150644.172 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:17:25.010Z,1348150645.010 [DVL_micro](INFO): Querying output modes
2012-09-20T14:17:25.011Z,1348150645.011 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:17:25.014Z,1348150645.014 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:17:25.015Z,1348150645.015 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:17:25.015Z,1348150645.015 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:17:25.019Z,1348150645.019 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:17:25.021Z,1348150645.021 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:17:25.560Z,1348150645.560 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:17:34.535Z,1348150654.535 [CommandLine](IMPORTANT): got command failComponent hardware DVL_micro
2012-09-20T14:17:34.535Z,1348150654.535 [DVL_micro] Hardware Fault, FailCount= 1
2012-09-20T14:17:34.535Z,1348150654.535 [DVL_micro](ERROR): Hardware Fault
2012-09-20T14:17:34.536Z,1348150654.536 [CommandLine](IMPORTANT): DVL_micro failureMode is Hardware Fault
2012-09-20T14:17:34.547Z,1348150654.547 [DVL_micro](INFO): uninitialize:Powering down
2012-09-20T14:17:34.608Z,1348150654.608 [CBIT](ERROR): Hardware Fault in component: DVL_micro
2012-09-20T14:17:34.609Z,1348150654.609 [CBIT](INFO): Clearing failed state for component DVL_micro
2012-09-20T14:17:34.609Z,1348150654.609 [DVL_micro] No Fault, FailCount= 1
2012-09-20T14:17:34.994Z,1348150654.994 [DVL_micro](INFO): Initializing
2012-09-20T14:17:34.994Z,1348150654.994 [DVL_micro](INFO): start:Powering up
2012-09-20T14:17:36.506Z,1348150656.506 [DVL_micro](INFO): Querying output modes
2012-09-20T14:17:36.507Z,1348150656.507 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:17:36.510Z,1348150656.510 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:17:36.511Z,1348150656.510 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:17:36.511Z,1348150656.511 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:17:36.515Z,1348150656.515 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:17:36.515Z,1348150656.515 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:17:36.530Z,1348150656.530 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-20T14:17:36.530Z,1348150656.530 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2012-09-20T14:17:36.530Z,1348150656.530 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:17:36.530Z,1348150656.530 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:17:37.203Z,1348150657.203 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:17:37.637Z,1348150657.637 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:17:37.802Z,1348150657.802 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:17:37.926Z,1348150657.926 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:17:37.926Z,1348150657.926 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:17:38.615Z,1348150658.615 [NavChartDb](INFO): Charting 2011p6
2012-09-20T14:17:38.710Z,1348150658.710 [DVL_micro](INFO): Querying output modes
2012-09-20T14:17:38.711Z,1348150658.711 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:17:38.718Z,1348150658.718 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:17:38.719Z,1348150658.718 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:17:38.719Z,1348150658.719 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:17:38.726Z,1348150658.727 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:17:38.729Z,1348150658.729 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:17:38.965Z,1348150658.965 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:17:41.385Z,1348150661.385 [NavChartDb](INFO): Charting 2194p5
2012-09-20T14:17:42.287Z,1348150662.287 [AHRS_sp3003D](ERROR): readMagneticsBin UART error: serial timeout
2012-09-20T14:17:42.287Z,1348150662.287 [AHRS_sp3003D](FAULT): Read Magnetics Failure.
2012-09-20T14:17:42.287Z,1348150662.287 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:17:42.287Z,1348150662.287 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:17:42.323Z,1348150662.323 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:17:42.371Z,1348150662.371 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:17:42.727Z,1348150662.727 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:17:42.826Z,1348150662.826 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:17:42.827Z,1348150662.827 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:17:43.673Z,1348150663.673 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:17:44.494Z,1348150664.494 [DVL_micro](INFO): Querying output modes
2012-09-20T14:17:44.495Z,1348150664.495 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:17:44.498Z,1348150664.499 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:17:44.499Z,1348150664.499 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:17:44.499Z,1348150664.499 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:17:44.503Z,1348150664.503 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:17:44.505Z,1348150664.505 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:17:46.451Z,1348150666.451 [NavChartDb](INFO): Charting 2743p2
2012-09-20T14:17:48.559Z,1348150668.559 [AHRS_sp3003D](ERROR): readAccelVecBin UART error: serial timeout
2012-09-20T14:17:48.559Z,1348150668.559 [AHRS_sp3003D](FAULT): Read Acceleration Vector Failure.
2012-09-20T14:17:48.560Z,1348150668.560 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-20T14:17:48.560Z,1348150668.560 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:17:48.579Z,1348150668.579 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:17:49.651Z,1348150669.651 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:17:49.723Z,1348150669.723 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:17:50.086Z,1348150670.086 [DVL_micro](INFO): Querying output modes
2012-09-20T14:17:50.087Z,1348150670.087 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:17:50.178Z,1348150670.178 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:17:50.178Z,1348150670.178 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:17:50.179Z,1348150670.179 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:17:50.183Z,1348150670.183 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:17:50.667Z,1348150670.667 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:17:50.667Z,1348150670.667 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-20T14:17:51.919Z,1348150671.919 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:17:52.564Z,1348150672.564 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:17:54.361Z,1348150674.361 [AHRS_sp3003D](ERROR): readMagneticsBin UART error: serial timeout
2012-09-20T14:17:54.361Z,1348150674.362 [AHRS_sp3003D](FAULT): Read Magnetics Failure.
2012-09-20T14:17:54.362Z,1348150674.362 [AHRS_sp3003D] Communications Fault, FailCount= 2
2012-09-20T14:17:54.362Z,1348150674.362 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:17:54.402Z,1348150674.402 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:17:54.452Z,1348150674.452 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:17:54.600Z,1348150674.600 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:17:54.600Z,1348150674.600 [AHRS_sp3003D] No Fault, FailCount= 2
2012-09-20T14:17:56.479Z,1348150676.479 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:17:59.015Z,1348150679.015 [AHRS_sp3003D](ERROR): readPitchRollBin UART error: serial timeout
2012-09-20T14:17:59.015Z,1348150679.015 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2012-09-20T14:17:59.015Z,1348150679.015 [AHRS_sp3003D] Communications Fault, FailCount= 3
2012-09-20T14:17:59.015Z,1348150679.015 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-20T14:17:59.647Z,1348150679.647 [DVL_micro](INFO): resume:Powering up
2012-09-20T14:18:00.043Z,1348150680.043 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-20T14:18:00.112Z,1348150680.112 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:18:00.738Z,1348150680.738 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-20T14:18:00.738Z,1348150680.738 [AHRS_sp3003D] No Fault, FailCount= 3
2012-09-20T14:18:01.438Z,1348150681.438 [DVL_micro](INFO): Querying output modes
2012-09-20T14:18:01.438Z,1348150681.438 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:18:01.467Z,1348150681.467 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:18:01.467Z,1348150681.467 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:18:01.468Z,1348150681.467 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:18:01.475Z,1348150681.475 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:18:01.477Z,1348150681.477 [DVL_micro](INFO): pause:Powering down
2012-09-20T14:18:01.949Z,1348150681.949 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-20T14:18:04.971Z,1348150684.971 [CommandLine](IMPORTANT): got command failComponent hardware DVL_micro
2012-09-20T14:18:04.971Z,1348150684.971 [DVL_micro] Hardware Fault, FailCount= 2
2012-09-20T14:18:04.971Z,1348150684.971 [DVL_micro](ERROR): Hardware Fault
2012-09-20T14:18:04.972Z,1348150684.972 [CommandLine](IMPORTANT): DVL_micro failureMode is Hardware Fault
2012-09-20T14:18:05.331Z,1348150685.331 [DVL_micro](INFO): uninitialize:Powering down
2012-09-20T14:18:06.024Z,1348150686.024 [CBIT](ERROR): Hardware Fault in component: DVL_micro
2012-09-20T14:18:06.024Z,1348150686.024 [CBIT](INFO): Clearing failed state for component DVL_micro
2012-09-20T14:18:06.024Z,1348150686.024 [DVL_micro] No Fault, FailCount= 2
2012-09-20T14:18:06.034Z,1348150686.033 [DVL_micro](INFO): Initializing
2012-09-20T14:18:06.034Z,1348150686.034 [DVL_micro](INFO): start:Powering up
2012-09-20T14:18:06.147Z,1348150686.147 [CommandLine](IMPORTANT): got command failComponent hardware DVL_micro
2012-09-20T14:18:06.147Z,1348150686.147 [DVL_micro] Hardware Fault, FailCount= 3
2012-09-20T14:18:06.147Z,1348150686.147 [DVL_micro](ERROR): Hardware Fault
2012-09-20T14:18:06.148Z,1348150686.148 [CommandLine](IMPORTANT): DVL_micro failureMode is Hardware Fault
2012-09-20T14:18:06.488Z,1348150686.488 [CBIT](ERROR): Hardware Fault in component: DVL_micro
2012-09-20T14:18:07.023Z,1348150687.023 [CommandLine](IMPORTANT): got command failComponent hardware DVL_micro
2012-09-20T14:18:07.023Z,1348150687.023 [CommandLine](IMPORTANT): DVL_micro failureMode is Hardware Fault
2012-09-20T14:18:07.559Z,1348150687.559 [CommandLine](IMPORTANT): got command failComponent hardware DVL_micro
2012-09-20T14:18:07.559Z,1348150687.559 [CommandLine](IMPORTANT): DVL_micro failureMode is Hardware Fault
2012-09-20T14:18:07.814Z,1348150687.814 [DVL_micro](INFO): Querying output modes
2012-09-20T14:18:07.814Z,1348150687.815 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:18:07.870Z,1348150687.870 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:18:07.871Z,1348150687.871 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:18:07.871Z,1348150687.871 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:18:07.879Z,1348150687.879 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:18:07.880Z,1348150687.880 [DVL_micro](INFO): uninitialize:Powering down
2012-09-20T14:18:07.976Z,1348150687.976 [CBIT](INFO): Clearing failed state for component DVL_micro
2012-09-20T14:18:07.976Z,1348150687.976 [DVL_micro] No Fault, FailCount= 3
2012-09-20T14:18:08.072Z,1348150688.072 [CommandLine](IMPORTANT): got command failComponent hardware DVL_micro
2012-09-20T14:18:08.072Z,1348150688.072 [DVL_micro] Hardware Fault, FailCount= 4
2012-09-20T14:18:08.072Z,1348150688.072 [DVL_micro](ERROR): Hardware Fault
2012-09-20T14:18:08.073Z,1348150688.073 [CommandLine](IMPORTANT): DVL_micro failureMode is Hardware Fault
2012-09-20T14:18:08.515Z,1348150688.515 [CommandLine](IMPORTANT): got command failComponent hardware DVL_micro
2012-09-20T14:18:08.516Z,1348150688.516 [CommandLine](IMPORTANT): DVL_micro failureMode is Hardware Fault
2012-09-20T14:18:08.588Z,1348150688.588 [CBIT](ERROR): Hardware Fault in component: DVL_micro
2012-09-20T14:18:08.588Z,1348150688.588 [CBIT](INFO): Clearing failed state for component DVL_micro
2012-09-20T14:18:08.588Z,1348150688.588 [DVL_micro] No Fault, FailCount= 4
2012-09-20T14:18:08.598Z,1348150688.598 [DVL_micro](INFO): Initializing
2012-09-20T14:18:08.598Z,1348150688.598 [DVL_micro](INFO): start:Powering up
2012-09-20T14:18:09.027Z,1348150689.027 [CommandLine](IMPORTANT): got command failComponent hardware DVL_micro
2012-09-20T14:18:09.027Z,1348150689.027 [DVL_micro] Hardware Fault, FailCount= 5
2012-09-20T14:18:09.027Z,1348150689.027 [DVL_micro](ERROR): Hardware Fault
2012-09-20T14:18:09.028Z,1348150689.028 [CommandLine](IMPORTANT): DVL_micro failureMode is Hardware Fault
2012-09-20T14:18:10.107Z,1348150690.107 [DVL_micro](INFO): Querying output modes
2012-09-20T14:18:10.107Z,1348150690.107 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-20T14:18:10.111Z,1348150690.111 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-20T14:18:10.111Z,1348150690.111 [DVL_micro](INFO): NQ1 output enabled
2012-09-20T14:18:10.111Z,1348150690.111 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-20T14:18:10.115Z,1348150690.115 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-20T14:18:10.116Z,1348150690.116 [DVL_micro](INFO): uninitialize:Powering down
2012-09-20T14:18:10.155Z,1348150690.155 [CBIT](ERROR): Hardware Fault in component: DVL_micro
2012-09-20T14:18:10.156Z,1348150690.156 [CBIT](CRITICAL): Hardware Fault in component: DVL_micro
2012-09-20T14:18:11.183Z,1348150691.183 [CommandLine](IMPORTANT): got command failComponent hardware DVL_micro
2012-09-20T14:18:11.183Z,1348150691.183 [CommandLine](IMPORTANT): DVL_micro failureMode is Hardware Fault
2012-09-20T14:19:40.883Z,1348150780.883 [CommandLine](IMPORTANT): got command quit
2012-09-20T14:19:42.330Z,1348150782.330 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-09-20T14:19:42.330Z,1348150782.330 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-09-20T14:19:43.251Z,1348150783.251 [CBIT](INFO): Clearing failed count for component DropWeight
2012-09-20T14:19:43.251Z,1348150783.251 [DropWeight] No Fault, FailCount= 1
2012-09-20T14:20:04.878Z,1348150804.878 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2012-09-20T14:20:05.143Z,1348150805.143 [WetLabsBB2FL](INFO): Powering down
2012-09-20T14:20:05.158Z,1348150805.158 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler
2012-09-20T14:20:05.495Z,1348150805.495 [Turbulence_NPS](INFO): Powering down
2012-09-20T14:20:05.498Z,1348150805.498 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-09-20T14:20:05.879Z,1348150805.879 [CTD_NeilBrown](INFO): Powering down
2012-09-20T14:20:05.898Z,1348150805.898 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2012-09-20T14:20:05.902Z,1348150805.902 [DVL_micro](INFO): uninitialize:Powering down
2012-09-20T14:20:05.911Z,1348150805.911 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-09-20T14:20:06.187Z,1348150806.187 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-09-20T14:20:06.199Z,1348150806.199 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-09-20T14:20:06.201Z,1348150806.201 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-09-20T14:20:06.211Z,1348150806.211 [controlThread](DEBUG): Uninitializing ControlThread
2012-09-20T14:20:06.211Z,1348150806.211 [AHRS_sp3003D](INFO): Powering down
2012-09-20T14:20:06.299Z,1348150806.299 [NAL9602](INFO): Powering down
2012-09-20T14:20:06.301Z,1348150806.301 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-09-20T14:20:06.301Z,1348150806.301 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-09-20T14:20:06.303Z,1348150806.303 [Default] Stopped
2012-09-20T14:20:06.303Z,1348150806.303 [Default](INFO): Aggregate::uninitialize Default
2012-09-20T14:20:06.303Z,1348150806.303 [Default:GPS] Stopped
2012-09-20T14:20:06.303Z,1348150806.303 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-09-20T14:20:06.303Z,1348150806.303 [Default:GPS:A.SetSpeed] Stopped
2012-09-20T14:20:06.303Z,1348150806.303 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-09-20T14:20:06.303Z,1348150806.303 [Default:GPS:Read_GPS] Stopped
2012-09-20T14:20:06.303Z,1348150806.303 [Default:Iridium] Stopped
2012-09-20T14:20:06.303Z,1348150806.303 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-09-20T14:20:06.303Z,1348150806.303 [Default:Iridium:A.SetSpeed] Stopped
2012-09-20T14:20:06.303Z,1348150806.303 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-09-20T14:20:06.304Z,1348150806.304 [Default:Iridium:Read_Iridium] Stopped
2012-09-20T14:20:06.304Z,1348150806.304 [Default:D.SetSpeed] Stopped
2012-09-20T14:20:06.304Z,1348150806.304 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-09-20T14:20:06.304Z,1348150806.304 [Default:E.GoToSurface] Stopped
2012-09-20T14:20:06.304Z,1348150806.304 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-20T14:20:06.308Z,1348150806.308 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-09-20T14:20:06.308Z,1348150806.308 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-09-20T14:20:06.309Z,1348150806.309 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-09-20T14:20:06.309Z,1348150806.309 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-09-20T14:20:06.309Z,1348150806.309 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-09-20T14:20:06.309Z,1348150806.309 [BuoyancyServo](INFO): Powering down
2012-09-20T14:20:06.310Z,1348150806.310 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-09-20T14:20:06.310Z,1348150806.310 [ElevatorServo](INFO): Powering down
2012-09-20T14:20:06.311Z,1348150806.311 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-09-20T14:20:06.312Z,1348150806.312 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-09-20T14:20:06.312Z,1348150806.312 [RudderServo](INFO): Powering down
2012-09-20T14:20:06.312Z,1348150806.312 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-09-20T14:20:06.313Z,1348150806.313 [ThrusterServo](INFO): Powering down
2012-09-20T14:20:06.313Z,1348150806.313 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-09-20T14:20:06.314Z,1348150806.314 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-09-20T14:20:06.314Z,1348150806.314 [CBIT](DEBUG): Uninitialize CBIT Component.