2013-08-27T01:29:55.312Z,1377566995.312 [Supervisor](DEBUG): Initializing supervisor.
2013-08-27T01:29:55.315Z,1377566995.315 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-08-27T01:29:55.316Z,1377566995.316 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-08-27T01:29:55.317Z,1377566995.317 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-08-27T01:29:55.322Z,1377566995.322 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-08-27T01:29:55.333Z,1377566995.333 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-08-27T01:29:55.334Z,1377566995.334 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-08-27T01:29:55.335Z,1377566995.335 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-08-27T01:29:55.336Z,1377566995.336 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-08-27T01:29:55.337Z,1377566995.337 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-08-27T01:29:55.338Z,1377566995.338 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-08-27T01:29:55.618Z,1377566995.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-08-27T01:29:55.619Z,1377566995.619 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-08-27T01:29:55.811Z,1377566995.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-08-27T01:29:55.812Z,1377566995.812 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-08-27T01:29:55.902Z,1377566995.902 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-08-27T01:29:55.902Z,1377566995.902 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-08-27T01:29:56.014Z,1377566996.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-08-27T01:29:56.014Z,1377566996.014 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-08-27T01:29:56.155Z,1377566996.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-08-27T01:29:56.155Z,1377566996.155 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-08-27T01:29:56.393Z,1377566996.392 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-08-27T01:29:56.393Z,1377566996.393 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-08-27T01:29:56.557Z,1377566996.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-08-27T01:29:56.558Z,1377566996.557 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-08-27T01:29:56.847Z,1377566996.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-08-27T01:29:56.848Z,1377566996.848 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-08-27T01:29:56.949Z,1377566996.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-08-27T01:29:56.949Z,1377566996.949 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-08-27T01:29:57.359Z,1377566997.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-08-27T01:29:57.359Z,1377566997.359 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-08-27T01:29:57.469Z,1377566997.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-08-27T01:29:57.470Z,1377566997.470 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-08-27T01:29:57.556Z,1377566997.556 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-08-27T01:29:57.557Z,1377566997.557 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-08-27T01:29:57.655Z,1377566997.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-08-27T01:29:57.781Z,1377566997.781 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-08-27T01:29:57.874Z,1377566997.874 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-08-27T01:29:57.978Z,1377566997.978 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-08-27T01:29:58.073Z,1377566998.073 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-08-27T01:29:58.187Z,1377566998.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-08-27T01:29:58.282Z,1377566998.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-08-27T01:29:58.368Z,1377566998.368 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/LOGIN/
2013-08-27T01:29:58.368Z,1377566998.368 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-08-27T01:29:58.370Z,1377566998.370 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-08-27T01:29:58.535Z,1377566998.534 [InternalSim] Loaded
2013-08-27T01:29:58.535Z,1377566998.535 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-08-27T01:29:58.535Z,1377566998.535 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-08-27T01:29:58.536Z,1377566998.536 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-08-27T01:29:58.622Z,1377566998.622 [SBIT](DEBUG): Construct Startup Built In Test.
2013-08-27T01:29:58.650Z,1377566998.650 [SBIT] Loaded
2013-08-27T01:29:58.650Z,1377566998.650 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-08-27T01:29:58.651Z,1377566998.651 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-08-27T01:29:58.680Z,1377566998.680 [IBIT] Loaded
2013-08-27T01:29:58.680Z,1377566998.680 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-08-27T01:29:58.683Z,1377566998.682 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-08-27T01:29:58.795Z,1377566998.795 [CBIT] Loaded
2013-08-27T01:29:58.796Z,1377566998.796 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-08-27T01:29:58.796Z,1377566998.796 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-08-27T01:29:58.797Z,1377566998.797 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-08-27T01:29:58.967Z,1377566998.967 [BuoyancyServo] Loaded
2013-08-27T01:29:58.967Z,1377566998.967 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-08-27T01:29:58.979Z,1377566998.979 [ElevatorServo] Loaded
2013-08-27T01:29:58.979Z,1377566998.979 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-08-27T01:29:58.990Z,1377566998.990 [MassServo] Loaded
2013-08-27T01:29:58.991Z,1377566998.991 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-08-27T01:29:59.002Z,1377566999.002 [RudderServo] Loaded
2013-08-27T01:29:59.002Z,1377566999.002 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-08-27T01:29:59.013Z,1377566999.013 [ThrusterServo] Loaded
2013-08-27T01:29:59.014Z,1377566999.013 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-08-27T01:29:59.014Z,1377566999.014 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-08-27T01:29:59.014Z,1377566999.014 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-08-27T01:29:59.051Z,1377566999.051 [DepthRateCalculator] Loaded
2013-08-27T01:29:59.052Z,1377566999.052 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-08-27T01:30:02.181Z,1377567002.181 [HFRadarModelCalc] Loaded
2013-08-27T01:30:02.181Z,1377567002.181 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-08-27T01:30:02.197Z,1377567002.197 [NavChart] Loaded
2013-08-27T01:30:02.198Z,1377567002.198 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-08-27T01:30:02.203Z,1377567002.203 [PitchRateCalculator] Loaded
2013-08-27T01:30:02.203Z,1377567002.203 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-08-27T01:30:02.214Z,1377567002.214 [SpeedCalculator] Loaded
2013-08-27T01:30:02.214Z,1377567002.214 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-08-27T01:30:02.230Z,1377567002.230 [TempGradientCalculator] Loaded
2013-08-27T01:30:02.230Z,1377567002.230 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-08-27T01:30:02.236Z,1377567002.236 [YawRateCalculator] Loaded
2013-08-27T01:30:02.236Z,1377567002.236 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-08-27T01:30:02.282Z,1377567002.282 [Navigation] Loaded
2013-08-27T01:30:02.282Z,1377567002.282 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-08-27T01:30:02.283Z,1377567002.283 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-08-27T01:30:02.283Z,1377567002.283 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-08-27T01:30:02.520Z,1377567002.520 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-08-27T01:30:02.521Z,1377567002.521 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-08-27T01:30:02.544Z,1377567002.545 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-08-27T01:30:02.545Z,1377567002.545 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-08-27T01:30:02.594Z,1377567002.594 [VerticalControl](DEBUG): Construct VerticalControl.
2013-08-27T01:30:02.686Z,1377567002.686 [VerticalControl] Loaded
2013-08-27T01:30:02.686Z,1377567002.686 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-08-27T01:30:02.687Z,1377567002.687 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-08-27T01:30:02.744Z,1377567002.744 [HorizontalControl] Loaded
2013-08-27T01:30:02.744Z,1377567002.744 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-08-27T01:30:02.745Z,1377567002.745 [SpeedControl](DEBUG): Construct SpeedControl.
2013-08-27T01:30:02.747Z,1377567002.747 [SpeedControl] Loaded
2013-08-27T01:30:02.747Z,1377567002.747 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-08-27T01:30:02.748Z,1377567002.748 [LoopControl](DEBUG): Construct LoopControl.
2013-08-27T01:30:02.748Z,1377567002.748 [LoopControl] Loaded
2013-08-27T01:30:02.748Z,1377567002.749 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-08-27T01:30:02.749Z,1377567002.749 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-08-27T01:30:02.749Z,1377567002.750 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-08-27T01:30:02.756Z,1377567002.756 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-08-27T01:30:02.760Z,1377567002.760 [AsyncPiEstimator] Loaded
2013-08-27T01:30:02.761Z,1377567002.761 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-08-27T01:30:02.762Z,1377567002.762 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406474E0
2013-08-27T01:30:02.762Z,1377567002.763 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-08-27T01:30:02.763Z,1377567002.763 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-08-27T01:30:02.960Z,1377567002.960 [AHRS_sp3003D] Loaded
2013-08-27T01:30:02.960Z,1377567002.960 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-08-27T01:30:03.205Z,1377567003.205 [Batt_Ocean_Server] Loaded
2013-08-27T01:30:03.206Z,1377567003.206 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-08-27T01:30:03.219Z,1377567003.219 [Depth_Keller] Loaded
2013-08-27T01:30:03.220Z,1377567003.220 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-08-27T01:30:03.224Z,1377567003.224 [DropWeight] Loaded
2013-08-27T01:30:03.225Z,1377567003.225 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-08-27T01:30:03.405Z,1377567003.405 [DVL_micro] Loaded
2013-08-27T01:30:03.405Z,1377567003.405 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-08-27T01:30:03.406Z,1377567003.406 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406D54E0
2013-08-27T01:30:03.492Z,1377567003.492 [NAL9602] Loaded
2013-08-27T01:30:03.493Z,1377567003.493 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-08-27T01:30:03.535Z,1377567003.535 [Onboard] Loaded
2013-08-27T01:30:03.535Z,1377567003.535 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-08-27T01:30:03.542Z,1377567003.542 [Radio_Freewave] Loaded
2013-08-27T01:30:03.543Z,1377567003.543 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-08-27T01:30:03.682Z,1377567003.681 [DAT] Loaded
2013-08-27T01:30:03.682Z,1377567003.682 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2013-08-27T01:30:03.682Z,1377567003.682 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-08-27T01:30:03.683Z,1377567003.683 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-08-27T01:30:03.748Z,1377567003.748 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-08-27T01:30:03.751Z,1377567003.751 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-08-27T01:30:03.752Z,1377567003.752 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-08-27T01:30:03.759Z,1377567003.759 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-08-27T01:30:03.760Z,1377567003.760 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407324E0
2013-08-27T01:30:03.764Z,1377567003.764 [Supervisor](DEBUG): Running supervisor.
2013-08-27T01:30:03.765Z,1377567003.765 [CommandLine](INFO): Thread ID is 3310
2013-08-27T01:30:03.768Z,1377567003.768 [controlThread](INFO): Thread ID is 3309
2013-08-27T01:30:03.768Z,1377567003.768 [controlThread](DEBUG): Initializing ControlThread
2013-08-27T01:30:03.769Z,1377567003.769 [CycleStarter](INFO): Thread ID is 3308
2013-08-27T01:30:03.769Z,1377567003.769 [InternalSim](DEBUG):  InternalSim initializing...
2013-08-27T01:30:03.805Z,1377567003.805 [logger](INFO): Thread ID is 3311
2013-08-27T01:30:03.822Z,1377567003.822 [SBIT](INFO): Initialize SBIT Component.
2013-08-27T01:30:03.822Z,1377567003.822 [SBIT](IMPORTANT): Tethys CM Info: 
$ Rev:10573
2013-08-27T01:30:03.822Z,1377567003.822 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2013-08-27T01:30:03.823Z,1377567003.823 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013
2013-08-27T01:30:03.823Z,1377567003.823 [IBIT](INFO): Initialize IBIT Component.
2013-08-27T01:30:03.824Z,1377567003.824 [CBIT](DEBUG): Initialize CBIT Component.
2013-08-27T01:30:03.824Z,1377567003.824 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-08-27T01:30:03.834Z,1377567003.834 [AsyncPiEstimator](INFO): Thread ID is 3372
2013-08-27T01:30:03.834Z,1377567003.834 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-08-27T01:30:03.855Z,1377567003.855 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-08-27T01:30:03.855Z,1377567003.855 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-08-27T01:30:03.856Z,1377567003.856 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-08-27T01:30:03.856Z,1377567003.856 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-08-27T01:30:03.856Z,1377567003.856 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-08-27T01:30:03.857Z,1377567003.857 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-08-27T01:30:03.857Z,1377567003.857 [Navigation](DEBUG): Initializing Navigation.
2013-08-27T01:30:03.858Z,1377567003.858 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-08-27T01:30:03.859Z,1377567003.859 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-08-27T01:30:03.860Z,1377567003.860 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-08-27T01:30:03.860Z,1377567003.861 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-08-27T01:30:03.863Z,1377567003.863 [DVL_micro](INFO): Thread ID is 3373
2013-08-27T01:30:03.872Z,1377567003.872 [DVL_micro](INFO): Initializing
2013-08-27T01:30:03.873Z,1377567003.873 [DVL_micro](INFO): start:Powering up
2013-08-27T01:30:03.874Z,1377567003.874 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-08-27T01:30:03.874Z,1377567003.874 [DVL_micro](INFO): Cycling power to configure device.
2013-08-27T01:30:03.891Z,1377567003.891 [NavChartDb](INFO): Thread ID is 3374
2013-08-27T01:30:03.893Z,1377567003.893 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-08-27T01:30:03.894Z,1377567003.894 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-08-27T01:30:03.895Z,1377567003.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-08-27T01:30:03.895Z,1377567003.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-08-27T01:30:03.895Z,1377567003.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-08-27T01:30:03.895Z,1377567003.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-08-27T01:30:03.895Z,1377567003.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-08-27T01:30:03.896Z,1377567003.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-08-27T01:30:03.896Z,1377567003.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-08-27T01:30:05.336Z,1377567005.336 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2013-08-27T01:30:05.369Z,1377567005.369 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-08-27T01:30:05.388Z,1377567005.388 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-08-27T01:30:05.422Z,1377567005.422 [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-08-27T01:30:05.423Z,1377567005.423 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-08-27T01:30:05.498Z,1377567005.498 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-08-27T01:30:05.500Z,1377567005.500 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-08-27T01:30:05.530Z,1377567005.530 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-08-27T01:30:05.553Z,1377567005.553 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-08-27T01:30:05.557Z,1377567005.556 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-08-27T01:30:05.576Z,1377567005.576 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-08-27T01:30:05.597Z,1377567005.597 [Default:D.SetSpeed](DEBUG): Construct.
2013-08-27T01:30:05.600Z,1377567005.600 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-08-27T01:30:05.605Z,1377567005.605 [Default:F.Wait](DEBUG): Construct Wait.
2013-08-27T01:30:05.632Z,1377567005.632 [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-08-27T01:30:05.637Z,1377567005.637 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,DAT,Depth_Keller,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-08-27T01:30:05.678Z,1377567005.678 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-08-27T01:30:05.734Z,1377567005.734 [Depth_Keller](ERROR): Pressure reading out of range: 1831.078247 decibar
2013-08-27T01:30:05.848Z,1377567005.848 [Radio_Freewave](INFO): Powering up
2013-08-27T01:30:05.865Z,1377567005.865 [DAT](INFO): Powering up
2013-08-27T01:30:05.866Z,1377567005.866 [DAT](DEBUG): Initializing DAT.
2013-08-27T01:30:05.934Z,1377567005.934 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-08-27T01:30:05.934Z,1377567005.934 [DVL_micro](INFO): Querying output modes
2013-08-27T01:30:05.934Z,1377567005.934 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-08-27T01:30:05.946Z,1377567005.946 [DVL_micro](DEBUG): cmdResponse: 01
2013-08-27T01:30:05.946Z,1377567005.946 [DVL_micro](INFO): NQ1 output enabled
2013-08-27T01:30:05.947Z,1377567005.947 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-08-27T01:30:05.958Z,1377567005.958 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-08-27T01:30:05.963Z,1377567005.963 [DVL_micro](INFO): pause:Powering down
2013-08-27T01:30:06.096Z,1377567006.096 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-08-27T01:30:06.102Z,1377567006.102 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-08-27T01:30:06.138Z,1377567006.138 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-08-27T01:30:06.146Z,1377567006.146 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-08-27T01:30:06.152Z,1377567006.152 [MassServo](DEBUG): Initializing EZServoServo.
2013-08-27T01:30:06.165Z,1377567006.165 [MassServo](DEBUG): Initializing MassServo.
2013-08-27T01:30:06.175Z,1377567006.175 [RudderServo](DEBUG): Initializing EZServoServo.
2013-08-27T01:30:06.182Z,1377567006.182 [RudderServo](DEBUG): Initializing RudderServo.
2013-08-27T01:30:06.187Z,1377567006.187 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-08-27T01:30:06.194Z,1377567006.194 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-08-27T01:30:08.657Z,1377567008.657 [NAL9602](INFO): Powering up NAL9602
2013-08-27T01:30:19.504Z,1377567019.504 [NAL9602](INFO): NAL9602 initialized
2013-08-27T01:30:19.601Z,1377567019.601 [SBIT](IMPORTANT): Beginning Startup BIT
2013-08-27T01:30:19.603Z,1377567019.603 [CBIT](IMPORTANT): Beginning GF scan
2013-08-27T01:30:26.297Z,1377567026.297 [DAT](INFO): Powering down
2013-08-27T01:30:27.074Z,1377567027.074 [CommandLine](IMPORTANT): got command report touch DAT.range
2013-08-27T01:30:32.766Z,1377567032.766 [CommandLine](IMPORTANT): got command show variable tracking
2013-08-27T01:30:42.662Z,1377567042.662 [CommandLine](IMPORTANT): got command load ./Missions/Science/CANON_LST_Track.xml
2013-08-27T01:30:42.662Z,1377567042.662 [MissionManager](INFO): Loading Mission: ./Missions/Science/CANON_LST_Track.xml
2013-08-27T01:30:42.919Z,1377567042.919 [MissionManager](INFO): DefineArg CANON_LST_Track.MinAltitude = 7.000000 m
2013-08-27T01:30:42.922Z,1377567042.922 [MissionManager](INFO): DefineArg CANON_LST_Track.MaxDepth = 110.000000 m
2013-08-27T01:30:42.938Z,1377567042.938 [MissionManager](INFO): DefineArg CANON_LST_Track.MinOffshore = 1500.000000 m
2013-08-27T01:30:42.942Z,1377567042.942 [MissionManager](INFO): DefineArg CANON_LST_Track.Speed = 1.000000 m/s
2013-08-27T01:30:42.946Z,1377567042.946 [MissionManager](INFO): DefineArg CANON_LST_Track.MissionTimeout = 24.000000 h
2013-08-27T01:30:42.962Z,1377567042.962 [MissionManager](INFO): DefineArg CANON_LST_Track.MassDefault = 0.000000 n/a
2013-08-27T01:30:42.966Z,1377567042.966 [MissionManager](INFO): DefineArg CANON_LST_Track.BuoyancyNeutral = 0.000400 n/a
2013-08-27T01:30:42.998Z,1377567042.998 [MissionManager](INFO): DefineArg CANON_LST_Track.NeedCommsTime = 1.000000 h
2013-08-27T01:30:43.002Z,1377567043.002 [MissionManager](INFO): DefineArg CANON_LST_Track.KwpHeading = 0.010000 rad/m
2013-08-27T01:30:43.030Z,1377567043.030 [MissionManager](INFO): DefineArg CANON_LST_Track.WpLSTLat = 0.000000 arcdeg
2013-08-27T01:30:43.042Z,1377567043.042 [MissionManager](INFO): DefineArg CANON_LST_Track.WpLSTLon = 0.000000 arcdeg
2013-08-27T01:30:43.058Z,1377567043.058 [MissionManager](INFO): DefineArg CANON_LST_Track.Repeat = 1.000000 count
2013-08-27T01:30:43.066Z,1377567043.066 [MissionManager](INFO): DefineArg CANON_LST_Track.CircleRadius = 660.000000 m
2013-08-27T01:30:43.078Z,1377567043.078 [MissionManager](INFO): DefineArg CANON_LST_Track.CircleTimeout = 120.000000 min
2013-08-27T01:30:43.090Z,1377567043.090 [MissionManager](INFO): DefineArg CANON_LST_Track.CircleMaxError = 100.000000 m
2013-08-27T01:30:43.098Z,1377567043.098 [MissionManager](INFO): DefineArg CANON_LST_Track.CircleTurnToPort = 0 bool
2013-08-27T01:30:43.122Z,1377567043.122 [MissionManager](INFO): DefineArg CANON_LST_Track.WaitTime = 5.000000 min
2013-08-27T01:30:43.134Z,1377567043.134 [MissionManager](INFO): DefineArg CANON_LST_Track.YoYoMinDepth = 3.000000 m
2013-08-27T01:30:43.146Z,1377567043.146 [MissionManager](INFO): DefineArg CANON_LST_Track.YoYoMaxDepth = 100.000000 m
2013-08-27T01:30:43.156Z,1377567043.157 [MissionManager](INFO): DefineArg CANON_LST_Track.YoYoMinAltitude = 3.000000 m
2013-08-27T01:30:43.165Z,1377567043.165 [MissionManager](INFO): DefineArg CANON_LST_Track.YoYoUpPitch = 20.000000 arcdeg
2013-08-27T01:30:43.181Z,1377567043.181 [MissionManager](INFO): DefineArg CANON_LST_Track.YoYoDownPitch = -20.000000 arcdeg
2013-08-27T01:30:43.189Z,1377567043.189 [MissionManager](INFO): DefineArg CANON_LST_Track.ContactLabel = 1.000000 count
2013-08-27T01:30:43.195Z,1377567043.195 [CANON_LST_Track:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2013-08-27T01:30:43.243Z,1377567043.243 [CANON_LST_Track:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2013-08-27T01:30:43.260Z,1377567043.260 [CANON_LST_Track:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2013-08-27T01:30:43.276Z,1377567043.276 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2013-08-27T01:30:43.344Z,1377567043.344 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceComms.SurfaceDepthRate = nan m/s
2013-08-27T01:30:43.359Z,1377567043.359 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceComms.SurfacePitch = nan arcdeg
2013-08-27T01:30:43.367Z,1377567043.367 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceComms.SurfaceSpeed = 0.500000 m/s
2013-08-27T01:30:43.375Z,1377567043.375 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceComms.IridiumTimeout = 30.000000 min
2013-08-27T01:30:43.383Z,1377567043.383 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceComms.UnpackSplitAtSurface = 0 bool
2013-08-27T01:30:43.384Z,1377567043.384 [CANON_LST_Track:SurfaceComms:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-08-27T01:30:43.433Z,1377567043.433 [MissionManager](INFO): Inserting Stack: Missions/Insert/SurfaceCommsSplit.xml
2013-08-27T01:30:43.484Z,1377567043.484 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceCommsSplit.SurfacePitch = 20.000000 arcdeg
2013-08-27T01:30:43.492Z,1377567043.492 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceCommsSplit.SurfaceSpeed = 1.000000 m/s
2013-08-27T01:30:43.498Z,1377567043.497 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2013-08-27T01:30:43.574Z,1377567043.574 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceCommsSplit:SurfaceComms.SurfaceDepthRate = nan m/s
2013-08-27T01:30:43.582Z,1377567043.582 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceCommsSplit:SurfaceComms.SurfacePitch = nan arcdeg
2013-08-27T01:30:43.590Z,1377567043.590 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceCommsSplit:SurfaceComms.SurfaceSpeed = 0.500000 m/s
2013-08-27T01:30:43.598Z,1377567043.598 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceCommsSplit:SurfaceComms.IridiumTimeout = 30.000000 min
2013-08-27T01:30:43.631Z,1377567043.631 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceCommsSplit:SurfaceComms.UnpackSplitAtSurface = 0 bool
2013-08-27T01:30:43.632Z,1377567043.632 [CANON_LST_Track:SurfaceCommsSplit:SurfaceComms:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-08-27T01:30:43.671Z,1377567043.671 [MissionManager](INFO): RedefineArg CANON_LST_Track:SurfaceCommsSplit:SurfaceComms.SurfacePitch = 20.000000 arcdeg
2013-08-27T01:30:43.673Z,1377567043.673 [MissionManager](INFO): RedefineArg CANON_LST_Track:SurfaceCommsSplit:SurfaceComms.SurfaceSpeed = 1.000000 m/s
2013-08-27T01:30:43.678Z,1377567043.678 [MissionManager](INFO): RedefineArg CANON_LST_Track:SurfaceCommsSplit:SurfaceComms.UnpackSplitAtSurface = 1 bool
2013-08-27T01:30:43.679Z,1377567043.679 [MissionManager](INFO): Inserting Stack: Missions/Insert/SurfaceCommsShort.xml
2013-08-27T01:30:43.742Z,1377567043.742 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceCommsShort.FloatTime = 180.000000 s
2013-08-27T01:30:43.754Z,1377567043.754 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceCommsShort.Timeout = 1.000000 h
2013-08-27T01:30:43.762Z,1377567043.763 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceCommsShort.Pitch = 20.000000 arcdeg
2013-08-27T01:30:43.771Z,1377567043.771 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceCommsShort.Speed = 1.000000 m/s
2013-08-27T01:30:43.778Z,1377567043.778 [CANON_LST_Track:SurfaceCommsShort:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-08-27T01:30:43.828Z,1377567043.828 [MissionManager](INFO): Inserting Stack: Missions/Insert/SurfaceGPS.xml
2013-08-27T01:30:43.877Z,1377567043.876 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceGPS.Timeout = 5.000000 min
2013-08-27T01:30:43.888Z,1377567043.888 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceGPS.SurfacePitch = 20.000000 arcdeg
2013-08-27T01:30:43.896Z,1377567043.896 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceGPS.SurfaceSpeed = 1.000000 m/s
2013-08-27T01:30:43.902Z,1377567043.902 [MissionManager](INFO): Inserting Stack: Missions/Insert/SurfaceNavUpdate.xml
2013-08-27T01:30:43.968Z,1377567043.968 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceGPS:SurfaceNavUpdate.SurfaceDepthRate = nan m/s
2013-08-27T01:30:43.984Z,1377567043.984 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceGPS:SurfaceNavUpdate.SurfacePitch = nan arcdeg
2013-08-27T01:30:44.000Z,1377567044.000 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceGPS:SurfaceNavUpdate.SurfaceSpeed = 0.500000 m/s
2013-08-27T01:30:44.004Z,1377567044.004 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceGPS:SurfaceNavUpdate.UnpackSplitAtSurface = 0 bool
2013-08-27T01:30:44.045Z,1377567044.045 [CANON_LST_Track:SurfaceGPS:SurfaceNavUpdate:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-08-27T01:30:44.084Z,1377567044.084 [MissionManager](INFO): RedefineArg CANON_LST_Track:SurfaceGPS:SurfaceNavUpdate.SurfacePitch = nan n/a
2013-08-27T01:30:44.086Z,1377567044.086 [MissionManager](INFO): RedefineArg CANON_LST_Track:SurfaceGPS:SurfaceNavUpdate.SurfaceSpeed = 0.500000 n/a
2013-08-27T01:30:44.086Z,1377567044.086 [MissionManager](INFO): Inserting Stack: Missions/Insert/SurfaceSafety.xml
2013-08-27T01:30:44.141Z,1377567044.141 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceSafety.MaxTime = 3.000000 h
2013-08-27T01:30:44.162Z,1377567044.162 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceSafety.SurfacePitch = 20.000000 arcdeg
2013-08-27T01:30:44.167Z,1377567044.167 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceSafety.SurfaceSpeed = 1.000000 m/s
2013-08-27T01:30:44.168Z,1377567044.168 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2013-08-27T01:30:44.237Z,1377567044.237 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceSafety:SurfaceComms.SurfaceDepthRate = nan m/s
2013-08-27T01:30:44.272Z,1377567044.272 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceSafety:SurfaceComms.SurfacePitch = nan arcdeg
2013-08-27T01:30:44.292Z,1377567044.292 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceSafety:SurfaceComms.SurfaceSpeed = 0.500000 m/s
2013-08-27T01:30:44.300Z,1377567044.300 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceSafety:SurfaceComms.IridiumTimeout = 30.000000 min
2013-08-27T01:30:44.323Z,1377567044.323 [MissionManager](INFO): DefineArg CANON_LST_Track:SurfaceSafety:SurfaceComms.UnpackSplitAtSurface = 0 bool
2013-08-27T01:30:44.324Z,1377567044.324 [CANON_LST_Track:SurfaceSafety:SurfaceComms:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-08-27T01:30:44.376Z,1377567044.376 [MissionManager](INFO): RedefineArg CANON_LST_Track:SurfaceSafety:SurfaceComms.SurfacePitch = 20.000000 arcdeg
2013-08-27T01:30:44.382Z,1377567044.382 [MissionManager](INFO): RedefineArg CANON_LST_Track:SurfaceSafety:SurfaceComms.SurfaceSpeed = 1.000000 m/s
2013-08-27T01:30:44.383Z,1377567044.383 [CANON_LST_Track:I.Pitch](DEBUG): Construct.
2013-08-27T01:30:44.393Z,1377567044.393 [CANON_LST_Track:J.Buoyancy](DEBUG): Construct Buoyancy.
2013-08-27T01:30:44.639Z,1377567044.639 [MissionManager](ERROR): Within CANON_LST_Track:L, unknown MissionItem element: Assign
2013-08-27T01:30:44.640Z,1377567044.639 [MissionManager](ERROR): Within CANON_LST_Track:L, unknown MissionItem element: Assign
2013-08-27T01:30:44.668Z,1377567044.668 [CANON_LST_Track:waiting:B.Wait](DEBUG): Construct Wait.
2013-08-27T01:30:44.679Z,1377567044.679 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2013-08-27T01:30:44.984Z,1377567044.984 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.SampleISUS = 0 bool
2013-08-27T01:30:44.993Z,1377567044.993 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2013-08-27T01:30:45.001Z,1377567045.001 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.SampleISUSminPitch = -60.000000 arcdeg
2013-08-27T01:30:45.024Z,1377567045.024 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.SampleRSSI = 1 bool
2013-08-27T01:30:45.036Z,1377567045.036 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.SampleADCP = 1 bool
2013-08-27T01:30:45.048Z,1377567045.048 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.PeakDetectChlActive = 0 bool
2013-08-27T01:30:45.072Z,1377567045.072 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.PeakDetectNO3Active = 0 bool
2013-08-27T01:30:45.080Z,1377567045.080 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.PeakDetectRSSIActive = 0 bool
2013-08-27T01:30:45.091Z,1377567045.092 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2013-08-27T01:30:45.115Z,1377567045.115 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.ComputeWaterCurrent = 0 bool
2013-08-27T01:30:45.132Z,1377567045.132 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.ComputeWaterCurrentMinDepth = 2.000000 m
2013-08-27T01:30:45.144Z,1377567045.144 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.ComputeWaterCurrentMaxDepth = 5.000000 m
2013-08-27T01:30:45.162Z,1377567045.162 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.EnabledAanderaaO2 = 0.000000 n/a
2013-08-27T01:30:45.173Z,1377567045.173 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.EnabledNeilBrown = 0.000000 n/a
2013-08-27T01:30:45.185Z,1377567045.185 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.EnabledTurbulence_NPS = 0.000000 n/a
2013-08-27T01:30:45.194Z,1377567045.194 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.EnabledWetLabsBB2FL = 0.000000 n/a
2013-08-27T01:30:45.204Z,1377567045.204 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.EnabledDVL = 1.000000 n/a
2013-08-27T01:30:45.233Z,1377567045.233 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.EnabledISUS = 0.000000 n/a
2013-08-27T01:30:45.236Z,1377567045.237 [MissionManager](INFO): DefineArg CANON_LST_Track:ScienceOps:Science.HFRadarModelActive = 0 bool
2013-08-27T01:30:45.257Z,1377567045.257 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakChl = 0.000000 ug/l
2013-08-27T01:30:45.270Z,1377567045.270 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakChlDepth = 0.000000 m
2013-08-27T01:30:45.299Z,1377567045.299 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakChlLatitude = nan arcdeg
2013-08-27T01:30:45.303Z,1377567045.303 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakChlLongitude = nan arcdeg
2013-08-27T01:30:45.319Z,1377567045.319 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakNO3 = 0.000000 umol/l
2013-08-27T01:30:45.336Z,1377567045.336 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakNO3Depth = 0.000000 m
2013-08-27T01:30:45.340Z,1377567045.340 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakNO3Latitude = nan arcdeg
2013-08-27T01:30:45.360Z,1377567045.360 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakNO3Longitude = nan arcdeg
2013-08-27T01:30:45.364Z,1377567045.364 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakRSSI = 0.000000 dB
2013-08-27T01:30:45.385Z,1377567045.385 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakRSSIDepth = 0.000000 m
2013-08-27T01:30:45.389Z,1377567045.389 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakRSSILatitude = nan arcdeg
2013-08-27T01:30:45.393Z,1377567045.393 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.PeakRSSILongitude = nan arcdeg
2013-08-27T01:30:45.409Z,1377567045.409 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.eastwardSurfaceCurrent = nan m/s
2013-08-27T01:30:45.454Z,1377567045.454 [MissionManager](INFO): DefineOutput CANON_LST_Track:ScienceOps:Science.northwardSurfaceCurrent = nan m/s
2013-08-27T01:30:45.544Z,1377567045.545 [CANON_LST_Track:ScienceOps:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2013-08-27T01:30:45.583Z,1377567045.583 [CANON_LST_Track:ScienceOps:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2013-08-27T01:30:45.622Z,1377567045.622 [CANON_LST_Track:ScienceOps:Science:PeakDetectRSSI:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2013-08-27T01:30:45.663Z,1377567045.663 [MissionManager](INFO): RedefineArg CANON_LST_Track:ScienceOps:Science.PeakDetectChlActive = 1 bool
2013-08-27T01:30:45.664Z,1377567045.664 [MissionManager](INFO): RedefineArg CANON_LST_Track:ScienceOps:Science.SampleISUS = 1 bool
2013-08-27T01:30:45.670Z,1377567045.670 [MissionManager](INFO): RedefineArg CANON_LST_Track:ScienceOps:Science.SampleISUSmaxPitch = 30.000000 arcdeg
2013-08-27T01:30:45.672Z,1377567045.672 [MissionManager](INFO): RedefineArg CANON_LST_Track:ScienceOps:Science.SampleISUSminPitch = 10.000000 arcdeg
2013-08-27T01:30:45.690Z,1377567045.690 [MissionManager](INFO): RedefineArg CANON_LST_Track:ScienceOps:Science.SampleADCP = 1 bool
2013-08-27T01:30:45.691Z,1377567045.691 [MissionManager](INFO): RedefineArg CANON_LST_Track:ScienceOps:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2013-08-27T01:30:45.692Z,1377567045.692 [MissionManager](INFO): RedefineArg CANON_LST_Track:ScienceOps:Science.SampleRSSI = 0 bool
2013-08-27T01:30:45.698Z,1377567045.698 [CANON_LST_Track:ScienceOps:B.SetSpeed](DEBUG): Construct.
2013-08-27T01:30:45.717Z,1377567045.717 [CANON_LST_Track:ScienceOps:C.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2013-08-27T01:30:45.744Z,1377567045.744 [CANON_LST_Track:ScienceOps:D.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2013-08-27T01:30:45.765Z,1377567045.765 [CANON_LST_Track:ScienceOps:E.YoYo](DEBUG): Construct YoYo.
2013-08-27T01:30:45.795Z,1377567045.795 [CANON_LST_Track:ScienceOps:circleLST:B.Circle](DEBUG): Construct.
2013-08-27T01:30:45.823Z,1377567045.823 [CANON_LST_Track:ScienceOps:G.Wait](DEBUG): Construct Wait.
2013-08-27T01:30:45.909Z,1377567045.909 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Trigger="Tethys/Trigger" 
       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/Derivation http://aosn.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Trigger http://aosn.mbari.org/tethys/Xml/Trigger.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="CANON_LST_Track">

    <Description>
        This mission runs a circle around a given Lat/Lon and tracks the LST at
        a regular interval, updating the center of the circle. Following a
        circle, the mission gets the LST position from the surface for a more
        exact position and sends it to shore.
    </Description>

<!-- Mission-wide settings -->

    <DefineArg Name="MinAltitude"><Description>
        Minimum altitude for the entire mission.
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>110</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:meter/><Value>1500</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle speed.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>24</Value></DefineArg>

    <DefineArg Name="MassDefault"><Description>
        Static setting for mass during the mission. Defaults to massDefault
        setting in the Config/Control.cfg file.
    </Description><Control:VerticalControl.massDefault/></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Description>
        Buoyancy bladder position while performing the YoYo behavior. Defaults
        to buoyancyNeutral setting in the Config/Control.cfg file.
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications. This is included
        at a high level in the mission to override other items in the stack if
        needed during field operations for any reason. Leave this parameter
        large to let other aggregates and behaviors control when the vehicle
        surfaces.
    </Description><Units:hour/><Value>1</Value></DefineArg>

    <DefineArg Name="KwpHeading"><Description>
        Used to relax waypoint cross-track error constant that is adjusted for
        docking. (You can override this setting by passing an argument.)
    </Description><Units:radian_per_meter/><Value>0.010</Value></DefineArg>

<!-- Waypoint list -->

    <DefineArg Name="WpLSTLat"><Description>
        Latitude of LST drifter.
    </Description><Units:degree/><Value>0</Value></DefineArg>

    <DefineArg Name="WpLSTLon"><Description>
        Longitude of LST drifter.
    </Description><Units:degree/><Value>0</Value></DefineArg>

<!-- Science loop settings -->

    <DefineArg Name="Repeat"><Description>
        Number of times to repeat the science loop
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="CircleRadius"><Description>
        Radius of circle around LST during wait time
    </Description><Units:meter/><Value>660</Value></DefineArg>

    <DefineArg Name="CircleTimeout"><Description>
        Maximum time to circle the waypoint at the start of each lap.
    </Description><Units:minute/><Value>120</Value></DefineArg>

    <DefineArg Name="CircleMaxError"><Description>
        If this distance away from the circle, drive straight towards (or away
        from the center). Otherwise, try to reduce distance from the ideal
        circle.
    </Description><Units:meter/><Value>100</Value></DefineArg>

    <DefineArg Name="CircleTurnToPort"><Description>
        If true, vehicle turns to the left around the center point. If false,
        vehicle turns to the right.
    </Description><False/></DefineArg>

    <DefineArg Name="WaitTime"><Description>
        Time between satellite communications while waiting
    </Description><Units:minute/><Value>5</Value></DefineArg>

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth during yo-yo's.
    </Description><Units:meter/><Value>3.0</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth during yo-yo's.
    </Description><Units:meter/><Value>100.0</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Min altitude while in yo-yo mode.
    </Description><Units:meter/><Value>3.0</Value></DefineArg>

    <DefineArg Name="YoYoUpPitch"><Description>
        Pitch when yo-yo's are ascending.
    </Description><Units:degree/><Value>20.0</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Description>
        Pitch when yo-yo's are descending.
    </Description><Units:degree/><Value>-20.0</Value></DefineArg>

<!-- Variables used internally during the mission -->

    <DefineArg Name="ContactLabel"><Description>
        The acoustic address of the asset to be tracked
    </Description><Units:count/><Value>1</Value></DefineArg>

<!-- The mission -->

    <Timeout Duration="MissionTimeout"/>

<!-- High-priority directives that should always be near the top -->

    <Guidance:AltitudeEnvelope RunIn="Parallel">
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="MinAltitude"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:DepthEnvelope RunIn="Parallel">
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="MaxDepth"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:OffshoreEnvelope RunIn="Parallel">
        <Setting><Guidance:OffshoreEnvelope.minOffshore/><Arg Name="MinOffshore"/></Setting>
    </Guidance:OffshoreEnvelope>

<!-- Surfacing behaviors -->

    <Insert Filename="Insert/Surface.xml" Id="SurfaceComms"/>

    <Insert Filename="Insert/SurfaceCommsSplit.xml" Id="SurfaceCommsSplit"/>

    <Insert Filename="Insert/SurfaceCommsShort.xml" Id="SurfaceCommsShort"/>

    <Insert Filename="Insert/SurfaceGPS.xml" Id="SurfaceGPS"/>

    <Insert Filename="Insert/SurfaceSafety.xml" Id="SurfaceSafety"/>

<!-- TODO put all of these together into a master Surface insert file -->

<!-- mass and buoyancy defaults are lower priority than surfacing, but higher than everything else -->

    <Guidance:Pitch RunIn="Parallel">
        <Setting><Guidance:Pitch.massPosition/><Arg Name="MassDefault"/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy RunIn="Parallel">
        <Setting><Guidance:Buoyancy.position/><Arg Name="BuoyancyNeutral"/></Setting>
    </Guidance:Buoyancy>

<!-- High level NeedComms is useful for handling problems in the field. -->

    <Aggregate Id="NeedComms">

        <Description>
            Surface to communicate with shore (Set NeedCommsTime very large to
            make this a no-op.)
        </Description>

        <When>
            <Elapsed><Universal:platform_communications/></Elapsed>
            <Gt><Arg Name="NeedCommsTime"/></Gt>
        </When>

        <Call RefId="SurfaceComms"/>

    </Aggregate>

<!-- Add tracking directive high in the stack. -->

    <Derivation:Tracking RunIn="Parallel">
        <Setting><Derivation:Tracking.contactLabelSetting/><Arg Name="ContactLabel"/></Setting>
<!-- The assign calls below are only necessary for the "waiting" aggregate. Is that really needed? -->
        <Assign><Arg Name="WpLSTLat"/><Derivation:Tracking.contact_latitude/></Assign>
        <Assign><Arg Name="WpLSTLon"/><Derivation:Tracking.contact_longitude/></Assign>
    </Derivation:Tracking>

<!-- Maybe we are waiting for a center location.. -->

    <Aggregate Id="waiting" RunIn="Sequence">

        <Preemptive>
            <True/>
        </Preemptive>

        <When>
            <Arg Name="WpLSTLat"/>
            <Eq><Units:degree/><Value>0</Value></Eq>
            <Or><Arg Name="WpLSTLon"/><Eq><Units:degree/><Value>0</Value></Eq></Or>
        </When>

        <Syslog Severity="Important">Waiting for LST location</Syslog>

        <Guidance:Wait RunIn="Sequence">
            <Setting><Guidance:Wait.duration/><Arg Name="WaitTime"/></Setting>
        </Guidance:Wait>

        <Call RefId="SurfaceComms"/>

    </Aggregate>

    <Aggregate Id="ScienceOps" Repeat="Repeat">

        <Description>
            Circle while tracking and updating center, collecting science data.
        </Description>

        <Insert Filename="Insert/Science.xml" Id="Science">
            <Description>
                Get science data, including PeakDetectChl
            </Description>
            <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
            <RedefineArg Name="SampleISUS"><True/></RedefineArg>
            <RedefineArg Name="SampleISUSmaxPitch"><Units:degree/><Value>30.0</Value></RedefineArg>
            <RedefineArg Name="SampleISUSminPitch"><Units:degree/><Value>10.0</Value></RedefineArg>
            <RedefineArg Name="SampleADCP"><True/></RedefineArg>
            <RedefineArg Name="UpwardDerivativeOfTemperatureActive"><False/></RedefineArg>
            <RedefineArg Name="SampleRSSI"><False/></RedefineArg>
        </Insert>

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
        </Guidance:SetSpeed>

        <Guidance:DepthEnvelope RunIn="Parallel">
            <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
            <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
            <Setting><Guidance:DepthEnvelope.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
            <Setting><Guidance:DepthEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
        </Guidance:DepthEnvelope>

        <Guidance:AltitudeEnvelope RunIn="Parallel">
            <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
            <Setting><Guidance:AltitudeEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
        </Guidance:AltitudeEnvelope>

        <Guidance:YoYo RunIn="Parallel">
            <Setting><Guidance:YoYo.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
            <Setting><Guidance:YoYo.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
        </Guidance:YoYo>

        <Aggregate Id="circleLST" RunIn="Sequence">

            <Description>
                Circle LST and track, then surface and track LST and report
                position
            </Description>

            <Timeout Duration="CircleTimeout"/>

<!-- shouldn't this when clause not matter? you have preemptive set true for the waiting aggregate -->

            <When>
                <Arg Name="WpLSTLat"/>
                <Ne><Units:degree/><Value>0</Value></Ne>
                <And><Arg Name="WpLSTLon"/><Ne><Units:degree/><Value>0</Value></Ne></And>
            </When>

            <Assign RunIn="Parallel"><Control:HorizontalControl.kwpHeading/><Arg Name="KwpHeading"/></Assign>

            <Guidance:Circle RunIn="Sequence">
                <Setting><Guidance:Circle.latitude/><Derivation:Tracking.contact_latitude/></Setting>
                <Setting><Guidance:Circle.longitude/><Derivation:Tracking.contact_longitude/></Setting>
                <Setting><Guidance:Circle.radius/><Arg Name="CircleRadius"/></Setting>
                <Setting><Guidance:Circle.maxError/><Arg Name="CircleMaxError"/></Setting>
                <Setting><Guidance:Circle.turnToPort/><Arg Name="CircleTurnToPort"/></Setting>
            </Guidance:Circle>

        </Aggregate>

        <Guidance:Wait RunIn="Sequence"/>

    </Aggregate>

</Mission>


2013-08-27T01:30:45.930Z,1377567045.930 [CommandLine](IMPORTANT): Loaded ./Missions/Science/CANON_LST_Track.xml
2013-08-27T01:30:46.968Z,1377567046.968 [CBIT](FAULT): Chan 4 High side GF detected
mA:
CHAN 5 (24V): 0.035487
CHAN 4 (Batt): 0.006122
CHAN 2 (12V): 0.002583
CHAN 1 (5V): 0.000574
CHAN 0 (3.3V): 0.000383
OPEN: 0.000765
Full Scale Calc: 0.392
2013-08-27T01:30:50.238Z,1377567050.238 [CommandLine](IMPORTANT): got command show variable tracking
2013-08-27T01:30:50.370Z,1377567050.370 [CommandLine](INFO): Tracking.contact_label (enum)
2013-08-27T01:30:50.370Z,1377567050.371 [CommandLine](INFO): Tracking.contact_latitude (degree)
2013-08-27T01:30:50.371Z,1377567050.371 [CommandLine](INFO): Tracking.contact_longitude (degree)
2013-08-27T01:30:50.371Z,1377567050.371 [CommandLine](INFO): Tracking.eastings_to_contact (meter)
2013-08-27T01:30:50.372Z,1377567050.372 [CommandLine](INFO): Tracking.northings_to_contact (meter)
2013-08-27T01:30:50.372Z,1377567050.372 [CommandLine](INFO): Tracking.contact_depth (meter)
2013-08-27T01:30:50.373Z,1377567050.372 [CommandLine](INFO): Tracking.range_to_contact (meter)
2013-08-27T01:30:50.373Z,1377567050.373 [CommandLine](INFO): Tracking.azimuth_to_contact_vehicleFrame (radian)
2013-08-27T01:30:50.386Z,1377567050.386 [CommandLine](INFO): Tracking.elevation_to_contact_vehicleFrame (radian)
2013-08-27T01:30:50.386Z,1377567050.386 [CommandLine](INFO): Tracking.heading_to_contact (degree)
2013-08-27T01:30:50.386Z,1377567050.386 [CommandLine](INFO): Tracking.direction_vehicleFrame (none)
2013-08-27T01:30:50.387Z,1377567050.387 [CommandLine](INFO): Tracking.direction_navigationFrame (none)
2013-08-27T01:30:50.387Z,1377567050.387 [CommandLine](INFO): Tracking.relativePosition_vehicleFrame (none)
2013-08-27T01:30:50.388Z,1377567050.388 [CommandLine](INFO): Tracking.relativePosition_navigationFrame (none)
2013-08-27T01:30:59.598Z,1377567059.598 [CommandLine](IMPORTANT): got command report touch Tracking.contact_latitude
2013-08-27T01:31:06.994Z,1377567066.994 [CommandLine](IMPORTANT): got command show variable DAT
2013-08-27T01:31:06.999Z,1377567066.999 [CommandLine](INFO): NAL9602.sendDataToShore (bool)
2013-08-27T01:31:07.002Z,1377567067.002 [CommandLine](INFO): DAT.loadControl (none)
2013-08-27T01:31:07.002Z,1377567067.002 [CommandLine](INFO): DAT.uart (none)
2013-08-27T01:31:07.002Z,1377567067.003 [CommandLine](INFO): DAT.baud (bit_per_second)
2013-08-27T01:31:07.005Z,1377567067.005 [CommandLine](INFO): AHRS_3DMGX3.loadAtStartup (bool)
2013-08-27T01:31:07.006Z,1377567067.006 [CommandLine](INFO): AHRS_sp3003D.loadAtStartup (bool)
2013-08-27T01:31:07.007Z,1377567067.007 [CommandLine](INFO): Batt_Ocean_Server.loadAtStartup (bool)
2013-08-27T01:31:07.007Z,1377567067.007 [CommandLine](INFO): DAT.loadAtStartup (bool)
2013-08-27T01:31:07.007Z,1377567067.007 [CommandLine](INFO): DAT.simulateHardware (bool)
2013-08-27T01:31:07.008Z,1377567067.008 [CommandLine](INFO): DAT.remoteAddress (count)
2013-08-27T01:31:07.008Z,1377567067.008 [CommandLine](INFO): DAT.rotationOffset (degree)
2013-08-27T01:31:07.009Z,1377567067.009 [CommandLine](INFO): Depth_Keller.loadAtStartup (bool)
2013-08-27T01:31:07.018Z,1377567067.017 [CommandLine](INFO): DropWeight.loadAtStartup (bool)
2013-08-27T01:31:07.018Z,1377567067.018 [CommandLine](INFO): DVL_micro.loadAtStartup (bool)
2013-08-27T01:31:07.019Z,1377567067.019 [CommandLine](INFO): NAL9602.loadAtStartup (bool)
2013-08-27T01:31:07.019Z,1377567067.019 [CommandLine](INFO): Onboard.loadAtStartup (bool)
2013-08-27T01:31:07.020Z,1377567067.020 [CommandLine](INFO): Radio_Freewave.loadAtStartup (bool)
2013-08-27T01:31:07.020Z,1377567067.020 [CommandLine](INFO): SCPI.loadAtStartup (bool)
2013-08-27T01:31:07.021Z,1377567067.021 [CommandLine](INFO): AsyncPiEstimator.loadAtStartup (bool)
2013-08-27T01:31:07.021Z,1377567067.021 [CommandLine](INFO): CBIT.loadAtStartup (bool)
2013-08-27T01:31:07.023Z,1377567067.023 [CommandLine](INFO): SBIT.loadAtStartup (bool)
2013-08-27T01:31:07.023Z,1377567067.023 [CommandLine](INFO): IBIT.loadAtStartup (bool)
2013-08-27T01:31:07.023Z,1377567067.023 [CommandLine](INFO): BuoyancyServo.loadAtStartup (bool)
2013-08-27T01:31:07.024Z,1377567067.024 [CommandLine](INFO): ElevatorServo.loadAtStartup (bool)
2013-08-27T01:31:07.025Z,1377567067.025 [CommandLine](INFO): MassServo.loadAtStartup (bool)
2013-08-27T01:31:07.030Z,1377567067.030 [CommandLine](INFO): RudderServo.loadAtStartup (bool)
2013-08-27T01:31:07.032Z,1377567067.032 [CommandLine](INFO): ThrusterServo.loadAtStartup (bool)
2013-08-27T01:31:07.032Z,1377567067.032 [CommandLine](INFO): Aanderaa_O2.loadAtStartup (bool)
2013-08-27T01:31:07.033Z,1377567067.033 [CommandLine](INFO): CTD_NeilBrown.loadAtStartup (bool)
2013-08-27T01:31:07.038Z,1377567067.038 [CommandLine](INFO): ISUS.loadAtStartup (bool)
2013-08-27T01:31:07.038Z,1377567067.039 [CommandLine](INFO): PAR_Licor.loadAtStartup (bool)
2013-08-27T01:31:07.039Z,1377567067.039 [CommandLine](INFO): Turbulence_NPS.loadAtStartup (bool)
2013-08-27T01:31:07.040Z,1377567067.040 [CommandLine](INFO): WetLabsBB2FL.loadAtStartup (bool)
2013-08-27T01:31:07.041Z,1377567067.040 [CommandLine](INFO): HorizontalControl.loadAtStartup (bool)
2013-08-27T01:31:07.050Z,1377567067.050 [CommandLine](INFO): LoopControl.loadAtStartup (bool)
2013-08-27T01:31:07.050Z,1377567067.050 [CommandLine](INFO): SpeedControl.loadAtStartup (bool)
2013-08-27T01:31:07.051Z,1377567067.051 [CommandLine](INFO): VerticalControl.loadAtStartup (bool)
2013-08-27T01:31:07.058Z,1377567067.059 [CommandLine](INFO): ExternalSim.loadAtStartup (bool)
2013-08-27T01:31:07.059Z,1377567067.059 [CommandLine](INFO): InternalSim.loadAtStartup (bool)
2013-08-27T01:31:07.059Z,1377567067.059 [CommandLine](INFO): NavigationSim.loadAtStartup (bool)
2013-08-27T01:31:07.082Z,1377567067.082 [CommandLine](INFO): Config/Simulator.oceanModelData (none)
2013-08-27T01:31:07.083Z,1377567067.083 [CommandLine](INFO): DepthRateCalculator.loadAtStartup (bool)
2013-08-27T01:31:07.084Z,1377567067.084 [CommandLine](INFO): TempGradientCalculator.loadAtStartup (bool)
2013-08-27T01:31:07.084Z,1377567067.084 [CommandLine](INFO): PitchRateCalculator.loadAtStartup (bool)
2013-08-27T01:31:07.085Z,1377567067.085 [CommandLine](INFO): SpeedCalculator.loadAtStartup (bool)
2013-08-27T01:31:07.085Z,1377567067.085 [CommandLine](INFO): YawRateCalculator.loadAtStartup (bool)
2013-08-27T01:31:07.090Z,1377567067.090 [CommandLine](INFO): Navigation.loadAtStartup (bool)
2013-08-27T01:31:07.090Z,1377567067.090 [CommandLine](INFO): NavChart.loadAtStartup (bool)
2013-08-27T01:31:07.091Z,1377567067.091 [CommandLine](INFO): HFRadarModelCalc.loadAtStartup (bool)
2013-08-27T01:31:07.095Z,1377567067.095 [CommandLine](INFO): Navigation.useHFRadarData (bool)
2013-08-27T01:31:07.102Z,1377567067.102 [CommandLine](INFO): DAT.queryAddressRequested (count)
2013-08-27T01:31:07.103Z,1377567067.103 [CommandLine](INFO): DAT.onewayModeRequested (bool)
2013-08-27T01:31:07.103Z,1377567067.103 [CommandLine](INFO): DAT.numberOfPingsRequested (count)
2013-08-27T01:31:07.104Z,1377567067.104 [CommandLine](INFO): DAT.LVL1 (count)
2013-08-27T01:31:07.104Z,1377567067.104 [CommandLine](INFO): DAT.LVL2 (count)
2013-08-27T01:31:07.104Z,1377567067.104 [CommandLine](INFO): DAT.LVL3 (count)
2013-08-27T01:31:07.105Z,1377567067.105 [CommandLine](INFO): DAT.LVL4 (count)
2013-08-27T01:31:07.105Z,1377567067.105 [CommandLine](INFO): DAT.AGC (count)
2013-08-27T01:31:07.118Z,1377567067.118 [CommandLine](INFO): DAT.IDXPeak (count)
2013-08-27T01:31:07.118Z,1377567067.118 [CommandLine](INFO): DAT.IDXFit (count)
2013-08-27T01:31:07.119Z,1377567067.119 [CommandLine](INFO): DAT.IDXPhase (radian)
2013-08-27T01:31:07.119Z,1377567067.119 [CommandLine](INFO): DAT.phaseA (radian)
2013-08-27T01:31:07.119Z,1377567067.119 [CommandLine](INFO): DAT.phaseB (radian)
2013-08-27T01:31:07.120Z,1377567067.120 [CommandLine](INFO): DAT.phaseC (radian)
2013-08-27T01:31:07.120Z,1377567067.120 [CommandLine](INFO): DAT.vectorMagnitude (none)
2013-08-27T01:31:07.120Z,1377567067.121 [CommandLine](INFO): DAT.rawAzimuth (degree)
2013-08-27T01:31:07.121Z,1377567067.121 [CommandLine](INFO): DAT.rawElevation (degree)
2013-08-27T01:31:07.126Z,1377567067.126 [CommandLine](INFO): DAT.calibratedAzimuth (degree)
2013-08-27T01:31:07.126Z,1377567067.126 [CommandLine](INFO): DAT.calibratedElevation (degree)
2013-08-27T01:31:07.127Z,1377567067.127 [CommandLine](INFO): DAT.rotatedAzimuth (degree)
2013-08-27T01:31:07.127Z,1377567067.127 [CommandLine](INFO): DAT.rotatedElevation (degree)
2013-08-27T01:31:07.128Z,1377567067.128 [CommandLine](INFO): DAT.localAddress (enum)
2013-08-27T01:31:07.128Z,1377567067.128 [CommandLine](INFO): DAT.range (meter)
2013-08-27T01:31:07.128Z,1377567067.128 [CommandLine](INFO): DAT.onewayRequestedCmd (bool)
2013-08-27T01:31:07.129Z,1377567067.129 [CommandLine](INFO): DAT.azimuth_instrumentFrame (radian)
2013-08-27T01:31:07.134Z,1377567067.134 [CommandLine](INFO): DAT.elevation_instrumentFrame (radian)
2013-08-27T01:31:07.134Z,1377567067.134 [CommandLine](INFO): DAT.azimuth_vehicleFrame (radian)
2013-08-27T01:31:07.135Z,1377567067.135 [CommandLine](INFO): DAT.elevation_vehicleFrame (radian)
2013-08-27T01:31:07.135Z,1377567067.135 [CommandLine](INFO): DAT.direction_instrumentFrame (none)
2013-08-27T01:31:07.136Z,1377567067.136 [CommandLine](INFO): DAT.direction_vehicleFrame (none)
2013-08-27T01:31:07.136Z,1377567067.136 [CommandLine](INFO): DAT.xDirection_vehicleFrame (none)
2013-08-27T01:31:07.137Z,1377567067.137 [CommandLine](INFO): DAT.yDirection_vehicleFrame (none)
2013-08-27T01:31:07.137Z,1377567067.137 [CommandLine](INFO): DAT.zDirection_vehicleFrame (none)
2013-08-27T01:31:07.147Z,1377567067.147 [CommandLine](INFO): DAT.durationOfLastRun (second)
2013-08-27T01:31:07.154Z,1377567067.154 [CommandLine](INFO): DAT.component_voltage (volt)
2013-08-27T01:31:07.155Z,1377567067.155 [CommandLine](INFO): DAT.component_avgVoltage (volt)
2013-08-27T01:31:07.155Z,1377567067.155 [CommandLine](INFO): DAT.component_current (milliampere)
2013-08-27T01:31:07.155Z,1377567067.155 [CommandLine](INFO): DAT.component_avgCurrent (milliampere)
2013-08-27T01:31:07.160Z,1377567067.159 [CommandLine](INFO): CANON_LST_Track:SurfaceGPS:SurfaceNavUpdate.SurfaceDepthRate (meter_per_second)
2013-08-27T01:31:07.160Z,1377567067.160 [CommandLine](INFO): CANON_LST_Track:SurfaceGPS:SurfaceNavUpdate.SurfacePitch (degree)
2013-08-27T01:31:07.161Z,1377567067.161 [CommandLine](INFO): CANON_LST_Track:SurfaceGPS:SurfaceNavUpdate.SurfaceSpeed (meter_per_second)
2013-08-27T01:31:07.161Z,1377567067.161 [CommandLine](INFO): CANON_LST_Track:SurfaceGPS:SurfaceNavUpdate.UnpackSplitAtSurface (bool)
2013-08-27T01:31:13.885Z,1377567073.885 [SBIT](IMPORTANT): SBIT PASSED
2013-08-27T01:31:14.256Z,1377567074.256 [MissionManager](IMPORTANT): Started mission Startup
2013-08-27T01:31:14.256Z,1377567074.256 [Startup] Running Loop=1
2013-08-27T01:31:14.256Z,1377567074.256 [Startup](INFO): Aggregate::initialize Startup
2013-08-27T01:31:14.257Z,1377567074.257 [Startup:A.GoToSurface] Running Loop=1
2013-08-27T01:31:14.257Z,1377567074.257 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-27T01:31:14.263Z,1377567074.263 [Startup:StartupSatComms] Running Loop=1
2013-08-27T01:31:14.264Z,1377567074.263 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2013-08-27T01:31:14.264Z,1377567074.264 [Startup:StartupSatComms:A] Running Loop=1
2013-08-27T01:31:14.680Z,1377567074.680 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-08-27T01:31:29.518Z,1377567089.518 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2013-08-27T01:31:29.881Z,1377567089.881 [Startup:StartupSatComms:A] Stopped
2013-08-27T01:31:29.881Z,1377567089.881 [Startup:StartupSatComms:B] Running Loop=1
2013-08-27T01:31:30.333Z,1377567090.333 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-08-27T01:31:34.864Z,1377567094.864 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-08-27T01:31:36.598Z,1377567096.598 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2013-08-27T01:31:41.692Z,1377567101.692 [Startup:StartupSatComms:B] Stopped
2013-08-27T01:31:41.692Z,1377567101.692 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-08-27T01:31:41.692Z,1377567101.693 [Startup:StartupSatComms] Stopped
2013-08-27T01:31:41.693Z,1377567101.693 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2013-08-27T01:31:41.694Z,1377567101.694 [Startup](INFO): Completed Startup
2013-08-27T01:31:41.694Z,1377567101.694 [Startup] Stopped
2013-08-27T01:31:41.694Z,1377567101.694 [Startup](INFO): Aggregate::uninitialize Startup
2013-08-27T01:31:41.694Z,1377567101.694 [Startup:A.GoToSurface] Stopped
2013-08-27T01:31:41.694Z,1377567101.694 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-27T01:31:42.678Z,1377567102.678 [MissionManager](IMPORTANT): Started mission Default
2013-08-27T01:31:42.678Z,1377567102.678 [Default] Running Loop=1
2013-08-27T01:31:42.678Z,1377567102.678 [Default](INFO): Aggregate::initialize Default
2013-08-27T01:31:42.678Z,1377567102.678 [Default:D.SetSpeed] Running Loop=1
2013-08-27T01:31:42.678Z,1377567102.678 [Default:D.SetSpeed](DEBUG): Initialize.
2013-08-27T01:31:42.678Z,1377567102.678 [Default:E.GoToSurface] Running Loop=1
2013-08-27T01:31:42.678Z,1377567102.678 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-27T01:31:42.678Z,1377567102.678 [Default:Iridium] Running Loop=1
2013-08-27T01:31:42.678Z,1377567102.678 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-08-27T01:31:42.679Z,1377567102.679 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-08-27T01:31:42.679Z,1377567102.679 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-08-27T01:31:42.679Z,1377567102.679 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-08-27T01:31:42.679Z,1377567102.679 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-27T01:31:42.679Z,1377567102.679 [Default:E.GoToSurface] Running Loop=1
2013-08-27T01:31:42.684Z,1377567102.684 [Default:D.SetSpeed] Running Loop=1
2013-08-27T01:31:42.695Z,1377567102.695 [Default:Iridium:B.GoToSurface] Stopped
2013-08-27T01:31:42.695Z,1377567102.695 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-27T01:31:42.695Z,1377567102.695 [Default:Iridium:Read_Iridium] Running Loop=1
2013-08-27T01:31:42.695Z,1377567102.695 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-08-27T01:31:42.700Z,1377567102.700 [Default:GPS] Running Loop=1
2013-08-27T01:31:42.700Z,1377567102.700 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-08-27T01:31:42.700Z,1377567102.700 [Default:GPS:A.SetSpeed] Running Loop=1
2013-08-27T01:31:42.700Z,1377567102.700 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-08-27T01:31:42.700Z,1377567102.700 [Default:GPS:B.GoToSurface] Running Loop=1
2013-08-27T01:31:42.700Z,1377567102.700 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-27T01:31:42.706Z,1377567102.706 [Default:GPS:B.GoToSurface] Stopped
2013-08-27T01:31:42.706Z,1377567102.706 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-27T01:31:42.706Z,1377567102.706 [Default:GPS:Read_GPS] Running Loop=1
2013-08-27T01:31:42.706Z,1377567102.706 [Default:GPS:A.SetSpeed] Running Loop=1
2013-08-27T01:31:42.932Z,1377567102.932 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-08-27T01:31:42.948Z,1377567102.948 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-08-27T01:31:42.951Z,1377567102.951 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-08-27T01:31:46.956Z,1377567106.956 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-08-27T01:31:48.378Z,1377567108.378 [CommandLine](IMPORTANT): got command run
2013-08-27T01:31:48.382Z,1377567108.382 [CommandLine](IMPORTANT): Running 
2013-08-27T01:31:48.590Z,1377567108.590 [Default] Stopped
2013-08-27T01:31:48.590Z,1377567108.590 [Default](INFO): Aggregate::uninitialize Default
2013-08-27T01:31:48.590Z,1377567108.591 [Default:GPS] Stopped
2013-08-27T01:31:48.591Z,1377567108.591 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-08-27T01:31:48.591Z,1377567108.591 [Default:GPS:A.SetSpeed] Stopped
2013-08-27T01:31:48.591Z,1377567108.591 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-08-27T01:31:48.591Z,1377567108.591 [Default:GPS:Read_GPS] Stopped
2013-08-27T01:31:48.591Z,1377567108.591 [Default:Iridium] Stopped
2013-08-27T01:31:48.591Z,1377567108.591 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-08-27T01:31:48.591Z,1377567108.591 [Default:Iridium:A.SetSpeed] Stopped
2013-08-27T01:31:48.591Z,1377567108.591 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-08-27T01:31:48.591Z,1377567108.591 [Default:Iridium:Read_Iridium] Stopped
2013-08-27T01:31:48.591Z,1377567108.591 [Default:D.SetSpeed] Stopped
2013-08-27T01:31:48.591Z,1377567108.591 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-08-27T01:31:48.591Z,1377567108.591 [Default:E.GoToSurface] Stopped
2013-08-27T01:31:48.591Z,1377567108.592 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-27T01:31:48.592Z,1377567108.592 [MissionManager](IMPORTANT): Started mission CANON_LST_Track
2013-08-27T01:31:48.592Z,1377567108.592 [CANON_LST_Track] Running Loop=1
2013-08-27T01:31:48.592Z,1377567108.592 [CANON_LST_Track](INFO): Aggregate::initialize CANON_LST_Track
2013-08-27T01:31:48.592Z,1377567108.592 [CANON_LST_Track:A.AltitudeEnvelope] Running Loop=1
2013-08-27T01:31:48.592Z,1377567108.592 [CANON_LST_Track:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2013-08-27T01:31:48.592Z,1377567108.592 [CANON_LST_Track:B.DepthEnvelope] Running Loop=1
2013-08-27T01:31:48.592Z,1377567108.592 [CANON_LST_Track:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2013-08-27T01:31:48.594Z,1377567108.594 [CANON_LST_Track:C.OffshoreEnvelope] Running Loop=1
2013-08-27T01:31:48.594Z,1377567108.594 [CANON_LST_Track:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2013-08-27T01:31:48.594Z,1377567108.594 [CANON_LST_Track:I.Pitch] Running Loop=1
2013-08-27T01:31:48.594Z,1377567108.594 [CANON_LST_Track:I.Pitch](DEBUG): Initialize.
2013-08-27T01:31:48.594Z,1377567108.594 [CANON_LST_Track:J.Buoyancy] Running Loop=1
2013-08-27T01:31:48.594Z,1377567108.594 [CANON_LST_Track:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2013-08-27T01:31:48.595Z,1377567108.595 [CANON_LST_Track:L.] Running Loop=1
2013-08-27T01:31:48.595Z,1377567108.595 [CANON_LST_Track:L.](INFO): Initializing Tracking.
2013-08-27T01:31:48.595Z,1377567108.595 [CANON_LST_Track:SurfaceComms] Running Loop=1
2013-08-27T01:31:48.595Z,1377567108.595 [CANON_LST_Track:SurfaceComms](INFO): Aggregate::initialize CANON_LST_Track:SurfaceComms
2013-08-27T01:31:48.595Z,1377567108.595 [CANON_LST_Track:SurfaceComms:A.GoToSurface] Running Loop=1
2013-08-27T01:31:48.595Z,1377567108.595 [CANON_LST_Track:SurfaceComms:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-27T01:31:48.596Z,1377567108.596 [CANON_LST_Track:waiting] Running Loop=1
2013-08-27T01:31:48.596Z,1377567108.596 [CANON_LST_Track:waiting](INFO): Aggregate::initialize CANON_LST_Track:waiting
2013-08-27T01:31:48.596Z,1377567108.596 [CANON_LST_Track:waiting:A] Running Loop=1
2013-08-27T01:31:48.596Z,1377567108.596 [CANON_LST_Track:waiting:A](IMPORTANT): Waiting for LST location
2013-08-27T01:31:48.599Z,1377567108.599 [CANON_LST_Track:waiting:A] Stopped
2013-08-27T01:31:48.599Z,1377567108.599 [CANON_LST_Track:waiting:B.Wait] Running Loop=1
2013-08-27T01:31:48.599Z,1377567108.599 [CANON_LST_Track:waiting:B.Wait](DEBUG): Initialize Wait Component.
2013-08-27T01:31:48.599Z,1377567108.599 [CANON_LST_Track:L.] Running Loop=1
2013-08-27T01:31:48.599Z,1377567108.599 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:31:48.605Z,1377567108.605 [CANON_LST_Track:J.Buoyancy] Running Loop=1
2013-08-27T01:31:48.610Z,1377567108.610 [CANON_LST_Track:I.Pitch] Running Loop=1
2013-08-27T01:31:48.622Z,1377567108.622 [CANON_LST_Track:SurfaceComms:B] Running Loop=1
2013-08-27T01:31:48.622Z,1377567108.622 [CANON_LST_Track:SurfaceComms:B](INFO): Aggregate::initialize CANON_LST_Track:SurfaceComms:B
2013-08-27T01:31:48.622Z,1377567108.622 [CANON_LST_Track:SurfaceComms:B:A] Running Loop=1
2013-08-27T01:31:48.622Z,1377567108.622 [CANON_LST_Track:C.OffshoreEnvelope] Running Loop=1
2013-08-27T01:31:48.628Z,1377567108.628 [CANON_LST_Track:B.DepthEnvelope] Running Loop=1
2013-08-27T01:31:48.634Z,1377567108.634 [CANON_LST_Track:A.AltitudeEnvelope] Running Loop=1
2013-08-27T01:31:48.718Z,1377567108.718 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-08-27T01:31:48.719Z,1377567108.719 [DVL_micro](INFO): resume:Powering up
2013-08-27T01:31:48.719Z,1377567108.719 [DVL_micro](INFO): Cycling power to configure device.
2013-08-27T01:31:48.924Z,1377567108.924 [DAT](INFO): Powering up
2013-08-27T01:31:48.939Z,1377567108.939 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:31:48.941Z,1377567108.941 [CANON_LST_Track:SurfaceComms:B] Stopped
2013-08-27T01:31:48.941Z,1377567108.941 [CANON_LST_Track:SurfaceComms:B](INFO): Aggregate::uninitialize CANON_LST_Track:SurfaceComms:B
2013-08-27T01:31:48.941Z,1377567108.941 [CANON_LST_Track:SurfaceComms:B:A] Stopped
2013-08-27T01:31:48.941Z,1377567108.941 [CANON_LST_Track:SurfaceComms:C] Running Loop=1
2013-08-27T01:31:48.941Z,1377567108.941 [CANON_LST_Track:SurfaceComms:C](INFO): Aggregate::initialize CANON_LST_Track:SurfaceComms:C
2013-08-27T01:31:48.941Z,1377567108.941 [CANON_LST_Track:SurfaceComms:C:A] Running Loop=1
2013-08-27T01:31:49.380Z,1377567109.380 [CANON_LST_Track:L.] Preempted
2013-08-27T01:31:49.380Z,1377567109.381 [CANON_LST_Track:J.Buoyancy] Preempted
2013-08-27T01:31:49.380Z,1377567109.381 [CANON_LST_Track:I.Pitch] Preempted
2013-08-27T01:31:49.382Z,1377567109.382 [CANON_LST_Track:SurfaceComms:C:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-08-27T01:31:50.726Z,1377567110.726 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-08-27T01:31:50.726Z,1377567110.726 [DVL_micro](INFO): Querying output modes
2013-08-27T01:31:50.726Z,1377567110.726 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-08-27T01:31:50.738Z,1377567110.738 [DVL_micro](DEBUG): cmdResponse: 01
2013-08-27T01:31:50.738Z,1377567110.738 [DVL_micro](INFO): NQ1 output enabled
2013-08-27T01:31:50.738Z,1377567110.738 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-08-27T01:31:50.750Z,1377567110.750 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-08-27T01:31:53.262Z,1377567113.262 [DVL_micro](DEBUG): NQ1
2013-08-27T01:31:54.922Z,1377567114.922 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2013-08-27T01:31:55.818Z,1377567115.818 [DVL_micro](DEBUG): NQ1
2013-08-27T01:31:58.370Z,1377567118.370 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:00.914Z,1377567120.914 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:03.462Z,1377567123.462 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:04.282Z,1377567124.282 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2013-08-27T01:32:04.427Z,1377567124.427 [CANON_LST_Track:SurfaceComms:C:A] Stopped
2013-08-27T01:32:04.427Z,1377567124.427 [CANON_LST_Track:SurfaceComms:C:B] Running Loop=1
2013-08-27T01:32:04.827Z,1377567124.827 [CANON_LST_Track:SurfaceComms:C:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-08-27T01:32:06.014Z,1377567126.014 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:08.028Z,1377567128.028 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-08-27T01:32:08.558Z,1377567128.558 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:11.106Z,1377567131.106 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:12.331Z,1377567132.332 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-08-27T01:32:13.662Z,1377567133.662 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:14.358Z,1377567134.358 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2013-08-27T01:32:16.202Z,1377567136.202 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:17.964Z,1377567137.964 [CANON_LST_Track:SurfaceComms:C:B] Stopped
2013-08-27T01:32:17.964Z,1377567137.964 [CANON_LST_Track:SurfaceComms:C:C] Running Loop=1
2013-08-27T01:32:18.344Z,1377567138.344 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-08-27T01:32:18.390Z,1377567138.390 [CANON_LST_Track:SurfaceComms:C:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-08-27T01:32:18.767Z,1377567138.767 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:21.314Z,1377567141.314 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:23.865Z,1377567143.865 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:23.975Z,1377567143.974 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2013-08-27T01:32:26.426Z,1377567146.426 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:28.970Z,1377567148.970 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:31.553Z,1377567151.553 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:34.110Z,1377567154.110 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:36.658Z,1377567156.658 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:39.234Z,1377567159.234 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:41.778Z,1377567161.778 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:44.326Z,1377567164.326 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:46.874Z,1377567166.874 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:49.430Z,1377567169.430 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:51.986Z,1377567171.986 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:54.546Z,1377567174.546 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:57.102Z,1377567177.102 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:58.338Z,1377567178.338 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2013-08-27T01:32:58.686Z,1377567178.686 [CANON_LST_Track:SurfaceComms:C:C] Stopped
2013-08-27T01:32:58.687Z,1377567178.687 [CANON_LST_Track:SurfaceComms:C](INFO): Completed CANON_LST_Track:SurfaceComms:C
2013-08-27T01:32:58.687Z,1377567178.687 [CANON_LST_Track:SurfaceComms:C] Stopped
2013-08-27T01:32:58.687Z,1377567178.687 [CANON_LST_Track:SurfaceComms:C](INFO): Aggregate::uninitialize CANON_LST_Track:SurfaceComms:C
2013-08-27T01:32:58.688Z,1377567178.688 [CANON_LST_Track:SurfaceComms](INFO): Completed CANON_LST_Track:SurfaceComms
2013-08-27T01:32:58.688Z,1377567178.688 [CANON_LST_Track:SurfaceComms] Stopped
2013-08-27T01:32:58.688Z,1377567178.688 [CANON_LST_Track:SurfaceComms](INFO): Aggregate::uninitialize CANON_LST_Track:SurfaceComms
2013-08-27T01:32:58.688Z,1377567178.688 [CANON_LST_Track:SurfaceComms:A.GoToSurface] Stopped
2013-08-27T01:32:58.688Z,1377567178.688 [CANON_LST_Track:SurfaceComms:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-27T01:32:58.688Z,1377567178.688 [CANON_LST_Track:ScienceOps] Running Loop=1
2013-08-27T01:32:58.688Z,1377567178.688 [CANON_LST_Track:ScienceOps](INFO): Aggregate::initialize CANON_LST_Track:ScienceOps
2013-08-27T01:32:58.688Z,1377567178.688 [CANON_LST_Track:ScienceOps:Science] Running Loop=1
2013-08-27T01:32:58.689Z,1377567178.689 [CANON_LST_Track:ScienceOps:Science](INFO): Aggregate::initialize CANON_LST_Track:ScienceOps:Science
2013-08-27T01:32:58.689Z,1377567178.689 [CANON_LST_Track:ScienceOps:Science:A] Running Loop=1
2013-08-27T01:32:58.689Z,1377567178.689 [CANON_LST_Track:ScienceOps:Science:B] Running Loop=1
2013-08-27T01:32:58.689Z,1377567178.689 [CANON_LST_Track:ScienceOps:Science:C] Running Loop=1
2013-08-27T01:32:58.689Z,1377567178.689 [CANON_LST_Track:ScienceOps:Science:D] Running Loop=1
2013-08-27T01:32:58.689Z,1377567178.689 [CANON_LST_Track:ScienceOps:Science:E] Running Loop=1
2013-08-27T01:32:58.689Z,1377567178.689 [CANON_LST_Track:ScienceOps:Science:F] Running Loop=1
2013-08-27T01:32:58.689Z,1377567178.689 [CANON_LST_Track:ScienceOps:Science:G] Running Loop=1
2013-08-27T01:32:58.689Z,1377567178.689 [CANON_LST_Track:ScienceOps:Science:H] Running Loop=1
2013-08-27T01:32:58.689Z,1377567178.689 [CANON_LST_Track:ScienceOps:Science:I] Running Loop=1
2013-08-27T01:32:58.689Z,1377567178.690 [CANON_LST_Track:ScienceOps:B.SetSpeed] Running Loop=1
2013-08-27T01:32:58.690Z,1377567178.690 [CANON_LST_Track:ScienceOps:B.SetSpeed](DEBUG): Initialize.
2013-08-27T01:32:58.690Z,1377567178.690 [CANON_LST_Track:ScienceOps:C.DepthEnvelope] Running Loop=1
2013-08-27T01:32:58.690Z,1377567178.690 [CANON_LST_Track:ScienceOps:C.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2013-08-27T01:32:58.691Z,1377567178.691 [CANON_LST_Track:ScienceOps:D.AltitudeEnvelope] Running Loop=1
2013-08-27T01:32:58.691Z,1377567178.691 [CANON_LST_Track:ScienceOps:D.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2013-08-27T01:32:58.691Z,1377567178.691 [CANON_LST_Track:ScienceOps:E.YoYo] Running Loop=1
2013-08-27T01:32:58.691Z,1377567178.691 [CANON_LST_Track:ScienceOps:E.YoYo](DEBUG): Initialize YoYoComponent.
2013-08-27T01:32:58.692Z,1377567178.691 [CANON_LST_Track:ScienceOps:G.Wait] Running Loop=1
2013-08-27T01:32:58.692Z,1377567178.692 [CANON_LST_Track:ScienceOps:G.Wait](DEBUG): Initialize Wait Component.
2013-08-27T01:32:59.102Z,1377567179.102 [CANON_LST_Track:L.] Running Loop=1
2013-08-27T01:32:59.102Z,1377567179.102 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:32:59.103Z,1377567179.103 [CANON_LST_Track:J.Buoyancy] Running Loop=1
2013-08-27T01:32:59.103Z,1377567179.103 [CANON_LST_Track:I.Pitch] Running Loop=1
2013-08-27T01:32:59.424Z,1377567179.424 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:32:59.646Z,1377567179.645 [DVL_micro](DEBUG): NQ1
2013-08-27T01:32:59.861Z,1377567179.861 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:00.231Z,1377567180.231 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:00.620Z,1377567180.620 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:01.087Z,1377567181.087 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:01.431Z,1377567181.431 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:01.820Z,1377567181.820 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:02.210Z,1377567182.210 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:02.250Z,1377567182.250 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:02.635Z,1377567182.635 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:03.028Z,1377567183.028 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:03.491Z,1377567183.491 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:03.842Z,1377567183.842 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:04.280Z,1377567184.280 [NAL9602](INFO): Powering down
2013-08-27T01:33:04.303Z,1377567184.303 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:04.647Z,1377567184.647 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:04.762Z,1377567184.761 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:05.031Z,1377567185.031 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:05.420Z,1377567185.420 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:05.888Z,1377567185.888 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:06.231Z,1377567186.231 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:06.620Z,1377567186.620 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:07.035Z,1377567187.035 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:07.310Z,1377567187.309 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:07.435Z,1377567187.435 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:07.820Z,1377567187.820 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:08.288Z,1377567188.288 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:08.627Z,1377567188.627 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:09.020Z,1377567189.020 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:09.505Z,1377567189.505 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:09.827Z,1377567189.827 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:09.891Z,1377567189.891 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:10.224Z,1377567190.224 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:10.663Z,1377567190.663 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:11.027Z,1377567191.027 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:11.420Z,1377567191.420 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:11.841Z,1377567191.841 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:12.231Z,1377567192.231 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:12.434Z,1377567192.434 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:12.619Z,1377567192.619 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:13.086Z,1377567193.086 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:13.427Z,1377567193.427 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:13.820Z,1377567193.820 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:14.311Z,1377567194.311 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:14.627Z,1377567194.627 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:14.982Z,1377567194.981 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:15.035Z,1377567195.035 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:15.487Z,1377567195.487 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:15.853Z,1377567195.853 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:16.224Z,1377567196.224 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:16.635Z,1377567196.635 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:17.027Z,1377567197.027 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:17.424Z,1377567197.424 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:17.541Z,1377567197.542 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:17.887Z,1377567197.888 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:18.231Z,1377567198.231 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:18.628Z,1377567198.628 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:19.040Z,1377567199.039 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:19.506Z,1377567199.506 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:19.828Z,1377567199.828 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:20.106Z,1377567200.106 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:20.315Z,1377567200.315 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:20.643Z,1377567200.643 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:21.023Z,1377567201.023 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:21.439Z,1377567201.439 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:21.831Z,1377567201.831 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:22.225Z,1377567202.225 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:22.639Z,1377567202.639 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:22.654Z,1377567202.654 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:23.086Z,1377567203.086 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:23.419Z,1377567203.420 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:23.841Z,1377567203.842 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:24.231Z,1377567204.231 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:24.692Z,1377567204.691 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:25.039Z,1377567205.039 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:25.238Z,1377567205.238 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:25.486Z,1377567205.486 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:25.820Z,1377567205.820 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:26.239Z,1377567206.239 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:26.627Z,1377567206.627 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:27.020Z,1377567207.020 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:27.439Z,1377567207.439 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:27.794Z,1377567207.794 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:27.892Z,1377567207.892 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:28.224Z,1377567208.224 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:28.639Z,1377567208.639 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:29.027Z,1377567209.027 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:29.422Z,1377567209.422 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:29.916Z,1377567209.916 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:30.287Z,1377567210.287 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:30.357Z,1377567210.358 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:30.628Z,1377567210.628 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:31.039Z,1377567211.039 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:31.431Z,1377567211.431 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:31.855Z,1377567211.855 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:32.247Z,1377567212.247 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:32.686Z,1377567212.686 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:32.914Z,1377567212.914 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:33.024Z,1377567213.024 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:33.439Z,1377567213.439 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:33.826Z,1377567213.826 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:34.224Z,1377567214.224 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:34.647Z,1377567214.647 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:35.140Z,1377567215.140 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:35.439Z,1377567215.439 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:35.462Z,1377567215.462 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:35.849Z,1377567215.849 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:36.235Z,1377567216.235 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:36.635Z,1377567216.635 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:37.039Z,1377567217.039 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:37.456Z,1377567217.456 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:37.823Z,1377567217.823 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:38.034Z,1377567218.034 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:38.239Z,1377567218.239 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:38.627Z,1377567218.627 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:39.019Z,1377567219.019 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:39.439Z,1377567219.439 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:39.888Z,1377567219.888 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:40.296Z,1377567220.296 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:40.581Z,1377567220.582 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:40.655Z,1377567220.655 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:41.027Z,1377567221.027 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:41.419Z,1377567221.419 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:41.842Z,1377567221.842 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:42.264Z,1377567222.264 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:42.619Z,1377567222.619 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:43.039Z,1377567223.039 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:43.146Z,1377567223.145 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:43.427Z,1377567223.427 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:43.819Z,1377567223.819 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:44.239Z,1377567224.239 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:44.690Z,1377567224.690 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:45.094Z,1377567225.094 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:45.439Z,1377567225.439 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:45.710Z,1377567225.710 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:45.842Z,1377567225.842 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:46.223Z,1377567226.223 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:46.639Z,1377567226.639 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:47.027Z,1377567227.027 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:47.463Z,1377567227.463 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:47.882Z,1377567227.882 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:48.238Z,1377567228.239 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:48.279Z,1377567228.279 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:48.619Z,1377567228.619 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:49.039Z,1377567229.039 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:49.432Z,1377567229.432 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:49.858Z,1377567229.858 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:50.303Z,1377567230.303 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:50.631Z,1377567230.631 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:50.846Z,1377567230.846 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:51.035Z,1377567231.035 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:51.454Z,1377567231.454 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:51.835Z,1377567231.835 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:52.283Z,1377567232.283 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:52.659Z,1377567232.658 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:53.026Z,1377567233.026 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:53.406Z,1377567233.406 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:53.434Z,1377567233.434 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:53.842Z,1377567233.842 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:54.231Z,1377567234.231 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:54.659Z,1377567234.659 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:55.038Z,1377567235.039 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:55.499Z,1377567235.499 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:55.820Z,1377567235.820 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:55.966Z,1377567235.966 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:56.243Z,1377567236.243 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:56.628Z,1377567236.628 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:56.758Z,1377567236.758 [CommandLine](IMPORTANT): got command show variable range
2013-08-27T01:33:56.842Z,1377567236.842 [CommandLine](INFO): DAT.range (meter)
2013-08-27T01:33:56.854Z,1377567236.854 [CommandLine](INFO): Tracking.range_to_contact (meter)
2013-08-27T01:33:57.055Z,1377567237.055 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:57.439Z,1377567237.439 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:57.822Z,1377567237.822 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:58.223Z,1377567238.223 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:58.521Z,1377567238.522 [DVL_micro](DEBUG): NQ1
2013-08-27T01:33:58.659Z,1377567238.659 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:59.027Z,1377567239.027 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:59.459Z,1377567239.459 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:33:59.841Z,1377567239.841 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:00.231Z,1377567240.231 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:00.688Z,1377567240.688 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:01.038Z,1377567241.038 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:01.078Z,1377567241.078 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:01.427Z,1377567241.427 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:01.863Z,1377567241.863 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:02.242Z,1377567242.242 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:02.622Z,1377567242.622 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:03.019Z,1377567243.019 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:03.442Z,1377567243.443 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:03.634Z,1377567243.634 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:03.863Z,1377567243.863 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:04.263Z,1377567244.263 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:04.638Z,1377567244.638 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:05.023Z,1377567245.023 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:05.424Z,1377567245.424 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:05.903Z,1377567245.903 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:06.206Z,1377567246.206 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:06.247Z,1377567246.247 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:06.683Z,1377567246.683 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:07.047Z,1377567247.047 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:07.447Z,1377567247.447 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:07.827Z,1377567247.827 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:08.247Z,1377567248.247 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:08.647Z,1377567248.647 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:08.762Z,1377567248.762 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:09.063Z,1377567249.063 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:09.438Z,1377567249.438 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:09.831Z,1377567249.831 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:10.223Z,1377567250.223 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:10.639Z,1377567250.639 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:11.102Z,1377567251.102 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:11.310Z,1377567251.309 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:11.483Z,1377567251.483 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:11.841Z,1377567251.841 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:12.231Z,1377567252.231 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:12.619Z,1377567252.619 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:13.038Z,1377567253.039 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:13.428Z,1377567253.428 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:13.863Z,1377567253.863 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:13.866Z,1377567253.866 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:14.243Z,1377567254.243 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:14.627Z,1377567254.627 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:15.019Z,1377567255.019 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:15.438Z,1377567255.438 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:15.828Z,1377567255.828 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:16.324Z,1377567256.324 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:16.426Z,1377567256.426 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:16.647Z,1377567256.647 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:17.027Z,1377567257.027 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:17.419Z,1377567257.419 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:17.841Z,1377567257.841 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:18.227Z,1377567258.227 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:18.659Z,1377567258.659 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:18.967Z,1377567258.967 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:19.038Z,1377567259.039 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:19.434Z,1377567259.434 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:19.844Z,1377567259.844 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:20.243Z,1377567260.243 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:20.627Z,1377567260.627 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:21.059Z,1377567261.059 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:21.505Z,1377567261.505 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:21.510Z,1377567261.510 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:21.827Z,1377567261.828 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:22.223Z,1377567262.223 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:22.647Z,1377567262.647 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:23.035Z,1377567263.035 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:23.488Z,1377567263.488 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:23.849Z,1377567263.849 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:24.074Z,1377567264.074 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:24.248Z,1377567264.247 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:24.635Z,1377567264.635 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:25.043Z,1377567265.043 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:25.427Z,1377567265.427 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:25.863Z,1377567265.863 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:26.246Z,1377567266.246 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:26.642Z,1377567266.642 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:26.719Z,1377567266.719 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:27.019Z,1377567267.019 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:27.438Z,1377567267.438 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:27.827Z,1377567267.827 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:28.263Z,1377567268.263 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:28.638Z,1377567268.638 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:29.022Z,1377567269.022 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:29.234Z,1377567269.234 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:29.423Z,1377567269.423 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:29.841Z,1377567269.841 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:30.231Z,1377567270.231 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:30.619Z,1377567270.619 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:31.086Z,1377567271.086 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:31.424Z,1377567271.424 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:31.770Z,1377567271.770 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:31.908Z,1377567271.908 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:32.242Z,1377567272.242 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:32.627Z,1377567272.627 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:33.019Z,1377567273.019 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:33.463Z,1377567273.463 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:33.822Z,1377567273.822 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:34.223Z,1377567274.223 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:34.342Z,1377567274.342 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:34.646Z,1377567274.647 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:35.022Z,1377567275.022 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:35.423Z,1377567275.423 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:35.907Z,1377567275.907 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:36.236Z,1377567276.236 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:36.619Z,1377567276.619 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:36.898Z,1377567276.898 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:37.119Z,1377567277.118 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:37.431Z,1377567277.431 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:37.819Z,1377567277.819 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:38.285Z,1377567278.285 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:38.630Z,1377567278.630 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:39.027Z,1377567279.027 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:39.446Z,1377567279.446 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:39.488Z,1377567279.488 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:39.835Z,1377567279.835 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:40.231Z,1377567280.231 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:40.703Z,1377567280.703 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:41.026Z,1377567281.026 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:41.419Z,1377567281.419 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:41.845Z,1377567281.845 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:41.989Z,1377567281.990 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:42.302Z,1377567282.302 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:42.619Z,1377567282.619 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:43.087Z,1377567283.087 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:43.427Z,1377567283.427 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:43.819Z,1377567283.819 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:44.243Z,1377567284.243 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:44.570Z,1377567284.570 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:44.644Z,1377567284.644 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:45.019Z,1377567285.019 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:45.487Z,1377567285.487 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:45.827Z,1377567285.827 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:46.223Z,1377567286.223 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:46.639Z,1377567286.639 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:47.024Z,1377567287.024 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:47.117Z,1377567287.118 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:47.495Z,1377567287.495 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:47.891Z,1377567287.891 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:48.231Z,1377567288.231 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:48.619Z,1377567288.619 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:49.038Z,1377567289.039 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:49.427Z,1377567289.427 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:49.682Z,1377567289.681 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:49.827Z,1377567289.827 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:50.291Z,1377567290.291 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:50.627Z,1377567290.627 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:51.019Z,1377567291.019 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:51.443Z,1377567291.443 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:51.885Z,1377567291.885 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:52.234Z,1377567292.234 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:52.303Z,1377567292.303 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:52.687Z,1377567292.687 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:53.027Z,1377567293.027 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:53.423Z,1377567293.423 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:53.841Z,1377567293.841 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:54.227Z,1377567294.227 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:54.627Z,1377567294.627 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:54.805Z,1377567294.806 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:55.111Z,1377567295.111 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:55.447Z,1377567295.447 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:55.827Z,1377567295.827 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:56.247Z,1377567296.247 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:56.647Z,1377567296.647 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:57.023Z,1377567297.023 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:57.370Z,1377567297.370 [DVL_micro](DEBUG): NQ1
2013-08-27T01:34:57.587Z,1377567297.587 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:57.846Z,1377567297.846 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:58.235Z,1377567298.235 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:58.643Z,1377567298.643 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:59.034Z,1377567299.034 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:59.434Z,1377567299.434 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:59.888Z,1377567299.888 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:34:59.938Z,1377567299.938 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:00.243Z,1377567300.243 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:00.631Z,1377567300.631 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:01.050Z,1377567301.050 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:01.439Z,1377567301.439 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:01.831Z,1377567301.831 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:02.292Z,1377567302.292 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:02.521Z,1377567302.522 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:02.723Z,1377567302.723 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:03.031Z,1377567303.031 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:03.451Z,1377567303.451 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:03.834Z,1377567303.834 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:04.235Z,1377567304.235 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:04.693Z,1377567304.693 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:05.034Z,1377567305.034 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:05.083Z,1377567305.083 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:05.431Z,1377567305.431 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:05.849Z,1377567305.849 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:06.243Z,1377567306.243 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:06.631Z,1377567306.631 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:07.055Z,1377567307.055 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:07.497Z,1377567307.497 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:07.630Z,1377567307.630 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:08.062Z,1377567308.062 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:08.483Z,1377567308.483 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:08.803Z,1377567308.803 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:09.195Z,1377567309.195 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:09.583Z,1377567309.583 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:10.011Z,1377567310.011 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:10.209Z,1377567310.210 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:10.364Z,1377567310.364 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:10.815Z,1377567310.815 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:11.200Z,1377567311.200 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:11.678Z,1377567311.678 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:12.081Z,1377567312.081 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:12.482Z,1377567312.482 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:12.762Z,1377567312.762 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:12.832Z,1377567312.832 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:13.380Z,1377567313.379 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:13.760Z,1377567313.760 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:14.108Z,1377567314.108 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:14.463Z,1377567314.463 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:14.904Z,1377567314.904 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:15.248Z,1377567315.248 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:15.329Z,1377567315.329 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:15.663Z,1377567315.662 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:16.051Z,1377567316.051 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:16.443Z,1377567316.443 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:16.874Z,1377567316.875 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:17.299Z,1377567317.299 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:17.653Z,1377567317.653 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:17.886Z,1377567317.885 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:18.159Z,1377567318.159 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:18.460Z,1377567318.460 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:18.856Z,1377567318.856 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:19.271Z,1377567319.271 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:19.695Z,1377567319.695 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:20.054Z,1377567320.054 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:20.433Z,1377567320.434 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:20.475Z,1377567320.475 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:20.864Z,1377567320.864 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:21.254Z,1377567321.254 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:21.671Z,1377567321.671 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:22.095Z,1377567322.095 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:22.453Z,1377567322.453 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:22.874Z,1377567322.874 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:23.005Z,1377567323.006 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:23.312Z,1377567323.312 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:23.653Z,1377567323.653 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:24.055Z,1377567324.055 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:24.495Z,1377567324.495 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:24.868Z,1377567324.868 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:25.275Z,1377567325.274 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:25.562Z,1377567325.562 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:25.667Z,1377567325.667 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:26.053Z,1377567326.053 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:26.471Z,1377567326.471 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:26.899Z,1377567326.899 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:27.254Z,1377567327.254 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:27.676Z,1377567327.676 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:28.061Z,1377567328.061 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:28.110Z,1377567328.110 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:28.518Z,1377567328.518 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:28.875Z,1377567328.875 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:29.299Z,1377567329.299 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:29.653Z,1377567329.653 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:30.059Z,1377567330.059 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:30.461Z,1377567330.461 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:30.721Z,1377567330.721 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:30.863Z,1377567330.863 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:31.275Z,1377567331.275 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:31.695Z,1377567331.695 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:32.053Z,1377567332.053 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:32.459Z,1377567332.459 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:32.866Z,1377567332.866 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:33.253Z,1377567333.253 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:33.273Z,1377567333.273 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:33.730Z,1377567333.730 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:34.095Z,1377567334.095 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:34.453Z,1377567334.453 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:34.855Z,1377567334.855 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:35.262Z,1377567335.262 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:35.652Z,1377567335.652 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:35.845Z,1377567335.845 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:36.075Z,1377567336.075 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:36.495Z,1377567336.495 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:36.856Z,1377567336.856 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:37.255Z,1377567337.255 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:37.661Z,1377567337.661 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:38.057Z,1377567338.057 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:38.405Z,1377567338.405 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:38.479Z,1377567338.479 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:38.979Z,1377567338.979 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:39.263Z,1377567339.263 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:39.671Z,1377567339.671 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:40.061Z,1377567340.061 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:40.451Z,1377567340.451 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:40.874Z,1377567340.875 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:40.978Z,1377567340.978 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:41.304Z,1377567341.304 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:41.652Z,1377567341.652 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:42.059Z,1377567342.059 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:42.461Z,1377567342.461 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:42.850Z,1377567342.850 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:43.275Z,1377567343.274 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:43.538Z,1377567343.538 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:43.708Z,1377567343.708 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:44.100Z,1377567344.100 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:44.470Z,1377567344.470 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:44.863Z,1377567344.863 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:45.250Z,1377567345.250 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:45.655Z,1377567345.655 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:46.062Z,1377567346.062 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:46.086Z,1377567346.086 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:46.480Z,1377567346.480 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:46.874Z,1377567346.874 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:47.262Z,1377567347.262 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:47.650Z,1377567347.650 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:48.067Z,1377567348.067 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:48.461Z,1377567348.461 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:48.662Z,1377567348.662 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:48.887Z,1377567348.887 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:49.315Z,1377567349.315 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:49.661Z,1377567349.661 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:50.050Z,1377567350.050 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:50.470Z,1377567350.470 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:50.865Z,1377567350.865 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:51.209Z,1377567351.210 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:51.307Z,1377567351.307 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:51.670Z,1377567351.671 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:52.061Z,1377567352.061 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:52.452Z,1377567352.452 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:52.874Z,1377567352.874 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:53.261Z,1377567353.261 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:53.683Z,1377567353.684 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:53.770Z,1377567353.770 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:54.075Z,1377567354.075 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:54.514Z,1377567354.514 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:54.857Z,1377567354.857 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:55.270Z,1377567355.270 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:55.661Z,1377567355.661 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:56.080Z,1377567356.080 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:56.322Z,1377567356.322 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:56.483Z,1377567356.483 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:56.864Z,1377567356.864 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:57.250Z,1377567357.250 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:57.655Z,1377567357.655 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:58.062Z,1377567358.062 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:58.484Z,1377567358.484 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:58.875Z,1377567358.875 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:58.878Z,1377567358.878 [DVL_micro](DEBUG): NQ1
2013-08-27T01:35:59.261Z,1377567359.261 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:35:59.704Z,1377567359.704 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:00.070Z,1377567360.070 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:00.461Z,1377567360.461 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:00.880Z,1377567360.880 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:01.271Z,1377567361.271 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:01.438Z,1377567361.438 [DVL_micro](DEBUG): NQ1
2013-08-27T01:36:01.672Z,1377567361.672 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:02.052Z,1377567362.052 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:02.470Z,1377567362.470 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:02.863Z,1377567362.862 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:03.280Z,1377567363.280 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:03.670Z,1377567363.670 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:03.998Z,1377567363.998 [DVL_micro](DEBUG): NQ1
2013-08-27T01:36:04.075Z,1377567364.075 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:04.500Z,1377567364.500 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:04.875Z,1377567364.875 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:05.262Z,1377567365.262 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:05.675Z,1377567365.675 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:06.070Z,1377567366.070 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:06.461Z,1377567366.461 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:06.573Z,1377567366.573 [DVL_micro](DEBUG): NQ1
2013-08-27T01:36:06.855Z,1377567366.855 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:07.270Z,1377567367.270 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:07.661Z,1377567367.661 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:08.079Z,1377567368.079 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:08.470Z,1377567368.470 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:08.864Z,1377567368.864 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:09.138Z,1377567369.138 [DVL_micro](DEBUG): NQ1
2013-08-27T01:36:09.271Z,1377567369.271 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:09.671Z,1377567369.671 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:10.112Z,1377567370.112 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:10.476Z,1377567370.476 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:10.867Z,1377567370.867 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:11.264Z,1377567371.264 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:11.652Z,1377567371.652 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:11.689Z,1377567371.690 [DVL_micro](DEBUG): NQ1
2013-08-27T01:36:12.071Z,1377567372.071 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:12.461Z,1377567372.461 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:12.884Z,1377567372.884 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:13.270Z,1377567373.270 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:13.660Z,1377567373.660 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:14.050Z,1377567374.050 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:14.254Z,1377567374.254 [DVL_micro](DEBUG): NQ1
2013-08-27T01:36:14.479Z,1377567374.479 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:14.908Z,1377567374.908 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:15.280Z,1377567375.280 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:15.670Z,1377567375.670 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:16.061Z,1377567376.061 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:16.451Z,1377567376.451 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:16.813Z,1377567376.813 [DVL_micro](DEBUG): NQ1
2013-08-27T01:36:16.887Z,1377567376.887 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:17.262Z,1377567377.262 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:17.675Z,1377567377.675 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:18.070Z,1377567378.070 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:18.461Z,1377567378.461 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:18.856Z,1377567378.856 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:18.962Z,1377567378.962 [CommandLine](IMPORTANT): got command quit
2013-08-27T01:36:19.271Z,1377567379.271 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:19.377Z,1377567379.378 [DVL_micro](DEBUG): NQ1
2013-08-27T01:36:19.661Z,1377567379.661 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:20.113Z,1377567380.113 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2013-08-27T01:36:20.114Z,1377567380.114 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-08-27T01:36:20.154Z,1377567380.154 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-08-27T01:36:20.157Z,1377567380.157 [CANON_LST_Track:L.](DEBUG): Tracking...
2013-08-27T01:36:20.159Z,1377567380.159 [CANON_LST_Track] Stopped
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track](INFO): Aggregate::uninitialize CANON_LST_Track
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:A.AltitudeEnvelope] Stopped
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:B.DepthEnvelope] Stopped
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:B.DepthEnvelope](DEBUG): Uninitialize.
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:C.OffshoreEnvelope] Stopped
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:I.Pitch] Stopped
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:J.Buoyancy] Stopped
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:L.] Stopped
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:L.](DEBUG): Uninitializing Tracking.
2013-08-27T01:36:20.160Z,1377567380.160 [CANON_LST_Track:waiting] Stopped
2013-08-27T01:36:20.161Z,1377567380.161 [CANON_LST_Track:waiting](INFO): Aggregate::uninitialize CANON_LST_Track:waiting
2013-08-27T01:36:20.161Z,1377567380.161 [CANON_LST_Track:waiting:B.Wait] Stopped
2013-08-27T01:36:20.161Z,1377567380.161 [CANON_LST_Track:waiting:B.Wait](DEBUG): Uninitialize Wait Component.
2013-08-27T01:36:20.161Z,1377567380.161 [CANON_LST_Track:ScienceOps] Stopped
2013-08-27T01:36:20.161Z,1377567380.161 [CANON_LST_Track:ScienceOps](INFO): Aggregate::uninitialize CANON_LST_Track:ScienceOps
2013-08-27T01:36:20.161Z,1377567380.161 [CANON_LST_Track:ScienceOps:Science] Stopped
2013-08-27T01:36:20.161Z,1377567380.161 [CANON_LST_Track:ScienceOps:Science](INFO): Aggregate::uninitialize CANON_LST_Track:ScienceOps:Science
2013-08-27T01:36:20.166Z,1377567380.165 [CANON_LST_Track:ScienceOps:Science:A] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:Science:B] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:Science:C] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:Science:D] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:Science:E] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:Science:F] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:Science:G] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:Science:H] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:Science:I] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:B.SetSpeed] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:B.SetSpeed](DEBUG): Uninitialize.
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:C.DepthEnvelope] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:C.DepthEnvelope](DEBUG): Uninitialize.
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:D.AltitudeEnvelope] Stopped
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:D.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2013-08-27T01:36:20.166Z,1377567380.166 [CANON_LST_Track:ScienceOps:E.YoYo] Stopped
2013-08-27T01:36:20.167Z,1377567380.167 [CANON_LST_Track:ScienceOps:E.YoYo](DEBUG): Uninitialize YoYoComponent.
2013-08-27T01:36:20.167Z,1377567380.167 [CANON_LST_Track:ScienceOps:G.Wait] Stopped
2013-08-27T01:36:20.167Z,1377567380.167 [CANON_LST_Track:ScienceOps:G.Wait](DEBUG): Uninitialize Wait Component.
2013-08-27T01:36:20.466Z,1377567380.466 [MissionManager](IMPORTANT): Started mission Default
2013-08-27T01:36:20.466Z,1377567380.466 [Default] Running Loop=1
2013-08-27T01:36:20.466Z,1377567380.466 [Default](INFO): Aggregate::initialize Default
2013-08-27T01:36:20.467Z,1377567380.467 [Default:D.SetSpeed] Running Loop=1
2013-08-27T01:36:20.467Z,1377567380.467 [Default:D.SetSpeed](DEBUG): Initialize.
2013-08-27T01:36:20.467Z,1377567380.467 [Default:E.GoToSurface] Running Loop=1
2013-08-27T01:36:20.467Z,1377567380.467 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-27T01:36:20.467Z,1377567380.467 [Default:Iridium] Running Loop=1
2013-08-27T01:36:20.467Z,1377567380.467 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-08-27T01:36:20.467Z,1377567380.467 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-08-27T01:36:20.467Z,1377567380.467 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-08-27T01:36:20.468Z,1377567380.467 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-08-27T01:36:20.468Z,1377567380.468 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-27T01:36:20.470Z,1377567380.470 [Default:Iridium:B.GoToSurface] Stopped
2013-08-27T01:36:20.470Z,1377567380.470 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-27T01:36:20.470Z,1377567380.470 [Default:Iridium:Read_Iridium] Running Loop=1
2013-08-27T01:36:20.470Z,1377567380.470 [Default:GPS] Running Loop=1
2013-08-27T01:36:20.471Z,1377567380.471 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-08-27T01:36:20.471Z,1377567380.471 [Default:GPS:A.SetSpeed] Running Loop=1
2013-08-27T01:36:20.471Z,1377567380.471 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-08-27T01:36:20.471Z,1377567380.471 [Default:GPS:B.GoToSurface] Running Loop=1
2013-08-27T01:36:20.471Z,1377567380.471 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-08-27T01:36:20.472Z,1377567380.472 [Default:GPS:B.GoToSurface] Stopped
2013-08-27T01:36:20.472Z,1377567380.472 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-27T01:36:20.472Z,1377567380.472 [Default:GPS:Read_GPS] Running Loop=1
2013-08-27T01:36:20.846Z,1377567380.846 [DAT](INFO): Powering down
2013-08-27T01:36:21.239Z,1377567381.239 [NAL9602](INFO): Powering up
2013-08-27T01:36:21.482Z,1377567381.482 [DVL_micro](INFO): uninitialize:Powering down
2013-08-27T01:36:21.887Z,1377567381.886 [DVL_micro](INFO): uninitialize:Powering down
2013-08-27T01:36:21.893Z,1377567381.893 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-08-27T01:36:22.170Z,1377567382.170 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2013-08-27T01:36:22.185Z,1377567382.185 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-08-27T01:36:22.260Z,1377567382.260 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-08-27T01:36:22.262Z,1377567382.261 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-08-27T01:36:22.507Z,1377567382.507 [controlThread](DEBUG): Uninitializing ControlThread
2013-08-27T01:36:22.508Z,1377567382.508 [AHRS_sp3003D](INFO): Powering down
2013-08-27T01:36:22.595Z,1377567382.595 [NAL9602](INFO): Powering down
2013-08-27T01:36:22.596Z,1377567382.596 [DAT](INFO): Powering down
2013-08-27T01:36:22.597Z,1377567382.597 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-08-27T01:36:22.598Z,1377567382.598 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2013-08-27T01:36:22.599Z,1377567382.599 [Default] Stopped
2013-08-27T01:36:22.599Z,1377567382.599 [Default](INFO): Aggregate::uninitialize Default
2013-08-27T01:36:22.599Z,1377567382.599 [Default:GPS] Stopped
2013-08-27T01:36:22.599Z,1377567382.599 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-08-27T01:36:22.599Z,1377567382.599 [Default:GPS:A.SetSpeed] Stopped
2013-08-27T01:36:22.599Z,1377567382.599 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-08-27T01:36:22.599Z,1377567382.599 [Default:GPS:Read_GPS] Stopped
2013-08-27T01:36:22.599Z,1377567382.599 [Default:Iridium] Stopped
2013-08-27T01:36:22.600Z,1377567382.599 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-08-27T01:36:22.600Z,1377567382.599 [Default:Iridium:A.SetSpeed] Stopped
2013-08-27T01:36:22.600Z,1377567382.600 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-08-27T01:36:22.600Z,1377567382.600 [Default:Iridium:Read_Iridium] Stopped
2013-08-27T01:36:22.600Z,1377567382.600 [Default:D.SetSpeed] Stopped
2013-08-27T01:36:22.600Z,1377567382.600 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-08-27T01:36:22.600Z,1377567382.600 [Default:E.GoToSurface] Stopped
2013-08-27T01:36:22.600Z,1377567382.600 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-08-27T01:36:22.604Z,1377567382.604 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-08-27T01:36:22.604Z,1377567382.604 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-08-27T01:36:22.604Z,1377567382.604 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-08-27T01:36:22.605Z,1377567382.605 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-08-27T01:36:22.605Z,1377567382.605 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-08-27T01:36:22.606Z,1377567382.606 [BuoyancyServo](INFO): Powering down
2013-08-27T01:36:22.622Z,1377567382.622 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-08-27T01:36:22.622Z,1377567382.622 [ElevatorServo](INFO): Powering down
2013-08-27T01:36:22.623Z,1377567382.623 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-08-27T01:36:22.623Z,1377567382.623 [MassServo](INFO): Powering down
2013-08-27T01:36:22.624Z,1377567382.624 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-08-27T01:36:22.624Z,1377567382.624 [RudderServo](INFO): Powering down
2013-08-27T01:36:22.625Z,1377567382.625 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-08-27T01:36:22.625Z,1377567382.625 [ThrusterServo](INFO): Powering down
2013-08-27T01:36:22.626Z,1377567382.626 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-08-27T01:36:22.626Z,1377567382.626 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-08-27T01:36:22.627Z,1377567382.627 [CBIT](DEBUG): Uninitialize CBIT Component.