2014-09-16T22:12:33.495Z,1410905553.495 [Supervisor](DEBUG): Initializing supervisor.
2014-09-16T22:12:33.497Z,1410905553.497 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-09-16T22:12:33.498Z,1410905553.498 [SyncHandler](INFO): Protected caller Thread ID is 1278
2014-09-16T22:12:33.498Z,1410905553.498 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-09-16T22:12:33.499Z,1410905553.499 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-09-16T22:12:33.500Z,1410905553.500 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1279
2014-09-16T22:12:33.503Z,1410905553.503 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-09-16T22:12:33.521Z,1410905553.521 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-09-16T22:12:33.523Z,1410905553.523 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-09-16T22:12:33.523Z,1410905553.523 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1280
2014-09-16T22:12:33.524Z,1410905553.524 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-09-16T22:12:33.525Z,1410905553.525 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-09-16T22:12:33.525Z,1410905553.525 [logger ThreadHandler](INFO): Protected caller Thread ID is 1281
2014-09-16T22:12:33.527Z,1410905553.527 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-09-16T22:12:33.528Z,1410905553.528 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-09-16T22:12:33.530Z,1410905553.530 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-09-16T22:12:33.974Z,1410905553.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-09-16T22:12:33.975Z,1410905553.975 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-09-16T22:12:34.228Z,1410905554.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-09-16T22:12:34.230Z,1410905554.230 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-09-16T22:12:34.349Z,1410905554.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-09-16T22:12:34.349Z,1410905554.349 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-09-16T22:12:34.573Z,1410905554.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-09-16T22:12:34.574Z,1410905554.574 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-09-16T22:12:34.671Z,1410905554.671 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-09-16T22:12:34.863Z,1410905554.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-09-16T22:12:34.863Z,1410905554.863 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-09-16T22:12:35.025Z,1410905555.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-09-16T22:12:35.026Z,1410905555.026 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-09-16T22:12:35.279Z,1410905555.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-09-16T22:12:35.280Z,1410905555.280 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-09-16T22:12:35.768Z,1410905555.768 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-09-16T22:12:35.768Z,1410905555.768 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-09-16T22:12:36.189Z,1410905556.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-09-16T22:12:36.189Z,1410905556.189 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-09-16T22:12:36.880Z,1410905556.880 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-09-16T22:12:36.880Z,1410905556.880 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-09-16T22:12:37.139Z,1410905557.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-09-16T22:12:37.140Z,1410905557.140 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-09-16T22:12:37.302Z,1410905557.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-09-16T22:12:37.303Z,1410905557.303 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-09-16T22:12:37.896Z,1410905557.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-09-16T22:12:37.896Z,1410905557.896 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-09-16T22:12:38.016Z,1410905558.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-09-16T22:12:38.018Z,1410905558.018 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2014-09-16T22:12:38.019Z,1410905558.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2014-09-16T22:12:38.393Z,1410905558.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2014-09-16T22:12:38.394Z,1410905558.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2014-09-16T22:12:38.605Z,1410905558.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2014-09-16T22:12:38.713Z,1410905558.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2014-09-16T22:12:38.817Z,1410905558.817 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2014-09-16T22:12:39.010Z,1410905559.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2014-09-16T22:12:39.180Z,1410905559.180 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2014-09-16T22:12:39.440Z,1410905559.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2014-09-16T22:12:39.555Z,1410905559.555 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2014-09-16T22:12:39.699Z,1410905559.699 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2014-09-16T22:12:39.832Z,1410905559.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2014-09-16T22:12:39.948Z,1410905559.948 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2014-09-16T22:12:40.213Z,1410905560.213 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/LOGIN/
2014-09-16T22:12:40.213Z,1410905560.213 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-09-16T22:12:40.215Z,1410905560.215 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-09-16T22:12:40.270Z,1410905560.270 [VerticalControl](DEBUG): Construct VerticalControl.
2014-09-16T22:12:40.395Z,1410905560.395 [VerticalControl] Loaded
2014-09-16T22:12:40.396Z,1410905560.396 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-09-16T22:12:40.396Z,1410905560.396 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-09-16T22:12:40.547Z,1410905560.547 [HorizontalControl] Loaded
2014-09-16T22:12:40.547Z,1410905560.547 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-09-16T22:12:40.548Z,1410905560.548 [SpeedControl](DEBUG): Construct SpeedControl.
2014-09-16T22:12:40.562Z,1410905560.562 [SpeedControl] Loaded
2014-09-16T22:12:40.563Z,1410905560.563 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-09-16T22:12:40.563Z,1410905560.563 [LoopControl](DEBUG): Construct LoopControl.
2014-09-16T22:12:40.564Z,1410905560.564 [LoopControl] Loaded
2014-09-16T22:12:40.564Z,1410905560.564 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-09-16T22:12:40.565Z,1410905560.565 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-09-16T22:12:40.565Z,1410905560.565 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-09-16T22:12:40.663Z,1410905560.663 [SBIT](DEBUG): Construct Startup Built In Test.
2014-09-16T22:12:40.676Z,1410905560.676 [SBIT] Loaded
2014-09-16T22:12:40.676Z,1410905560.676 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-09-16T22:12:40.677Z,1410905560.677 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-09-16T22:12:40.710Z,1410905560.710 [IBIT] Loaded
2014-09-16T22:12:40.710Z,1410905560.710 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-09-16T22:12:40.713Z,1410905560.713 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-09-16T22:12:40.946Z,1410905560.946 [CBIT] Loaded
2014-09-16T22:12:40.946Z,1410905560.946 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-09-16T22:12:40.946Z,1410905560.946 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-09-16T22:12:40.947Z,1410905560.947 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-09-16T22:12:40.975Z,1410905560.975 [DepthRateCalculator] Loaded
2014-09-16T22:12:40.976Z,1410905560.976 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-09-16T22:12:40.981Z,1410905560.981 [PitchRateCalculator] Loaded
2014-09-16T22:12:40.981Z,1410905560.981 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-09-16T22:12:40.993Z,1410905560.993 [SpeedCalculator] Loaded
2014-09-16T22:12:40.993Z,1410905560.993 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-09-16T22:12:41.011Z,1410905561.011 [TempGradientCalculator] Loaded
2014-09-16T22:12:41.011Z,1410905561.011 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-09-16T22:12:41.016Z,1410905561.016 [YawRateCalculator] Loaded
2014-09-16T22:12:41.017Z,1410905561.017 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-09-16T22:12:41.017Z,1410905561.017 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-09-16T22:12:41.018Z,1410905561.018 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-09-16T22:12:42.555Z,1410905562.555 [HFRCMReconstructedInterpolator] Loaded
2014-09-16T22:12:42.556Z,1410905562.556 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-09-16T22:12:42.556Z,1410905562.556 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-09-16T22:12:42.557Z,1410905562.557 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-09-16T22:12:42.830Z,1410905562.830 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-09-16T22:12:42.855Z,1410905562.855 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-09-16T22:12:43.001Z,1410905563.001 [DeadReckonUsingMultipleVelocitySourcesVector] Loaded
2014-09-16T22:12:43.002Z,1410905563.002 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySourcesVector" handled in the control thread.
2014-09-16T22:12:43.059Z,1410905563.059 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-09-16T22:12:43.059Z,1410905563.059 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-09-16T22:12:43.107Z,1410905563.107 [DeadReckonWithRespectToWater] Loaded
2014-09-16T22:12:43.108Z,1410905563.108 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-09-16T22:12:43.158Z,1410905563.158 [DeadReckonWithRespectToSeafloor] Loaded
2014-09-16T22:12:43.159Z,1410905563.159 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-09-16T22:12:43.225Z,1410905563.225 [DeadReckonUsingDVLWaterTrack] Loaded
2014-09-16T22:12:43.225Z,1410905563.225 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-09-16T22:12:43.284Z,1410905563.284 [NavChart] Loaded
2014-09-16T22:12:43.284Z,1410905563.284 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-09-16T22:12:43.293Z,1410905563.293 [UniversalFixResidualReporter] Loaded
2014-09-16T22:12:43.293Z,1410905563.293 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-09-16T22:12:43.294Z,1410905563.294 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-09-16T22:12:43.294Z,1410905563.294 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-09-16T22:12:43.309Z,1410905563.309 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-09-16T22:12:43.309Z,1410905563.309 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-09-16T22:12:43.521Z,1410905563.521 [Aanderaa_O2] Loaded
2014-09-16T22:12:43.521Z,1410905563.521 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-09-16T22:12:43.550Z,1410905563.550 [rhodamine] Loaded
2014-09-16T22:12:43.550Z,1410905563.550 [ComponentRegistry](DEBUG): SyncComponent "rhodamine" handled in the control thread.
2014-09-16T22:12:43.588Z,1410905563.588 [WetLabsBB2FL] Loaded
2014-09-16T22:12:43.588Z,1410905563.588 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-09-16T22:12:43.590Z,1410905563.590 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405DB4E0
2014-09-16T22:12:43.590Z,1410905563.590 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1364
2014-09-16T22:12:43.591Z,1410905563.591 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-09-16T22:12:43.591Z,1410905563.591 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-09-16T22:12:43.991Z,1410905563.991 [AHRS_sp3003D] Loaded
2014-09-16T22:12:43.991Z,1410905563.991 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-09-16T22:12:44.143Z,1410905564.143 [DataOverHttps] Loaded
2014-09-16T22:12:44.144Z,1410905564.144 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2014-09-16T22:12:44.159Z,1410905564.159 [Depth_Keller] Loaded
2014-09-16T22:12:44.160Z,1410905564.160 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-09-16T22:12:44.165Z,1410905564.165 [DropWeight] Loaded
2014-09-16T22:12:44.165Z,1410905564.165 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-09-16T22:12:44.302Z,1410905564.302 [DVL_micro] Loaded
2014-09-16T22:12:44.302Z,1410905564.302 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-09-16T22:12:44.409Z,1410905564.409 [NAL9602] Loaded
2014-09-16T22:12:44.409Z,1410905564.409 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-09-16T22:12:44.542Z,1410905564.542 [Onboard] Loaded
2014-09-16T22:12:44.542Z,1410905564.542 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-09-16T22:12:44.567Z,1410905564.567 [Radio_Surface] Loaded
2014-09-16T22:12:44.567Z,1410905564.567 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2014-09-16T22:12:44.570Z,1410905564.570 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4081B4E0
2014-09-16T22:12:44.571Z,1410905564.571 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1365
2014-09-16T22:12:47.124Z,1410905567.124 [BPC1] Loaded
2014-09-16T22:12:47.124Z,1410905567.124 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2014-09-16T22:12:47.125Z,1410905567.125 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-09-16T22:12:47.125Z,1410905567.125 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-09-16T22:12:47.230Z,1410905567.230 [BuoyancyServo] Loaded
2014-09-16T22:12:47.231Z,1410905567.231 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-09-16T22:12:47.289Z,1410905567.289 [ElevatorServo] Loaded
2014-09-16T22:12:47.293Z,1410905567.293 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-09-16T22:12:47.319Z,1410905567.319 [MassServo] Loaded
2014-09-16T22:12:47.320Z,1410905567.320 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-09-16T22:12:47.357Z,1410905567.357 [RudderServo] Loaded
2014-09-16T22:12:47.359Z,1410905567.359 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-09-16T22:12:47.373Z,1410905567.373 [ThrusterServo] Loaded
2014-09-16T22:12:47.373Z,1410905567.373 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-09-16T22:12:47.374Z,1410905567.374 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-09-16T22:12:47.374Z,1410905567.374 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-09-16T22:12:47.537Z,1410905567.537 [InternalSim] Loaded
2014-09-16T22:12:47.537Z,1410905567.537 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-09-16T22:12:47.538Z,1410905567.538 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-09-16T22:12:47.538Z,1410905567.538 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-09-16T22:12:47.565Z,1410905567.565 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-09-16T22:12:47.567Z,1410905567.567 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-09-16T22:12:47.568Z,1410905567.568 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-09-16T22:12:47.576Z,1410905567.576 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-09-16T22:12:47.577Z,1410905567.577 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408CC4E0
2014-09-16T22:12:47.578Z,1410905567.578 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1366
2014-09-16T22:12:47.584Z,1410905567.584 [Supervisor](INFO): Main Thread ID is 1155
2014-09-16T22:12:47.584Z,1410905567.584 [Supervisor](DEBUG): Running supervisor.
2014-09-16T22:12:47.585Z,1410905567.585 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1367
2014-09-16T22:12:47.587Z,1410905567.587 [controlThread ThreadHandler](INFO): Handler Thread ID is 1368
2014-09-16T22:12:47.588Z,1410905567.588 [controlThread](DEBUG): Initializing ControlThread
2014-09-16T22:12:47.589Z,1410905567.589 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-09-16T22:12:47.591Z,1410905567.591 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-09-16T22:12:47.591Z,1410905567.591 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-09-16T22:12:47.592Z,1410905567.592 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-09-16T22:12:47.592Z,1410905567.592 [SBIT](INFO): Initialize SBIT Component.
2014-09-16T22:12:47.593Z,1410905567.593 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11625
2014-09-16T22:12:47.593Z,1410905567.593 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-09-16T22:12:47.593Z,1410905567.593 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-09-16T22:12:47.594Z,1410905567.594 [IBIT](INFO): Initialize IBIT Component.
2014-09-16T22:12:47.595Z,1410905567.595 [CBIT](DEBUG): Initialize CBIT Component.
2014-09-16T22:12:47.595Z,1410905567.595 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-09-16T22:12:47.596Z,1410905567.596 [logger ThreadHandler](INFO): Handler Thread ID is 1369
2014-09-16T22:12:47.618Z,1410905567.618 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-16T22:12:47.618Z,1410905567.618 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-09-16T22:12:47.619Z,1410905567.619 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-09-16T22:12:47.619Z,1410905567.619 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-09-16T22:12:47.620Z,1410905567.620 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-09-16T22:12:47.621Z,1410905567.621 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-09-16T22:12:47.624Z,1410905567.624 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Initializing DeadReckonUsingMultipleVelocitySourcesVector component.
2014-09-16T22:12:47.625Z,1410905567.625 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-16T22:12:47.625Z,1410905567.625 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-09-16T22:12:47.626Z,1410905567.626 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-09-16T22:12:47.626Z,1410905567.626 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-09-16T22:12:47.626Z,1410905567.626 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-09-16T22:12:47.627Z,1410905567.627 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-16T22:12:47.647Z,1410905567.647 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1370
2014-09-16T22:12:47.649Z,1410905567.649 [WetLabsBB2FL](INFO): Powering down
2014-09-16T22:12:47.678Z,1410905567.678 [InternalSim](DEBUG):  InternalSim initializing...
2014-09-16T22:12:47.703Z,1410905567.703 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1371
2014-09-16T22:12:47.714Z,1410905567.714 [Radio_Surface](INFO): Powering up
2014-09-16T22:12:47.731Z,1410905567.731 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1372
2014-09-16T22:12:47.733Z,1410905567.733 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2014-09-16T22:12:47.737Z,1410905567.737 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-09-16T22:12:47.739Z,1410905567.739 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2014-09-16T22:12:47.740Z,1410905567.740 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2014-09-16T22:12:47.742Z,1410905567.742 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2014-09-16T22:12:47.744Z,1410905567.744 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2014-09-16T22:12:47.745Z,1410905567.745 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2014-09-16T22:12:47.747Z,1410905567.747 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2014-09-16T22:12:47.749Z,1410905567.749 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2014-09-16T22:12:47.752Z,1410905567.752 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2014-09-16T22:12:47.840Z,1410905567.840 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-09-16T22:12:47.866Z,1410905567.866 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-16T22:12:47.894Z,1410905567.894 [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>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2014-09-16T22:12:47.895Z,1410905567.895 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-09-16T22:12:47.939Z,1410905567.939 [NavChartDb](INFO): Setup scan of Resources/US5CA83M.000
2014-09-16T22:12:47.988Z,1410905567.988 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-09-16T22:12:47.994Z,1410905567.994 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-09-16T22:12:48.003Z,1410905568.003 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-16T22:12:48.028Z,1410905568.028 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-09-16T22:12:48.070Z,1410905568.070 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-09-16T22:12:48.116Z,1410905568.116 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-09-16T22:12:48.172Z,1410905568.172 [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="TimeMissionWasStarted"><Units:hour/><Value>0</Value></DefineArg>

    <DefineArg Name="ElapsedSinceMissionWasStarted"><Units:hour/><Value>0</Value></DefineArg>

    <Aggregate Id="StartClock">

        <Sequence/>

        <Assign><Arg Name="TimeMissionWasStarted"/><Universal:time/></Assign>

    </Aggregate>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <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>

<!-- TODO: Do we really want this When operating on platform_communications? or on something else like time_fix? -->

<!-- Run application at high speed -->

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

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
<!-- NOTE: Original Default.xml didn't have a timeout on the GPS read, so theoretically could have hung indefinitely there... -->
            <Universal:latitude_fix/>
        </ReadDatum>

<!-- Do data comms -->

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <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="UpdateAndReportMinutesSinceMissionStarted">

        <When>
            <Elapsed><Arg Name="ElapsedSinceMissionWasStarted"/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
        </When>

        <Assign><Arg Name="ElapsedSinceMissionWasStarted"/><Universal:time/><Sub><Arg Name="TimeMissionWasStarted"/></Sub></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceMissionWasStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2014-09-16T22:12:48.178Z,1410905568.178 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,Aanderaa_O2,rhodamine,AHRS_sp3003D,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySourcesVector,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-09-16T22:12:48.263Z,1410905568.263 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-09-16T22:12:48.354Z,1410905568.354 [DVL_micro](INFO): Initializing
2014-09-16T22:12:48.604Z,1410905568.604 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:48.622Z,1410905568.622 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T22:12:48.666Z,1410905568.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T22:12:48.714Z,1410905568.714 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T22:12:48.742Z,1410905568.742 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T22:12:48.753Z,1410905568.753 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T22:12:48.825Z,1410905568.825 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-09-16T22:12:48.876Z,1410905568.876 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-09-16T22:12:48.882Z,1410905568.882 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-09-16T22:12:48.904Z,1410905568.904 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-09-16T22:12:48.910Z,1410905568.910 [MassServo](DEBUG): Initializing EZServoServo.
2014-09-16T22:12:48.916Z,1410905568.916 [MassServo](DEBUG): Initializing MassServo.
2014-09-16T22:12:48.934Z,1410905568.934 [RudderServo](DEBUG): Initializing EZServoServo.
2014-09-16T22:12:48.948Z,1410905568.948 [RudderServo](DEBUG): Initializing RudderServo.
2014-09-16T22:12:48.966Z,1410905568.966 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-09-16T22:12:48.969Z,1410905568.969 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-09-16T22:12:49.274Z,1410905569.274 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:49.274Z,1410905569.274 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T22:12:49.275Z,1410905569.275 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T22:12:49.294Z,1410905569.294 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T22:12:49.295Z,1410905569.295 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T22:12:49.305Z,1410905569.305 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-16T22:12:49.801Z,1410905569.801 [NAL9602](INFO): Powering up NAL9602
2014-09-16T22:12:49.827Z,1410905569.827 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:50.631Z,1410905570.631 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:51.063Z,1410905571.063 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:51.510Z,1410905571.510 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:51.825Z,1410905571.825 [Aanderaa_O2](INFO): Powering down
2014-09-16T22:12:52.280Z,1410905572.280 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:52.726Z,1410905572.726 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:53.385Z,1410905573.385 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:54.122Z,1410905574.122 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:54.986Z,1410905574.986 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:55.574Z,1410905575.574 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:56.076Z,1410905576.076 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:56.581Z,1410905576.581 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:57.001Z,1410905577.001 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:57.413Z,1410905577.413 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:57.826Z,1410905577.826 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:58.252Z,1410905578.252 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:58.733Z,1410905578.733 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:59.157Z,1410905579.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:12:59.582Z,1410905579.582 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:00.062Z,1410905580.062 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:00.601Z,1410905580.601 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:01.094Z,1410905581.094 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:01.509Z,1410905581.509 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:01.926Z,1410905581.926 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:02.382Z,1410905582.382 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:02.813Z,1410905582.813 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:03.344Z,1410905583.344 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:03.368Z,1410905583.368 [SBIT](IMPORTANT): Beginning Startup BIT
2014-09-16T22:13:03.371Z,1410905583.371 [CBIT](IMPORTANT): Beginning GF scan
2014-09-16T22:13:03.771Z,1410905583.771 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:04.228Z,1410905584.228 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:04.667Z,1410905584.667 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:05.132Z,1410905585.132 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:05.723Z,1410905585.723 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:06.576Z,1410905586.576 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:07.103Z,1410905587.103 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:07.729Z,1410905587.729 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:08.252Z,1410905588.252 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:08.822Z,1410905588.822 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:09.375Z,1410905589.375 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:09.923Z,1410905589.923 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:10.339Z,1410905590.339 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:10.849Z,1410905590.849 [NAL9602](ERROR): NAL9602 initialization error.
2014-09-16T22:13:10.849Z,1410905590.849 [NAL9602] Communications Fault, FailCount= 1
2014-09-16T22:13:10.849Z,1410905590.849 [NAL9602](ERROR): Communications Fault
2014-09-16T22:13:10.887Z,1410905590.887 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:10.990Z,1410905590.990 [CBIT](ERROR): Communications Fault in component: NAL9602
2014-09-16T22:13:11.396Z,1410905591.396 [NAL9602](INFO): Powering down
2014-09-16T22:13:11.453Z,1410905591.453 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:11.890Z,1410905591.890 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:12.333Z,1410905592.333 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:12.473Z,1410905592.473 [CBIT](INFO): Clearing failed state for component NAL9602
2014-09-16T22:13:12.473Z,1410905592.473 [NAL9602] No Fault, FailCount= 1
2014-09-16T22:13:12.919Z,1410905592.919 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:13.340Z,1410905593.340 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:13.740Z,1410905593.740 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:14.153Z,1410905594.153 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:14.569Z,1410905594.569 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:14.951Z,1410905594.951 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:15.389Z,1410905595.389 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:15.869Z,1410905595.869 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:16.325Z,1410905596.325 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:16.735Z,1410905596.735 [NAL9602](INFO): Powering up NAL9602
2014-09-16T22:13:16.787Z,1410905596.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:17.278Z,1410905597.278 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:17.833Z,1410905597.833 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:18.286Z,1410905598.286 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:18.686Z,1410905598.686 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:19.130Z,1410905599.130 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:20.131Z,1410905600.131 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:20.632Z,1410905600.632 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:21.149Z,1410905601.149 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:21.696Z,1410905601.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:22.186Z,1410905602.186 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:22.679Z,1410905602.679 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:23.158Z,1410905603.158 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:23.739Z,1410905603.739 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:24.262Z,1410905604.262 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:24.891Z,1410905604.891 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:25.538Z,1410905605.538 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:26.114Z,1410905606.114 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:26.596Z,1410905606.596 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:27.066Z,1410905607.066 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:27.563Z,1410905607.563 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:28.063Z,1410905608.063 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:28.546Z,1410905608.546 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:28.992Z,1410905608.992 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:29.418Z,1410905609.418 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:29.889Z,1410905609.889 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:30.373Z,1410905610.373 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:30.977Z,1410905610.977 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:31.134Z,1410905611.134 [CBIT](IMPORTANT): No ground fault detected
2014-09-16T22:13:31.520Z,1410905611.520 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:31.997Z,1410905611.997 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:32.386Z,1410905612.386 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:32.901Z,1410905612.901 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:33.368Z,1410905613.368 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:33.763Z,1410905613.763 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:34.230Z,1410905614.230 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:34.639Z,1410905614.639 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:35.042Z,1410905615.042 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:35.445Z,1410905615.445 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:35.866Z,1410905615.866 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:36.280Z,1410905616.280 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:36.681Z,1410905616.681 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:37.089Z,1410905617.089 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:37.529Z,1410905617.529 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:37.949Z,1410905617.949 [NAL9602](ERROR): NAL9602 initialization error.
2014-09-16T22:13:37.949Z,1410905617.949 [NAL9602] Communications Fault, FailCount= 2
2014-09-16T22:13:37.949Z,1410905617.949 [NAL9602](ERROR): Communications Fault
2014-09-16T22:13:37.981Z,1410905617.981 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:37.998Z,1410905617.998 [CBIT](ERROR): Communications Fault in component: NAL9602
2014-09-16T22:13:38.396Z,1410905618.396 [NAL9602](INFO): Powering down
2014-09-16T22:13:38.407Z,1410905618.407 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:38.864Z,1410905618.864 [NAL9602](FAULT): LCB fault:  Invalid Command.
2014-09-16T22:13:38.865Z,1410905618.865 [NAL9602] Hardware Fault, FailCount= 2
2014-09-16T22:13:38.865Z,1410905618.865 [NAL9602](ERROR): Hardware Fault
2014-09-16T22:13:38.934Z,1410905618.934 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:39.386Z,1410905619.386 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:39.402Z,1410905619.402 [CBIT](INFO): Clearing failed state for component NAL9602
2014-09-16T22:13:39.402Z,1410905619.402 [NAL9602] No Fault, FailCount= 2
2014-09-16T22:13:39.789Z,1410905619.789 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:40.166Z,1410905620.166 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:40.608Z,1410905620.608 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:41.019Z,1410905621.019 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:41.422Z,1410905621.422 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:41.847Z,1410905621.847 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:42.260Z,1410905622.260 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:42.678Z,1410905622.678 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:43.096Z,1410905623.096 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:43.488Z,1410905623.488 [NAL9602](INFO): Powering up NAL9602
2014-09-16T22:13:43.499Z,1410905623.499 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:43.960Z,1410905623.960 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:44.382Z,1410905624.382 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:44.819Z,1410905624.819 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:45.379Z,1410905625.379 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:45.804Z,1410905625.804 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:46.408Z,1410905626.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:47.416Z,1410905627.416 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:47.899Z,1410905627.899 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:48.488Z,1410905628.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:49.030Z,1410905629.030 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:49.737Z,1410905629.737 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:50.307Z,1410905630.307 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:50.794Z,1410905630.794 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:50.884Z,1410905630.884 [CommandLine](IMPORTANT): got command quit
2014-09-16T22:13:51.270Z,1410905631.270 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:51.742Z,1410905631.742 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:51.889Z,1410905631.889 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T22:13:51.889Z,1410905631.889 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:51.998Z,1410905631.998 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-09-16T22:13:51.998Z,1410905631.998 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:51.999Z,1410905631.999 [CommandLine](INFO): Join timeout helper Thread ID is 1388
2014-09-16T22:13:52.002Z,1410905632.002 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-09-16T22:13:52.002Z,1410905632.002 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:52.003Z,1410905632.003 [NavChartDb](INFO): Join timeout helper Thread ID is 1389
2014-09-16T22:13:52.043Z,1410905632.043 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T22:13:52.043Z,1410905632.043 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:52.046Z,1410905632.046 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2014-09-16T22:13:52.046Z,1410905632.046 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:52.047Z,1410905632.047 [Radio_Surface](INFO): Join timeout helper Thread ID is 1390
2014-09-16T22:13:52.218Z,1410905632.218 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:52.400Z,1410905632.400 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T22:13:52.401Z,1410905632.401 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:52.419Z,1410905632.419 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-09-16T22:13:52.419Z,1410905632.419 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:52.419Z,1410905632.419 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1391
2014-09-16T22:13:52.579Z,1410905632.579 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T22:13:52.580Z,1410905632.580 [WetLabsBB2FL](INFO): Powering down
2014-09-16T22:13:52.580Z,1410905632.580 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:52.583Z,1410905632.583 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-09-16T22:13:52.583Z,1410905632.583 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:52.584Z,1410905632.584 [logger](INFO): Join timeout helper Thread ID is 1392
2014-09-16T22:13:52.599Z,1410905632.599 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T22:13:52.599Z,1410905632.599 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:52.604Z,1410905632.604 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-09-16T22:13:52.604Z,1410905632.604 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:52.604Z,1410905632.604 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-09-16T22:13:52.604Z,1410905632.604 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:52.605Z,1410905632.605 [controlThread](INFO): Join timeout helper Thread ID is 1393
2014-09-16T22:13:52.641Z,1410905632.641 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-16T22:13:52.782Z,1410905632.782 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-16T22:13:52.782Z,1410905632.782 [controlThread](DEBUG): Uninitializing ControlThread
2014-09-16T22:13:52.783Z,1410905632.783 [Aanderaa_O2](INFO): Powering down
2014-09-16T22:13:52.784Z,1410905632.784 [AHRS_sp3003D](INFO): Powering down
2014-09-16T22:13:52.873Z,1410905632.873 [DVL_micro](INFO): uninitialize:Powering down
2014-09-16T22:13:52.874Z,1410905632.874 [NAL9602](INFO): Powering down
2014-09-16T22:13:52.876Z,1410905632.876 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-16T22:13:52.878Z,1410905632.878 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-09-16T22:13:52.898Z,1410905632.898 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-09-16T22:13:52.898Z,1410905632.898 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-09-16T22:13:52.899Z,1410905632.899 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-09-16T22:13:52.899Z,1410905632.899 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-09-16T22:13:52.912Z,1410905632.912 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-09-16T22:13:52.912Z,1410905632.912 [BuoyancyServo](INFO): Powering down
2014-09-16T22:13:52.924Z,1410905632.924 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-09-16T22:13:52.924Z,1410905632.924 [ElevatorServo](INFO): Powering down
2014-09-16T22:13:52.925Z,1410905632.925 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-09-16T22:13:52.925Z,1410905632.925 [MassServo](INFO): Powering down
2014-09-16T22:13:52.926Z,1410905632.926 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-09-16T22:13:52.926Z,1410905632.926 [RudderServo](INFO): Powering down
2014-09-16T22:13:52.927Z,1410905632.927 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-09-16T22:13:52.927Z,1410905632.927 [ThrusterServo](INFO): Powering down
2014-09-16T22:13:52.949Z,1410905632.949 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-09-16T22:13:52.949Z,1410905632.949 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-09-16T22:13:52.949Z,1410905632.949 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-09-16T22:13:52.983Z,1410905632.983 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:53.035Z,1410905633.035 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:53.098Z,1410905633.098 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:53.146Z,1410905633.146 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-16T22:13:53.185Z,1410905633.185 [logger ThreadHandler](INFO): Thread cancelled.