2014-10-27T16:32:12.137Z,1414427532.137 [Supervisor](DEBUG): Initializing supervisor.
2014-10-27T16:32:12.139Z,1414427532.139 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-10-27T16:32:12.140Z,1414427532.140 [SyncHandler](INFO): Protected caller Thread ID is 1339
2014-10-27T16:32:12.140Z,1414427532.140 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-10-27T16:32:12.141Z,1414427532.141 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-10-27T16:32:12.142Z,1414427532.142 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1340
2014-10-27T16:32:12.144Z,1414427532.144 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-10-27T16:32:12.156Z,1414427532.156 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-10-27T16:32:12.157Z,1414427532.157 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-10-27T16:32:12.158Z,1414427532.158 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1341
2014-10-27T16:32:12.158Z,1414427532.158 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-10-27T16:32:12.159Z,1414427532.159 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-10-27T16:32:12.160Z,1414427532.160 [logger ThreadHandler](INFO): Protected caller Thread ID is 1342
2014-10-27T16:32:12.162Z,1414427532.162 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-10-27T16:32:12.162Z,1414427532.162 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-10-27T16:32:12.164Z,1414427532.164 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-10-27T16:32:12.649Z,1414427532.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-10-27T16:32:12.649Z,1414427532.649 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-10-27T16:32:12.804Z,1414427532.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-10-27T16:32:12.805Z,1414427532.805 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-10-27T16:32:12.912Z,1414427532.912 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-10-27T16:32:12.913Z,1414427532.913 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-10-27T16:32:13.033Z,1414427533.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-10-27T16:32:13.033Z,1414427533.033 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-10-27T16:32:13.118Z,1414427533.118 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-10-27T16:32:13.257Z,1414427533.257 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-10-27T16:32:13.258Z,1414427533.258 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-10-27T16:32:13.343Z,1414427533.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-10-27T16:32:13.344Z,1414427533.344 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-10-27T16:32:13.546Z,1414427533.546 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-10-27T16:32:13.547Z,1414427533.547 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-10-27T16:32:13.898Z,1414427533.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-10-27T16:32:13.899Z,1414427533.899 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-10-27T16:32:14.195Z,1414427534.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-10-27T16:32:14.196Z,1414427534.196 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-10-27T16:32:14.703Z,1414427534.703 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-10-27T16:32:14.704Z,1414427534.704 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-10-27T16:32:14.890Z,1414427534.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-10-27T16:32:14.891Z,1414427534.891 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-10-27T16:32:14.994Z,1414427534.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-10-27T16:32:14.994Z,1414427534.994 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-10-27T16:32:15.443Z,1414427535.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-10-27T16:32:15.443Z,1414427535.443 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-10-27T16:32:15.553Z,1414427535.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-10-27T16:32:15.555Z,1414427535.555 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2014-10-27T16:32:15.556Z,1414427535.556 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2014-10-27T16:32:15.795Z,1414427535.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2014-10-27T16:32:15.795Z,1414427535.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2014-10-27T16:32:15.922Z,1414427535.922 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2014-10-27T16:32:16.017Z,1414427536.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2014-10-27T16:32:16.113Z,1414427536.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2014-10-27T16:32:16.208Z,1414427536.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2014-10-27T16:32:16.346Z,1414427536.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2014-10-27T16:32:16.514Z,1414427536.514 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2014-10-27T16:32:16.622Z,1414427536.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2014-10-27T16:32:16.706Z,1414427536.706 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2014-10-27T16:32:16.805Z,1414427536.805 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2014-10-27T16:32:16.900Z,1414427536.900 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2014-10-27T16:32:17.075Z,1414427537.075 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2014-10-27T16:32:17.076Z,1414427537.076 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-10-27T16:32:17.089Z,1414427537.089 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-10-27T16:32:17.142Z,1414427537.142 [VerticalControl](DEBUG): Construct VerticalControl.
2014-10-27T16:32:17.262Z,1414427537.262 [VerticalControl] Loaded
2014-10-27T16:32:17.262Z,1414427537.262 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-10-27T16:32:17.263Z,1414427537.263 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-10-27T16:32:17.335Z,1414427537.335 [HorizontalControl] Loaded
2014-10-27T16:32:17.336Z,1414427537.336 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-10-27T16:32:17.336Z,1414427537.336 [SpeedControl](DEBUG): Construct SpeedControl.
2014-10-27T16:32:17.342Z,1414427537.342 [SpeedControl] Loaded
2014-10-27T16:32:17.342Z,1414427537.342 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-10-27T16:32:17.343Z,1414427537.343 [LoopControl](DEBUG): Construct LoopControl.
2014-10-27T16:32:17.344Z,1414427537.344 [LoopControl] Loaded
2014-10-27T16:32:17.344Z,1414427537.344 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-10-27T16:32:17.344Z,1414427537.344 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-10-27T16:32:17.345Z,1414427537.345 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-10-27T16:32:17.455Z,1414427537.455 [SBIT](DEBUG): Construct Startup Built In Test.
2014-10-27T16:32:17.467Z,1414427537.467 [SBIT] Loaded
2014-10-27T16:32:17.467Z,1414427537.467 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-10-27T16:32:17.468Z,1414427537.468 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-10-27T16:32:17.497Z,1414427537.497 [IBIT] Loaded
2014-10-27T16:32:17.497Z,1414427537.497 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-10-27T16:32:17.500Z,1414427537.500 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-10-27T16:32:17.621Z,1414427537.621 [CBIT] Loaded
2014-10-27T16:32:17.621Z,1414427537.621 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-10-27T16:32:17.622Z,1414427537.622 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-10-27T16:32:17.623Z,1414427537.623 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-10-27T16:32:17.641Z,1414427537.641 [DepthRateCalculator] Loaded
2014-10-27T16:32:17.641Z,1414427537.641 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-10-27T16:32:17.647Z,1414427537.647 [PitchRateCalculator] Loaded
2014-10-27T16:32:17.647Z,1414427537.647 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-10-27T16:32:17.658Z,1414427537.658 [SpeedCalculator] Loaded
2014-10-27T16:32:17.659Z,1414427537.659 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-10-27T16:32:17.676Z,1414427537.676 [TempGradientCalculator] Loaded
2014-10-27T16:32:17.676Z,1414427537.676 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-10-27T16:32:17.681Z,1414427537.681 [YawRateCalculator] Loaded
2014-10-27T16:32:17.682Z,1414427537.682 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-10-27T16:32:17.682Z,1414427537.682 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-10-27T16:32:17.683Z,1414427537.683 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-10-27T16:32:21.202Z,1414427541.202 [HFRCMReconstructedInterpolator] Loaded
2014-10-27T16:32:21.203Z,1414427541.203 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-10-27T16:32:21.203Z,1414427541.203 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-10-27T16:32:21.204Z,1414427541.204 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-10-27T16:32:21.442Z,1414427541.442 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-10-27T16:32:21.443Z,1414427541.443 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-10-27T16:32:21.522Z,1414427541.522 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-10-27T16:32:21.522Z,1414427541.522 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-10-27T16:32:21.568Z,1414427541.568 [DeadReckonUsingSpeedCalculator] Loaded
2014-10-27T16:32:21.568Z,1414427541.569 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2014-10-27T16:32:21.614Z,1414427541.614 [DeadReckonWithRespectToWater] Loaded
2014-10-27T16:32:21.614Z,1414427541.614 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-10-27T16:32:21.661Z,1414427541.661 [DeadReckonWithRespectToSeafloor] Loaded
2014-10-27T16:32:21.661Z,1414427541.661 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-10-27T16:32:21.712Z,1414427541.712 [DeadReckonUsingDVLWaterTrack] Loaded
2014-10-27T16:32:21.712Z,1414427541.712 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-10-27T16:32:21.730Z,1414427541.730 [NavChart] Loaded
2014-10-27T16:32:21.730Z,1414427541.730 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-10-27T16:32:21.734Z,1414427541.734 [UniversalFixResidualReporter] Loaded
2014-10-27T16:32:21.734Z,1414427541.734 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-10-27T16:32:21.735Z,1414427541.735 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-10-27T16:32:21.735Z,1414427541.735 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-10-27T16:32:21.741Z,1414427541.741 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-10-27T16:32:21.742Z,1414427541.742 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-10-27T16:32:21.915Z,1414427541.915 [Aanderaa_O2] Loaded
2014-10-27T16:32:21.916Z,1414427541.916 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-10-27T16:32:21.960Z,1414427541.960 [CTD_NeilBrown] Loaded
2014-10-27T16:32:21.961Z,1414427541.961 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-10-27T16:32:21.962Z,1414427541.962 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405DD4E0
2014-10-27T16:32:21.962Z,1414427541.962 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1424
2014-10-27T16:32:21.980Z,1414427541.980 [rhodamine] Loaded
2014-10-27T16:32:21.980Z,1414427541.980 [ComponentRegistry](DEBUG): SyncComponent "rhodamine" handled in the control thread.
2014-10-27T16:32:22.016Z,1414427542.016 [WetLabsBB2FL] Loaded
2014-10-27T16:32:22.017Z,1414427542.017 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-10-27T16:32:22.018Z,1414427542.018 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4060D4E0
2014-10-27T16:32:22.018Z,1414427542.018 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1425
2014-10-27T16:32:22.019Z,1414427542.019 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-10-27T16:32:22.019Z,1414427542.019 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-10-27T16:32:22.335Z,1414427542.335 [AHRS_sp3003D] Loaded
2014-10-27T16:32:22.336Z,1414427542.336 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-10-27T16:32:22.382Z,1414427542.382 [AcousticModem_Benthos_ATM900] Loaded
2014-10-27T16:32:22.382Z,1414427542.382 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2014-10-27T16:32:22.397Z,1414427542.397 [Depth_Keller] Loaded
2014-10-27T16:32:22.397Z,1414427542.397 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-10-27T16:32:22.402Z,1414427542.402 [DropWeight] Loaded
2014-10-27T16:32:22.402Z,1414427542.402 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-10-27T16:32:22.546Z,1414427542.546 [DVL_micro] Loaded
2014-10-27T16:32:22.546Z,1414427542.546 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-10-27T16:32:22.643Z,1414427542.643 [NAL9602] Loaded
2014-10-27T16:32:22.644Z,1414427542.644 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-10-27T16:32:22.686Z,1414427542.686 [Onboard] Loaded
2014-10-27T16:32:22.686Z,1414427542.686 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-10-27T16:32:24.616Z,1414427544.616 [BPC1] Loaded
2014-10-27T16:32:24.617Z,1414427544.617 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2014-10-27T16:32:24.617Z,1414427544.617 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-10-27T16:32:24.618Z,1414427544.618 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-10-27T16:32:24.722Z,1414427544.722 [BuoyancyServo] Loaded
2014-10-27T16:32:24.722Z,1414427544.722 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-10-27T16:32:24.734Z,1414427544.734 [ElevatorServo] Loaded
2014-10-27T16:32:24.735Z,1414427544.735 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-10-27T16:32:24.746Z,1414427544.746 [MassServo] Loaded
2014-10-27T16:32:24.747Z,1414427544.747 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-10-27T16:32:24.759Z,1414427544.759 [RudderServo] Loaded
2014-10-27T16:32:24.759Z,1414427544.759 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-10-27T16:32:24.770Z,1414427544.770 [ThrusterServo] Loaded
2014-10-27T16:32:24.771Z,1414427544.771 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-10-27T16:32:24.771Z,1414427544.771 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-10-27T16:32:24.772Z,1414427544.772 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-10-27T16:32:24.932Z,1414427544.932 [InternalSim] Loaded
2014-10-27T16:32:24.932Z,1414427544.932 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-10-27T16:32:24.933Z,1414427544.933 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-10-27T16:32:24.933Z,1414427544.933 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-10-27T16:32:24.959Z,1414427544.959 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-10-27T16:32:24.961Z,1414427544.961 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-10-27T16:32:24.962Z,1414427544.962 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-10-27T16:32:24.968Z,1414427544.968 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-10-27T16:32:24.969Z,1414427544.969 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408DA4E0
2014-10-27T16:32:24.970Z,1414427544.970 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1426
2014-10-27T16:32:24.975Z,1414427544.975 [Supervisor](INFO): Main Thread ID is 1200
2014-10-27T16:32:24.975Z,1414427544.975 [Supervisor](DEBUG): Running supervisor.
2014-10-27T16:32:24.975Z,1414427544.975 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1427
2014-10-27T16:32:24.980Z,1414427544.980 [controlThread ThreadHandler](INFO): Handler Thread ID is 1428
2014-10-27T16:32:24.980Z,1414427544.980 [controlThread](DEBUG): Initializing ControlThread
2014-10-27T16:32:24.981Z,1414427544.981 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-10-27T16:32:24.983Z,1414427544.983 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-10-27T16:32:24.983Z,1414427544.983 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-10-27T16:32:24.984Z,1414427544.984 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-10-27T16:32:24.984Z,1414427544.984 [SBIT](INFO): Initialize SBIT Component.
2014-10-27T16:32:24.984Z,1414427544.984 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11797
2014-10-27T16:32:24.985Z,1414427544.985 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-10-27T16:32:24.985Z,1414427544.985 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-10-27T16:32:24.986Z,1414427544.986 [IBIT](INFO): Initialize IBIT Component.
2014-10-27T16:32:24.987Z,1414427544.987 [CBIT](DEBUG): Initialize CBIT Component.
2014-10-27T16:32:24.987Z,1414427544.987 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-10-27T16:32:24.988Z,1414427544.988 [logger ThreadHandler](INFO): Handler Thread ID is 1429
2014-10-27T16:32:25.009Z,1414427545.009 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1430
2014-10-27T16:32:25.010Z,1414427545.010 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-10-27T16:32:25.013Z,1414427545.013 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-10-27T16:32:25.013Z,1414427545.013 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-10-27T16:32:25.013Z,1414427545.013 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-10-27T16:32:25.014Z,1414427545.014 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-10-27T16:32:25.014Z,1414427545.014 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-10-27T16:32:25.015Z,1414427545.015 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-10-27T16:32:25.017Z,1414427545.017 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-10-27T16:32:25.017Z,1414427545.017 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2014-10-27T16:32:25.018Z,1414427545.018 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-10-27T16:32:25.018Z,1414427545.018 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-10-27T16:32:25.018Z,1414427545.018 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-10-27T16:32:25.019Z,1414427545.019 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-10-27T16:32:25.019Z,1414427545.019 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2014-10-27T16:32:25.029Z,1414427545.029 [InternalSim](DEBUG):  InternalSim initializing...
2014-10-27T16:32:25.038Z,1414427545.038 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-10-27T16:32:25.045Z,1414427545.045 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1432
2014-10-27T16:32:25.047Z,1414427545.047 [WetLabsBB2FL](INFO): Powering down
2014-10-27T16:32:25.073Z,1414427545.073 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1433
2014-10-27T16:32:25.076Z,1414427545.076 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-10-27T16:32:25.077Z,1414427545.077 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-10-27T16:32:25.077Z,1414427545.077 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-10-27T16:32:25.078Z,1414427545.078 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-10-27T16:32:25.078Z,1414427545.078 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-10-27T16:32:25.078Z,1414427545.078 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-10-27T16:32:25.078Z,1414427545.078 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-10-27T16:32:25.079Z,1414427545.079 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-10-27T16:32:25.079Z,1414427545.079 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-10-27T16:32:25.195Z,1414427545.195 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-10-27T16:32:25.229Z,1414427545.229 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-10-27T16:32:25.254Z,1414427545.254 [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-10-27T16:32:25.255Z,1414427545.255 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-10-27T16:32:25.358Z,1414427545.358 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-10-27T16:32:25.394Z,1414427545.394 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-10-27T16:32:25.410Z,1414427545.410 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-10-27T16:32:25.447Z,1414427545.447 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-10-27T16:32:25.498Z,1414427545.498 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-10-27T16:32:25.533Z,1414427545.533 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-10-27T16:32:25.555Z,1414427545.555 [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-10-27T16:32:25.561Z,1414427545.561 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,Aanderaa_O2,rhodamine,AHRS_sp3003D,AcousticModem_Benthos_ATM900,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-10-27T16:32:25.674Z,1414427545.674 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-10-27T16:32:25.696Z,1414427545.696 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-10-27T16:32:25.697Z,1414427545.697 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-10-27T16:32:25.874Z,1414427545.874 [DVL_micro](INFO): Initializing
2014-10-27T16:32:26.162Z,1414427546.162 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:26.174Z,1414427546.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:26.214Z,1414427546.214 [DeadReckonUsingSpeedCalculator](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:26.221Z,1414427546.221 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:26.244Z,1414427546.244 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:26.267Z,1414427546.267 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:26.384Z,1414427546.384 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-10-27T16:32:26.391Z,1414427546.391 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-10-27T16:32:26.421Z,1414427546.421 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-10-27T16:32:26.427Z,1414427546.427 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-10-27T16:32:26.441Z,1414427546.441 [MassServo](DEBUG): Initializing EZServoServo.
2014-10-27T16:32:26.447Z,1414427546.447 [MassServo](DEBUG): Initializing MassServo.
2014-10-27T16:32:26.465Z,1414427546.465 [RudderServo](DEBUG): Initializing EZServoServo.
2014-10-27T16:32:26.471Z,1414427546.471 [RudderServo](DEBUG): Initializing RudderServo.
2014-10-27T16:32:26.481Z,1414427546.481 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-10-27T16:32:26.487Z,1414427546.487 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-10-27T16:32:26.871Z,1414427546.871 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:26.880Z,1414427546.880 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:26.881Z,1414427546.881 [DeadReckonUsingSpeedCalculator](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:26.883Z,1414427546.883 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:26.892Z,1414427546.892 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:26.893Z,1414427546.893 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:27.248Z,1414427547.248 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2014-10-27T16:32:27.248Z,1414427547.248 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2014-10-27T16:32:27.248Z,1414427547.248 [BuoyancyServo] Communications Fault, FailCount= 1
2014-10-27T16:32:27.248Z,1414427547.248 [BuoyancyServo](ERROR): Communications Fault
2014-10-27T16:32:27.474Z,1414427547.474 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2014-10-27T16:32:27.616Z,1414427547.616 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:27.616Z,1414427547.616 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:27.617Z,1414427547.617 [DeadReckonUsingSpeedCalculator](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:27.618Z,1414427547.618 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:27.619Z,1414427547.619 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:27.620Z,1414427547.620 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T16:32:27.637Z,1414427547.637 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-10-27T16:32:27.638Z,1414427547.638 [BuoyancyServo](INFO): Powering down
2014-10-27T16:32:28.212Z,1414427548.212 [NAL9602](INFO): Powering up NAL9602
2014-10-27T16:32:28.229Z,1414427548.229 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:28.625Z,1414427548.625 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:28.811Z,1414427548.811 [Aanderaa_O2](INFO): Powering down
2014-10-27T16:32:29.268Z,1414427549.268 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:29.797Z,1414427549.797 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:29.823Z,1414427549.823 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2014-10-27T16:32:29.823Z,1414427549.823 [BuoyancyServo] No Fault, FailCount= 1
2014-10-27T16:32:30.192Z,1414427550.192 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:30.210Z,1414427550.210 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-10-27T16:32:30.330Z,1414427550.330 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-10-27T16:32:30.896Z,1414427550.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:31.582Z,1414427551.582 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:32.155Z,1414427552.155 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:32.556Z,1414427552.556 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:33.069Z,1414427553.069 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:33.480Z,1414427553.480 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:34.004Z,1414427554.004 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:34.811Z,1414427554.811 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:35.268Z,1414427555.268 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:35.903Z,1414427555.903 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:36.420Z,1414427556.420 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:36.858Z,1414427556.858 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:37.305Z,1414427557.305 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:37.755Z,1414427557.755 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:38.164Z,1414427558.164 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:38.705Z,1414427558.705 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:39.162Z,1414427559.162 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:39.671Z,1414427559.671 [NAL9602](INFO): NAL9602 initialized
2014-10-27T16:32:39.735Z,1414427559.735 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:40.175Z,1414427560.175 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:40.640Z,1414427560.640 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:40.704Z,1414427560.704 [SBIT](IMPORTANT): Beginning Startup BIT
2014-10-27T16:32:40.707Z,1414427560.707 [CBIT](IMPORTANT): Beginning GF scan
2014-10-27T16:32:41.165Z,1414427561.165 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:41.669Z,1414427561.669 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:42.225Z,1414427562.225 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:42.686Z,1414427562.686 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:43.264Z,1414427563.264 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:43.769Z,1414427563.769 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:44.269Z,1414427564.269 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:44.810Z,1414427564.810 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:45.394Z,1414427565.394 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:45.815Z,1414427565.815 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2014-10-27T16:32:45.815Z,1414427565.815 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2014-10-27T16:32:45.815Z,1414427565.815 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2014-10-27T16:32:45.896Z,1414427565.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:46.077Z,1414427566.077 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2014-10-27T16:32:46.476Z,1414427566.476 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-10-27T16:32:46.757Z,1414427566.757 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:47.304Z,1414427567.304 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:47.839Z,1414427567.839 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:47.000Z,1414427568.000 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2014-10-27T16:32:47.000Z,1414427568.000 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2014-10-27T16:32:48.396Z,1414427568.396 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:48.900Z,1414427568.900 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:49.401Z,1414427569.401 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:49.869Z,1414427569.869 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-10-27T16:32:49.869Z,1414427569.869 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-10-27T16:32:49.979Z,1414427569.979 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:50.471Z,1414427570.471 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:51.050Z,1414427571.050 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:51.573Z,1414427571.573 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:52.102Z,1414427572.102 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:52.605Z,1414427572.605 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:53.220Z,1414427573.220 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:53.704Z,1414427573.704 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:53.856Z,1414427573.856 [SBIT](FAULT): Mass: EXPECTED:0.006000 ACTUAL:0.005627
2014-10-27T16:32:53.856Z,1414427573.856 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.670113
2014-10-27T16:32:54.285Z,1414427574.285 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:54.818Z,1414427574.818 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:55.335Z,1414427575.335 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:55.812Z,1414427575.812 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:56.286Z,1414427576.286 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:57.044Z,1414427577.044 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:57.534Z,1414427577.534 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:58.082Z,1414427578.082 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:58.605Z,1414427578.605 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:59.115Z,1414427579.115 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:32:59.645Z,1414427579.645 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:00.139Z,1414427580.139 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:00.631Z,1414427580.631 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:01.244Z,1414427581.244 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:01.725Z,1414427581.725 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:02.269Z,1414427582.269 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:02.814Z,1414427582.814 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:03.296Z,1414427583.296 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:03.796Z,1414427583.796 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:04.333Z,1414427584.333 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:04.845Z,1414427584.845 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:05.378Z,1414427585.378 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:05.932Z,1414427585.932 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:06.441Z,1414427586.441 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:07.109Z,1414427587.109 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:07.658Z,1414427587.658 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:07.765Z,1414427587.765 [CBIT](FAULT): Low side GF detected
mA:
CHAN 5 (24V): 0.391695
CHAN 4 (Batt): 0.391695
CHAN 2 (12V): 0.391695
CHAN 1 (5V): 0.391695
CHAN 0 (3.3V): 0.336313
OPEN: 0.000478
Full Scale Calc: 0.392
2014-10-27T16:33:08.238Z,1414427588.238 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:08.692Z,1414427588.692 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:09.196Z,1414427589.196 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:09.706Z,1414427589.706 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:10.137Z,1414427590.137 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2014-10-27T16:33:10.137Z,1414427590.137 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2014-10-27T16:33:10.137Z,1414427590.137 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2014-10-27T16:33:10.166Z,1414427590.166 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:10.295Z,1414427590.295 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2014-10-27T16:33:10.641Z,1414427590.641 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-10-27T16:33:10.823Z,1414427590.823 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:11.348Z,1414427591.348 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:11.844Z,1414427591.844 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:11.938Z,1414427591.938 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2014-10-27T16:33:11.938Z,1414427591.938 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2014-10-27T16:33:12.359Z,1414427592.359 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:12.834Z,1414427592.834 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:13.331Z,1414427593.331 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:13.774Z,1414427593.774 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-10-27T16:33:13.774Z,1414427593.774 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-10-27T16:33:13.855Z,1414427593.855 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:14.336Z,1414427594.336 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:14.813Z,1414427594.813 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:15.266Z,1414427595.266 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:15.805Z,1414427595.805 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:16.287Z,1414427596.287 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:16.768Z,1414427596.768 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:17.245Z,1414427597.245 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:17.765Z,1414427597.765 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:18.234Z,1414427598.234 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:18.699Z,1414427598.699 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:19.239Z,1414427599.239 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:19.738Z,1414427599.738 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:20.222Z,1414427600.222 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:20.678Z,1414427600.678 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:21.127Z,1414427601.127 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:21.576Z,1414427601.576 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:22.080Z,1414427602.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:22.597Z,1414427602.597 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:23.146Z,1414427603.146 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:24.052Z,1414427604.052 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:24.540Z,1414427604.540 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:25.110Z,1414427605.110 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:25.696Z,1414427605.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:26.216Z,1414427606.216 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:26.681Z,1414427606.681 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:27.172Z,1414427607.172 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:27.699Z,1414427607.699 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:28.220Z,1414427608.220 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:28.629Z,1414427608.629 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:29.087Z,1414427609.087 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:29.483Z,1414427609.483 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:29.882Z,1414427609.882 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:30.277Z,1414427610.277 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:30.692Z,1414427610.692 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:31.097Z,1414427611.097 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:31.503Z,1414427611.503 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:31.893Z,1414427611.893 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:32.303Z,1414427612.303 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:32.733Z,1414427612.733 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:33.136Z,1414427613.136 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:33.541Z,1414427613.541 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:33.906Z,1414427613.906 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2014-10-27T16:33:33.906Z,1414427613.906 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2014-10-27T16:33:33.906Z,1414427613.906 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2014-10-27T16:33:33.957Z,1414427613.957 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:33.981Z,1414427613.981 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2014-10-27T16:33:34.330Z,1414427614.330 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-10-27T16:33:34.465Z,1414427614.465 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:34.863Z,1414427614.863 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:34.891Z,1414427614.891 [SBIT](CRITICAL): SBIT FAILED
2014-10-27T16:33:34.892Z,1414427614.892 [CommandLine](FAULT): Scheduling is paused
2014-10-27T16:33:35.329Z,1414427615.329 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:35.335Z,1414427615.335 [MissionManager](IMPORTANT): Started mission Startup
2014-10-27T16:33:35.335Z,1414427615.335 [Startup] Running Loop=1
2014-10-27T16:33:35.336Z,1414427615.336 [Startup](INFO): Aggregate::initialize Startup
2014-10-27T16:33:35.336Z,1414427615.336 [Startup:A.GoToSurface] Running Loop=1
2014-10-27T16:33:35.336Z,1414427615.336 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-10-27T16:33:35.336Z,1414427615.336 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-10-27T16:33:35.337Z,1414427615.337 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-10-27T16:33:35.337Z,1414427615.337 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-10-27T16:33:35.346Z,1414427615.346 [Startup:StartupSatComms] Running Loop=1
2014-10-27T16:33:35.346Z,1414427615.346 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-10-27T16:33:35.346Z,1414427615.346 [Startup:StartupSatComms:A] Running Loop=1
2014-10-27T16:33:35.355Z,1414427615.355 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2014-10-27T16:33:35.355Z,1414427615.355 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2014-10-27T16:33:35.722Z,1414427615.722 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:35.728Z,1414427615.728 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-10-27T16:33:36.122Z,1414427616.122 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:36.523Z,1414427616.523 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:36.946Z,1414427616.946 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:37.683Z,1414427617.683 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-10-27T16:33:37.684Z,1414427617.684 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-10-27T16:33:37.736Z,1414427617.736 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:38.140Z,1414427618.140 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:38.663Z,1414427618.663 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:39.090Z,1414427619.090 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:39.520Z,1414427619.520 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:39.993Z,1414427619.993 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:40.398Z,1414427620.398 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:40.838Z,1414427620.838 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:41.268Z,1414427621.268 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:41.710Z,1414427621.710 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:42.143Z,1414427622.143 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:42.564Z,1414427622.564 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:43.013Z,1414427623.013 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:43.443Z,1414427623.443 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:43.848Z,1414427623.848 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:44.264Z,1414427624.264 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:44.744Z,1414427624.744 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:45.191Z,1414427625.191 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:45.617Z,1414427625.617 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:46.049Z,1414427626.049 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:46.451Z,1414427626.451 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:46.858Z,1414427626.858 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:47.297Z,1414427627.297 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:47.775Z,1414427627.775 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:48.204Z,1414427628.204 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:48.634Z,1414427628.634 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:49.064Z,1414427629.064 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:49.497Z,1414427629.497 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:49.909Z,1414427629.909 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:50.312Z,1414427630.312 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:50.741Z,1414427630.741 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:51.232Z,1414427631.232 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:51.665Z,1414427631.665 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:52.088Z,1414427632.088 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:52.514Z,1414427632.514 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:52.977Z,1414427632.977 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:53.429Z,1414427633.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:53.866Z,1414427633.866 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:54.368Z,1414427634.368 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:54.887Z,1414427634.887 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:55.293Z,1414427635.293 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:55.694Z,1414427635.694 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:56.084Z,1414427636.084 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:56.479Z,1414427636.479 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:56.889Z,1414427636.889 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:57.295Z,1414427637.295 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:57.745Z,1414427637.745 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:58.111Z,1414427638.111 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2014-10-27T16:33:58.111Z,1414427638.111 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2014-10-27T16:33:58.111Z,1414427638.111 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2014-10-27T16:33:58.132Z,1414427638.132 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:58.147Z,1414427638.147 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2014-10-27T16:33:58.516Z,1414427638.516 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-10-27T16:33:58.665Z,1414427638.665 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:59.039Z,1414427639.039 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:59.422Z,1414427639.422 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:33:59.437Z,1414427639.437 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2014-10-27T16:33:59.437Z,1414427639.437 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2014-10-27T16:33:59.871Z,1414427639.871 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:00.278Z,1414427640.278 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:00.743Z,1414427640.743 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:01.128Z,1414427641.128 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:01.531Z,1414427641.531 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:01.913Z,1414427641.913 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-10-27T16:34:01.913Z,1414427641.913 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-10-27T16:34:01.949Z,1414427641.949 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:02.336Z,1414427642.336 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:02.756Z,1414427642.756 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:03.155Z,1414427643.155 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:03.561Z,1414427643.561 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:03.955Z,1414427643.955 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:04.354Z,1414427644.354 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:04.744Z,1414427644.744 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:05.145Z,1414427645.145 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:05.541Z,1414427645.541 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:05.953Z,1414427645.953 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:06.356Z,1414427646.356 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:06.760Z,1414427646.760 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:07.185Z,1414427647.185 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:07.650Z,1414427647.650 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:08.037Z,1414427648.037 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:08.438Z,1414427648.438 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:08.886Z,1414427648.886 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:09.269Z,1414427649.269 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:09.707Z,1414427649.707 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:10.117Z,1414427650.117 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:10.514Z,1414427650.514 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:10.928Z,1414427650.928 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:11.322Z,1414427651.322 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:11.757Z,1414427651.757 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:12.158Z,1414427652.158 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:12.557Z,1414427652.557 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:12.947Z,1414427652.947 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:13.348Z,1414427653.348 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:13.764Z,1414427653.764 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:14.160Z,1414427654.160 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:14.559Z,1414427654.559 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:14.978Z,1414427654.978 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:15.378Z,1414427655.378 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:15.784Z,1414427655.784 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:16.173Z,1414427656.173 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:16.561Z,1414427656.561 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:16.981Z,1414427656.981 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:17.381Z,1414427657.381 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:17.787Z,1414427657.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:18.188Z,1414427658.188 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:18.603Z,1414427658.603 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:18.978Z,1414427658.978 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:19.367Z,1414427659.367 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:19.767Z,1414427659.767 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:20.185Z,1414427660.185 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:20.591Z,1414427660.591 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:21.011Z,1414427661.011 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:21.415Z,1414427661.415 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:21.825Z,1414427661.825 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:22.189Z,1414427662.189 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2014-10-27T16:34:22.189Z,1414427662.189 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2014-10-27T16:34:22.189Z,1414427662.189 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2014-10-27T16:34:22.288Z,1414427662.288 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:22.322Z,1414427662.322 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2014-10-27T16:34:22.665Z,1414427662.665 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-10-27T16:34:22.809Z,1414427662.809 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:23.220Z,1414427663.220 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:23.619Z,1414427663.619 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:23.633Z,1414427663.633 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2014-10-27T16:34:23.634Z,1414427663.634 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2014-10-27T16:34:24.016Z,1414427664.016 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:24.070Z,1414427664.070 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:24.416Z,1414427664.416 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:24.487Z,1414427664.487 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:24.861Z,1414427664.861 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:24.884Z,1414427664.884 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:25.270Z,1414427665.270 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:25.280Z,1414427665.280 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:25.674Z,1414427665.674 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:25.685Z,1414427665.685 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:26.060Z,1414427666.060 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-10-27T16:34:26.060Z,1414427666.060 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-10-27T16:34:26.083Z,1414427666.083 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:26.094Z,1414427666.094 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:26.504Z,1414427666.504 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:26.573Z,1414427666.573 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:26.937Z,1414427666.937 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:26.969Z,1414427666.969 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:27.353Z,1414427667.353 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:27.390Z,1414427667.390 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:27.765Z,1414427667.765 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:27.793Z,1414427667.793 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:28.170Z,1414427668.170 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:28.189Z,1414427668.189 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:28.567Z,1414427668.567 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:28.588Z,1414427668.588 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:28.962Z,1414427668.962 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:28.989Z,1414427668.989 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:29.364Z,1414427669.364 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:29.389Z,1414427669.389 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:29.769Z,1414427669.769 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:29.816Z,1414427669.816 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:30.208Z,1414427670.208 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:30.233Z,1414427670.233 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:30.614Z,1414427670.614 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:30.655Z,1414427670.655 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:31.002Z,1414427671.002 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:31.013Z,1414427671.013 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:31.407Z,1414427671.407 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:31.418Z,1414427671.418 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:31.808Z,1414427671.808 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:31.819Z,1414427671.819 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:32.208Z,1414427672.208 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:32.219Z,1414427672.219 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:32.609Z,1414427672.609 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:32.620Z,1414427672.620 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:33.010Z,1414427673.010 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:33.083Z,1414427673.083 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:33.462Z,1414427673.462 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:33.491Z,1414427673.491 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:33.867Z,1414427673.867 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:33.908Z,1414427673.908 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:34.272Z,1414427674.272 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:34.282Z,1414427674.282 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:34.664Z,1414427674.664 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:34.675Z,1414427674.675 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:35.061Z,1414427675.061 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:35.073Z,1414427675.073 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:35.462Z,1414427675.462 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:35.472Z,1414427675.473 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:35.479Z,1414427675.479 [Startup:StartupSatComms:A](INFO): Timed out from 2014-10-27T16:33:35.3Z
2014-10-27T16:34:35.479Z,1414427675.479 [Startup:StartupSatComms:A] Stopped
2014-10-27T16:34:35.479Z,1414427675.479 [Startup:StartupSatComms:B] Running Loop=1
2014-10-27T16:34:35.870Z,1414427675.870 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:35.894Z,1414427675.894 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:35.920Z,1414427675.920 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-10-27T16:34:36.527Z,1414427676.527 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:36.649Z,1414427676.649 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:37.029Z,1414427677.029 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:37.073Z,1414427677.073 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:37.449Z,1414427677.449 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:37.468Z,1414427677.468 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:37.854Z,1414427677.854 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:37.891Z,1414427677.891 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:38.266Z,1414427678.266 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:38.295Z,1414427678.295 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:38.667Z,1414427678.667 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:39.209Z,1414427679.209 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:39.580Z,1414427679.580 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:39.771Z,1414427679.771 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:40.137Z,1414427680.137 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:40.162Z,1414427680.162 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:40.538Z,1414427680.538 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:40.570Z,1414427680.570 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:40.946Z,1414427680.946 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:40.977Z,1414427680.977 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:41.356Z,1414427681.356 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:41.384Z,1414427681.384 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:41.760Z,1414427681.760 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:41.770Z,1414427681.770 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:42.161Z,1414427682.161 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:42.172Z,1414427682.172 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:42.562Z,1414427682.562 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:42.590Z,1414427682.590 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:42.962Z,1414427682.962 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:43.510Z,1414427683.510 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:43.873Z,1414427683.873 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:43.994Z,1414427683.994 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:44.359Z,1414427684.359 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:44.370Z,1414427684.370 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:44.764Z,1414427684.764 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:44.787Z,1414427684.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:45.160Z,1414427685.160 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:45.184Z,1414427685.184 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:45.561Z,1414427685.561 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:45.590Z,1414427685.590 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:45.974Z,1414427685.974 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-10-27T16:34:45.984Z,1414427685.984 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:46.358Z,1414427686.358 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2014-10-27T16:34:46.358Z,1414427686.358 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2014-10-27T16:34:46.358Z,1414427686.358 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2014-10-27T16:34:46.372Z,1414427686.372 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:46.386Z,1414427686.386 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2014-10-27T16:34:46.775Z,1414427686.775 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-10-27T16:34:46.930Z,1414427686.930 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:47.326Z,1414427687.326 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:48.230Z,1414427688.230 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:48.245Z,1414427688.245 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2014-10-27T16:34:48.245Z,1414427688.245 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2014-10-27T16:34:48.751Z,1414427688.751 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:49.133Z,1414427689.133 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:49.551Z,1414427689.551 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:49.920Z,1414427689.920 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-10-27T16:34:49.920Z,1414427689.920 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-10-27T16:34:50.021Z,1414427690.021 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:50.429Z,1414427690.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:50.793Z,1414427690.793 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:51.198Z,1414427691.198 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:51.688Z,1414427691.688 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:52.585Z,1414427692.585 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:53.118Z,1414427693.118 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:53.542Z,1414427693.542 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:53.942Z,1414427693.942 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:54.352Z,1414427694.352 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:54.777Z,1414427694.777 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:55.145Z,1414427695.145 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:55.551Z,1414427695.551 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:55.952Z,1414427695.952 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:56.383Z,1414427696.383 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-10-27T16:34:56.383Z,1414427696.383 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 442 1337 586 2 2 -192.2 1035.7 788.9 2 1049.3 -91.6 788.9 2 -192 1035 788 2 1049 -91 788 2 0.53 0.03 254.5 -3.0 21.1 0.005 35.0 1489 114
2014-10-27T16:34:56.433Z,1414427696.433 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:56.825Z,1414427696.825 [CommandLine](IMPORTANT): got command failComponent
2014-10-27T16:34:56.825Z,1414427696.825 [CommandLine](IMPORTANT): Failed components:
2014-10-27T16:34:56.826Z,1414427696.826 [CommandLine](IMPORTANT): No failed Components.
2014-10-27T16:34:57.336Z,1414427697.336 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:57.836Z,1414427697.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:58.223Z,1414427698.223 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:58.625Z,1414427698.625 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:59.030Z,1414427699.030 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:59.475Z,1414427699.475 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:34:59.868Z,1414427699.868 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:00.275Z,1414427700.275 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:00.658Z,1414427700.658 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:01.569Z,1414427701.569 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:02.072Z,1414427702.072 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:02.475Z,1414427702.475 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:02.889Z,1414427702.889 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:03.290Z,1414427703.290 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:03.706Z,1414427703.706 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:04.068Z,1414427704.068 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:04.471Z,1414427704.471 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:04.870Z,1414427704.870 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:05.293Z,1414427705.293 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:06.201Z,1414427706.201 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:06.736Z,1414427706.736 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:07.177Z,1414427707.177 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:07.568Z,1414427707.568 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:07.976Z,1414427707.976 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:08.410Z,1414427708.410 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:08.787Z,1414427708.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:09.242Z,1414427709.242 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:09.665Z,1414427709.665 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:10.019Z,1414427710.019 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2014-10-27T16:35:10.019Z,1414427710.019 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2014-10-27T16:35:10.019Z,1414427710.019 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2014-10-27T16:35:10.554Z,1414427710.554 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:10.569Z,1414427710.569 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2014-10-27T16:35:10.916Z,1414427710.916 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-10-27T16:35:11.255Z,1414427711.255 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:11.672Z,1414427711.672 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:12.070Z,1414427712.070 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:12.085Z,1414427712.085 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2014-10-27T16:35:12.085Z,1414427712.085 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2014-10-27T16:35:12.494Z,1414427712.494 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:12.925Z,1414427712.925 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:13.294Z,1414427713.294 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:13.682Z,1414427713.682 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:14.069Z,1414427714.069 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-10-27T16:35:14.070Z,1414427714.070 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-10-27T16:35:14.105Z,1414427714.105 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:14.526Z,1414427714.526 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:15.435Z,1414427715.435 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:15.989Z,1414427715.989 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:16.382Z,1414427716.382 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:16.799Z,1414427716.799 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:17.202Z,1414427717.202 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:17.608Z,1414427717.608 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:17.994Z,1414427717.994 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:18.391Z,1414427718.391 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:18.787Z,1414427718.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:19.206Z,1414427719.206 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:19.632Z,1414427719.632 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:20.532Z,1414427720.532 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:21.061Z,1414427721.061 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:21.440Z,1414427721.440 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:21.909Z,1414427721.909 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:22.359Z,1414427722.359 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:22.789Z,1414427722.789 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:23.162Z,1414427723.162 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:23.588Z,1414427723.588 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:23.991Z,1414427723.991 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:24.367Z,1414427724.367 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:25.282Z,1414427725.282 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:25.816Z,1414427725.816 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:26.206Z,1414427726.206 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:26.618Z,1414427726.618 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:27.013Z,1414427727.013 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:27.423Z,1414427727.423 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:27.805Z,1414427727.805 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:28.207Z,1414427728.207 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:28.625Z,1414427728.625 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:29.529Z,1414427729.529 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:30.022Z,1414427730.022 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:30.408Z,1414427730.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:30.819Z,1414427730.819 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:31.200Z,1414427731.200 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-10-27T16:35:31.200Z,1414427731.200 [DVL_micro] Data Fault, FailCount= 1
2014-10-27T16:35:31.200Z,1414427731.200 [DVL_micro](ERROR): Data Fault
2014-10-27T16:35:31.229Z,1414427731.229 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:31.276Z,1414427731.276 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-10-27T16:35:31.691Z,1414427731.691 [DVL_micro](INFO): uninitialize:Powering down
2014-10-27T16:35:31.726Z,1414427731.726 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:32.116Z,1414427732.116 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:32.512Z,1414427732.512 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:32.527Z,1414427732.527 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-10-27T16:35:32.527Z,1414427732.527 [DVL_micro] No Fault, FailCount= 1
2014-10-27T16:35:32.906Z,1414427732.906 [DVL_micro](INFO): Initializing
2014-10-27T16:35:32.918Z,1414427732.918 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:33.327Z,1414427733.327 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:34.234Z,1414427734.234 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:34.597Z,1414427734.597 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2014-10-27T16:35:34.597Z,1414427734.597 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2014-10-27T16:35:34.598Z,1414427734.598 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2014-10-27T16:35:34.743Z,1414427734.743 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:34.768Z,1414427734.768 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2014-10-27T16:35:34.769Z,1414427734.769 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2014-10-27T16:35:35.110Z,1414427735.110 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-10-27T16:35:35.256Z,1414427735.256 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:35.658Z,1414427735.658 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:35.665Z,1414427735.665 [Startup:StartupSatComms:B](INFO): Timed out from 2014-10-27T16:34:35.5Z
2014-10-27T16:35:35.665Z,1414427735.665 [Startup:StartupSatComms:B] Stopped
2014-10-27T16:35:35.665Z,1414427735.665 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2014-10-27T16:35:35.666Z,1414427735.666 [Startup:StartupSatComms] Stopped
2014-10-27T16:35:35.666Z,1414427735.666 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-10-27T16:35:35.667Z,1414427735.667 [Startup](INFO): Completed Startup
2014-10-27T16:35:35.667Z,1414427735.667 [Startup] Stopped
2014-10-27T16:35:35.667Z,1414427735.667 [Startup](INFO): Aggregate::uninitialize Startup
2014-10-27T16:35:35.667Z,1414427735.667 [Startup:A.GoToSurface] Stopped
2014-10-27T16:35:35.667Z,1414427735.667 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-10-27T16:35:36.126Z,1414427736.126 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:36.132Z,1414427736.132 [MissionManager](IMPORTANT): Started mission Default
2014-10-27T16:35:36.132Z,1414427736.132 [Default] Running Loop=1
2014-10-27T16:35:36.132Z,1414427736.132 [Default](INFO): Aggregate::initialize Default
2014-10-27T16:35:36.132Z,1414427736.132 [Default:B.GoToSurface] Running Loop=1
2014-10-27T16:35:36.132Z,1414427736.132 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-10-27T16:35:36.132Z,1414427736.132 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-10-27T16:35:36.133Z,1414427736.133 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-10-27T16:35:36.133Z,1414427736.133 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-10-27T16:35:36.133Z,1414427736.133 [Default:StartClock] Running Loop=1
2014-10-27T16:35:36.133Z,1414427736.133 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock
2014-10-27T16:35:36.133Z,1414427736.133 [Default:StartClock:A] Running Loop=1
2014-10-27T16:35:36.135Z,1414427736.135 [Default:CheckIn] Running Loop=1
2014-10-27T16:35:36.135Z,1414427736.135 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2014-10-27T16:35:36.135Z,1414427736.135 [Default:CheckIn:A.SetSpeed] Running Loop=1
2014-10-27T16:35:36.135Z,1414427736.135 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2014-10-27T16:35:36.135Z,1414427736.135 [Default:CheckIn:Read_GPS] Running Loop=1
2014-10-27T16:35:36.136Z,1414427736.136 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-10-27T16:35:36.138Z,1414427736.138 [Default:CheckIn:A.SetSpeed] Running Loop=1
2014-10-27T16:35:36.174Z,1414427736.174 [Default:StartClock:A] Stopped
2014-10-27T16:35:36.175Z,1414427736.175 [Default:StartClock](INFO): Completed Default:StartClock
2014-10-27T16:35:36.175Z,1414427736.175 [Default:StartClock] Stopped
2014-10-27T16:35:36.175Z,1414427736.175 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock
2014-10-27T16:35:36.558Z,1414427736.558 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:36.565Z,1414427736.565 [Default:WaitAtTheSurface] Running Loop=1
2014-10-27T16:35:36.565Z,1414427736.565 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2014-10-27T16:35:36.565Z,1414427736.565 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2014-10-27T16:35:36.565Z,1414427736.565 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2014-10-27T16:35:36.989Z,1414427736.989 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:36.995Z,1414427736.995 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2014-10-27T16:35:37.372Z,1414427737.372 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:37.804Z,1414427737.804 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:38.217Z,1414427738.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:38.602Z,1414427738.602 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:39.040Z,1414427739.040 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:39.510Z,1414427739.510 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:39.926Z,1414427739.926 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:40.315Z,1414427740.315 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:40.706Z,1414427740.706 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:41.107Z,1414427741.107 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:41.501Z,1414427741.501 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:41.945Z,1414427741.945 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:42.317Z,1414427742.317 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:42.783Z,1414427742.783 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:43.179Z,1414427743.179 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:43.569Z,1414427743.569 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:43.983Z,1414427743.983 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:44.367Z,1414427744.367 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:44.781Z,1414427744.781 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:45.203Z,1414427745.203 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:45.584Z,1414427745.584 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:45.982Z,1414427745.982 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:46.408Z,1414427746.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:46.808Z,1414427746.808 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:47.184Z,1414427747.184 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:47.606Z,1414427747.606 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:48.015Z,1414427748.015 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:48.412Z,1414427748.412 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:48.817Z,1414427748.817 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:49.229Z,1414427749.229 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:49.641Z,1414427749.641 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:50.033Z,1414427750.033 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:50.429Z,1414427750.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:50.872Z,1414427750.872 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:51.263Z,1414427751.263 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:51.673Z,1414427751.673 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:52.071Z,1414427752.071 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:52.486Z,1414427752.486 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:52.890Z,1414427752.890 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:53.288Z,1414427753.288 [BPC1](FAULT): Failed to receive battery data
2014-10-27T16:35:53.288Z,1414427753.288 [BPC1] Communications Fault, FailCount= 1
2014-10-27T16:35:53.288Z,1414427753.288 [BPC1](ERROR): Communications Fault
2014-10-27T16:35:53.293Z,1414427753.293 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:53.345Z,1414427753.345 [CBIT](ERROR): Communications Fault in component: BPC1
2014-10-27T16:35:53.741Z,1414427753.741 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:54.173Z,1414427754.173 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:54.568Z,1414427754.568 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:54.583Z,1414427754.583 [CBIT](INFO): Clearing failed state for component BPC1
2014-10-27T16:35:54.583Z,1414427754.583 [BPC1] No Fault, FailCount= 1
2014-10-27T16:35:55.019Z,1414427755.019 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:55.388Z,1414427755.388 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:55.848Z,1414427755.848 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:56.226Z,1414427756.226 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:56.630Z,1414427756.630 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:57.044Z,1414427757.044 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:57.450Z,1414427757.450 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:57.855Z,1414427757.855 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:58.236Z,1414427758.236 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:58.632Z,1414427758.632 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:59.055Z,1414427759.055 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:59.452Z,1414427759.452 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:35:59.878Z,1414427759.878 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:00.286Z,1414427760.286 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:00.689Z,1414427760.689 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:01.077Z,1414427761.077 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:01.482Z,1414427761.482 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:01.883Z,1414427761.883 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:02.303Z,1414427762.303 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:02.734Z,1414427762.734 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:03.115Z,1414427763.115 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:03.535Z,1414427763.535 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:03.931Z,1414427763.931 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:04.328Z,1414427764.328 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:04.728Z,1414427764.728 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:05.112Z,1414427765.112 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:05.548Z,1414427765.548 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:05.959Z,1414427765.959 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:06.408Z,1414427766.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:06.839Z,1414427766.839 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:07.195Z,1414427767.195 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:07.604Z,1414427767.604 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:08.037Z,1414427768.037 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:08.404Z,1414427768.404 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:08.896Z,1414427768.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:09.302Z,1414427769.302 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:09.696Z,1414427769.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:10.103Z,1414427770.103 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:10.495Z,1414427770.495 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:10.901Z,1414427770.901 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:11.318Z,1414427771.318 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:11.744Z,1414427771.744 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:12.036Z,1414427772.036 [CommandLine](IMPORTANT): got command restart application
2014-10-27T16:36:12.185Z,1414427772.185 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:12.613Z,1414427772.613 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:12.986Z,1414427772.986 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:13.046Z,1414427773.046 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T16:36:13.047Z,1414427773.047 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.067Z,1414427773.067 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-10-27T16:36:13.067Z,1414427773.067 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.068Z,1414427773.068 [CommandLine](INFO): Join timeout helper Thread ID is 1461
2014-10-27T16:36:13.069Z,1414427773.069 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-10-27T16:36:13.069Z,1414427773.069 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.070Z,1414427773.070 [NavChartDb](INFO): Join timeout helper Thread ID is 1462
2014-10-27T16:36:13.336Z,1414427773.336 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T16:36:13.336Z,1414427773.336 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.348Z,1414427773.348 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-10-27T16:36:13.348Z,1414427773.348 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.348Z,1414427773.348 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1463
2014-10-27T16:36:13.376Z,1414427773.376 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T16:36:13.376Z,1414427773.376 [WetLabsBB2FL](INFO): Powering down
2014-10-27T16:36:13.424Z,1414427773.424 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.428Z,1414427773.428 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-10-27T16:36:13.428Z,1414427773.428 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.428Z,1414427773.428 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1464
2014-10-27T16:36:13.435Z,1414427773.435 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:13.645Z,1414427773.645 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T16:36:13.645Z,1414427773.645 [CTD_NeilBrown](INFO): Powering down
2014-10-27T16:36:13.646Z,1414427773.646 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.664Z,1414427773.664 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-10-27T16:36:13.664Z,1414427773.664 [logger ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.665Z,1414427773.665 [logger](INFO): Join timeout helper Thread ID is 1465
2014-10-27T16:36:13.700Z,1414427773.700 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T16:36:13.701Z,1414427773.701 [logger ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.705Z,1414427773.705 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-10-27T16:36:13.705Z,1414427773.705 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.706Z,1414427773.706 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-10-27T16:36:13.706Z,1414427773.706 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:13.706Z,1414427773.706 [controlThread](INFO): Join timeout helper Thread ID is 1466
2014-10-27T16:36:13.825Z,1414427773.825 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T16:36:13.842Z,1414427773.842 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T16:36:13.842Z,1414427773.842 [controlThread](DEBUG): Uninitializing ControlThread
2014-10-27T16:36:13.843Z,1414427773.843 [Aanderaa_O2](INFO): Powering down
2014-10-27T16:36:13.844Z,1414427773.844 [AHRS_sp3003D](INFO): Powering down
2014-10-27T16:36:13.929Z,1414427773.929 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-10-27T16:36:14.001Z,1414427774.001 [DVL_micro](INFO): uninitialize:Powering down
2014-10-27T16:36:14.002Z,1414427774.002 [NAL9602](INFO): Powering down
2014-10-27T16:36:14.004Z,1414427774.004 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-10-27T16:36:14.006Z,1414427774.006 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-10-27T16:36:14.006Z,1414427774.006 [Default] Stopped
2014-10-27T16:36:14.006Z,1414427774.006 [Default](INFO): Aggregate::uninitialize Default
2014-10-27T16:36:14.006Z,1414427774.006 [Default:B.GoToSurface] Stopped
2014-10-27T16:36:14.007Z,1414427774.007 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-10-27T16:36:14.007Z,1414427774.007 [Default:CheckIn] Stopped
2014-10-27T16:36:14.007Z,1414427774.007 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2014-10-27T16:36:14.007Z,1414427774.007 [Default:CheckIn:A.SetSpeed] Stopped
2014-10-27T16:36:14.007Z,1414427774.007 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2014-10-27T16:36:14.007Z,1414427774.007 [Default:CheckIn:Read_GPS] Stopped
2014-10-27T16:36:14.007Z,1414427774.007 [Default:WaitAtTheSurface] Stopped
2014-10-27T16:36:14.007Z,1414427774.007 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface
2014-10-27T16:36:14.007Z,1414427774.007 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Stopped
2014-10-27T16:36:14.007Z,1414427774.007 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Uninitialize.
2014-10-27T16:36:14.011Z,1414427774.011 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-10-27T16:36:14.011Z,1414427774.011 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-10-27T16:36:14.011Z,1414427774.011 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-10-27T16:36:14.012Z,1414427774.012 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-10-27T16:36:14.012Z,1414427774.012 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-10-27T16:36:14.012Z,1414427774.012 [BuoyancyServo](INFO): Powering down
2014-10-27T16:36:14.025Z,1414427774.025 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-10-27T16:36:14.025Z,1414427774.025 [ElevatorServo](INFO): Powering down
2014-10-27T16:36:14.026Z,1414427774.026 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-10-27T16:36:14.026Z,1414427774.026 [MassServo](INFO): Powering down
2014-10-27T16:36:14.027Z,1414427774.027 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-10-27T16:36:14.027Z,1414427774.027 [RudderServo](INFO): Powering down
2014-10-27T16:36:14.028Z,1414427774.028 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-10-27T16:36:14.028Z,1414427774.028 [ThrusterServo](INFO): Powering down
2014-10-27T16:36:14.029Z,1414427774.029 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-10-27T16:36:14.029Z,1414427774.029 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-10-27T16:36:14.029Z,1414427774.029 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-10-27T16:36:14.062Z,1414427774.062 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:14.112Z,1414427774.112 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:14.115Z,1414427774.115 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:14.213Z,1414427774.213 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-10-27T16:36:14.252Z,1414427774.252 [logger ThreadHandler](INFO): Thread cancelled.