2014-09-23T21:25:04.885Z,1411507504.885 [Supervisor](DEBUG): Initializing supervisor.
2014-09-23T21:25:04.888Z,1411507504.888 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-09-23T21:25:04.888Z,1411507504.888 [SyncHandler](INFO): Protected caller Thread ID is 766
2014-09-23T21:25:04.889Z,1411507504.889 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-09-23T21:25:04.894Z,1411507504.894 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-09-23T21:25:04.894Z,1411507504.894 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 767
2014-09-23T21:25:04.897Z,1411507504.897 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-09-23T21:25:04.909Z,1411507504.909 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-09-23T21:25:04.918Z,1411507504.918 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-09-23T21:25:04.918Z,1411507504.918 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 768
2014-09-23T21:25:04.919Z,1411507504.919 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-09-23T21:25:04.922Z,1411507504.922 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-09-23T21:25:04.922Z,1411507504.922 [logger ThreadHandler](INFO): Protected caller Thread ID is 769
2014-09-23T21:25:04.924Z,1411507504.924 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-09-23T21:25:04.925Z,1411507504.925 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-09-23T21:25:04.930Z,1411507504.930 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-09-23T21:25:05.294Z,1411507505.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-09-23T21:25:05.296Z,1411507505.296 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-09-23T21:25:05.472Z,1411507505.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-09-23T21:25:05.472Z,1411507505.472 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-09-23T21:25:05.588Z,1411507505.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-09-23T21:25:05.588Z,1411507505.588 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-09-23T21:25:05.728Z,1411507505.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-09-23T21:25:05.731Z,1411507505.731 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-09-23T21:25:05.827Z,1411507505.827 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-09-23T21:25:05.989Z,1411507505.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-09-23T21:25:05.989Z,1411507505.989 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-09-23T21:25:06.087Z,1411507506.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-09-23T21:25:06.088Z,1411507506.088 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-09-23T21:25:06.309Z,1411507506.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-09-23T21:25:06.309Z,1411507506.309 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-09-23T21:25:06.677Z,1411507506.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-09-23T21:25:06.681Z,1411507506.681 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-09-23T21:25:07.018Z,1411507507.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-09-23T21:25:07.019Z,1411507507.019 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-09-23T21:25:07.568Z,1411507507.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-09-23T21:25:07.569Z,1411507507.569 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-09-23T21:25:07.792Z,1411507507.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-09-23T21:25:07.792Z,1411507507.792 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-09-23T21:25:07.907Z,1411507507.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-09-23T21:25:07.908Z,1411507507.908 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-09-23T21:25:08.367Z,1411507508.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-09-23T21:25:08.371Z,1411507508.371 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-09-23T21:25:08.497Z,1411507508.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-09-23T21:25:08.499Z,1411507508.499 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2014-09-23T21:25:19.843Z,1411507519.843 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2014-09-23T21:25:20.084Z,1411507520.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2014-09-23T21:25:20.085Z,1411507520.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2014-09-23T21:25:20.814Z,1411507520.814 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2014-09-23T21:25:20.913Z,1411507520.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2014-09-23T21:25:21.014Z,1411507521.014 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2014-09-23T21:25:21.112Z,1411507521.112 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2014-09-23T21:25:21.252Z,1411507521.252 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2014-09-23T21:25:21.422Z,1411507521.422 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2014-09-23T21:25:21.524Z,1411507521.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2014-09-23T21:25:21.612Z,1411507521.612 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2014-09-23T21:25:21.714Z,1411507521.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2014-09-23T21:25:21.812Z,1411507521.812 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2014-09-23T21:25:21.987Z,1411507521.987 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-09-23T21:25:21.993Z,1411507521.993 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-09-23T21:25:22.066Z,1411507522.066 [VerticalControl](DEBUG): Construct VerticalControl.
2014-09-23T21:25:22.187Z,1411507522.187 [VerticalControl] Loaded
2014-09-23T21:25:22.188Z,1411507522.188 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-09-23T21:25:22.188Z,1411507522.188 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-09-23T21:25:22.261Z,1411507522.261 [HorizontalControl] Loaded
2014-09-23T21:25:22.261Z,1411507522.261 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-09-23T21:25:22.262Z,1411507522.262 [SpeedControl](DEBUG): Construct SpeedControl.
2014-09-23T21:25:22.268Z,1411507522.268 [SpeedControl] Loaded
2014-09-23T21:25:22.268Z,1411507522.268 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-09-23T21:25:22.269Z,1411507522.269 [LoopControl](DEBUG): Construct LoopControl.
2014-09-23T21:25:22.270Z,1411507522.270 [LoopControl] Loaded
2014-09-23T21:25:22.270Z,1411507522.270 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-09-23T21:25:22.270Z,1411507522.270 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-09-23T21:25:22.271Z,1411507522.271 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-09-23T21:25:22.442Z,1411507522.442 [SBIT](DEBUG): Construct Startup Built In Test.
2014-09-23T21:25:22.454Z,1411507522.454 [SBIT] Loaded
2014-09-23T21:25:22.454Z,1411507522.454 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-09-23T21:25:22.455Z,1411507522.455 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-09-23T21:25:22.484Z,1411507522.484 [IBIT] Loaded
2014-09-23T21:25:22.485Z,1411507522.485 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-09-23T21:25:22.487Z,1411507522.487 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-09-23T21:25:22.607Z,1411507522.607 [CBIT] Loaded
2014-09-23T21:25:22.608Z,1411507522.608 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-09-23T21:25:22.608Z,1411507522.608 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-09-23T21:25:22.609Z,1411507522.609 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-09-23T21:25:22.640Z,1411507522.640 [DepthRateCalculator] Loaded
2014-09-23T21:25:22.640Z,1411507522.640 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-09-23T21:25:22.646Z,1411507522.646 [PitchRateCalculator] Loaded
2014-09-23T21:25:22.646Z,1411507522.646 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-09-23T21:25:22.658Z,1411507522.658 [SpeedCalculator] Loaded
2014-09-23T21:25:22.658Z,1411507522.658 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-09-23T21:25:22.674Z,1411507522.674 [TempGradientCalculator] Loaded
2014-09-23T21:25:22.675Z,1411507522.675 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-09-23T21:25:22.680Z,1411507522.680 [YawRateCalculator] Loaded
2014-09-23T21:25:22.680Z,1411507522.680 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-09-23T21:25:22.681Z,1411507522.681 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-09-23T21:25:22.682Z,1411507522.682 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-09-23T21:25:23.981Z,1411507523.981 [HFRCMReconstructedInterpolator] Loaded
2014-09-23T21:25:23.982Z,1411507523.982 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-09-23T21:25:23.982Z,1411507523.982 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-09-23T21:25:23.983Z,1411507523.983 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-09-23T21:25:24.263Z,1411507524.263 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-09-23T21:25:24.264Z,1411507524.264 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-09-23T21:25:24.357Z,1411507524.357 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-09-23T21:25:24.358Z,1411507524.358 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-09-23T21:25:24.402Z,1411507524.402 [DeadReckonWithRespectToWater] Loaded
2014-09-23T21:25:24.402Z,1411507524.402 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-09-23T21:25:24.448Z,1411507524.448 [DeadReckonWithRespectToSeafloor] Loaded
2014-09-23T21:25:24.449Z,1411507524.449 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-09-23T21:25:24.499Z,1411507524.499 [DeadReckonUsingDVLWaterTrack] Loaded
2014-09-23T21:25:24.499Z,1411507524.499 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-09-23T21:25:24.517Z,1411507524.517 [NavChart] Loaded
2014-09-23T21:25:24.517Z,1411507524.517 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-09-23T21:25:24.522Z,1411507524.522 [UniversalFixResidualReporter] Loaded
2014-09-23T21:25:24.522Z,1411507524.522 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-09-23T21:25:24.522Z,1411507524.522 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-09-23T21:25:24.523Z,1411507524.523 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-09-23T21:25:24.532Z,1411507524.532 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-09-23T21:25:24.533Z,1411507524.533 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-09-23T21:25:24.732Z,1411507524.732 [Aanderaa_O2] Loaded
2014-09-23T21:25:24.733Z,1411507524.733 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-09-23T21:25:24.769Z,1411507524.769 [CTD_NeilBrown] Loaded
2014-09-23T21:25:24.770Z,1411507524.770 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-09-23T21:25:24.771Z,1411507524.771 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405D94E0
2014-09-23T21:25:24.771Z,1411507524.771 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 852
2014-09-23T21:25:24.789Z,1411507524.789 [rhodamine] Loaded
2014-09-23T21:25:24.789Z,1411507524.789 [ComponentRegistry](DEBUG): SyncComponent "rhodamine" handled in the control thread.
2014-09-23T21:25:24.825Z,1411507524.825 [WetLabsBB2FL] Loaded
2014-09-23T21:25:24.825Z,1411507524.825 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-09-23T21:25:24.826Z,1411507524.826 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406094E0
2014-09-23T21:25:24.827Z,1411507524.827 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 853
2014-09-23T21:25:24.827Z,1411507524.827 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-09-23T21:25:24.828Z,1411507524.828 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-09-23T21:25:25.522Z,1411507525.522 [AHRS_sp3003D] Loaded
2014-09-23T21:25:25.522Z,1411507525.522 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-09-23T21:25:25.568Z,1411507525.568 [AcousticModem_Benthos_ATM900] Loaded
2014-09-23T21:25:25.568Z,1411507525.568 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2014-09-23T21:25:25.730Z,1411507525.730 [DataOverHttps] Loaded
2014-09-23T21:25:25.730Z,1411507525.730 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2014-09-23T21:25:25.744Z,1411507525.744 [Depth_Keller] Loaded
2014-09-23T21:25:25.744Z,1411507525.744 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-09-23T21:25:25.749Z,1411507525.749 [DropWeight] Loaded
2014-09-23T21:25:25.750Z,1411507525.750 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-09-23T21:25:25.891Z,1411507525.891 [DVL_micro] Loaded
2014-09-23T21:25:25.891Z,1411507525.891 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-09-23T21:25:25.987Z,1411507525.987 [NAL9602] Loaded
2014-09-23T21:25:25.988Z,1411507525.988 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-09-23T21:25:26.029Z,1411507526.029 [Onboard] Loaded
2014-09-23T21:25:26.030Z,1411507526.030 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-09-23T21:25:26.037Z,1411507526.037 [Radio_Surface] Loaded
2014-09-23T21:25:26.037Z,1411507526.037 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2014-09-23T21:25:26.038Z,1411507526.038 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408534E0
2014-09-23T21:25:26.039Z,1411507526.039 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 854
2014-09-23T21:25:27.904Z,1411507527.904 [BPC1] Loaded
2014-09-23T21:25:27.904Z,1411507527.904 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2014-09-23T21:25:27.904Z,1411507527.904 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-09-23T21:25:27.905Z,1411507527.905 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-09-23T21:25:31.464Z,1411507531.464 [BuoyancyServo] Loaded
2014-09-23T21:25:31.464Z,1411507531.464 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-09-23T21:25:31.477Z,1411507531.477 [ElevatorServo] Loaded
2014-09-23T21:25:31.477Z,1411507531.477 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-09-23T21:25:31.489Z,1411507531.489 [MassServo] Loaded
2014-09-23T21:25:31.489Z,1411507531.489 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-09-23T21:25:31.501Z,1411507531.501 [RudderServo] Loaded
2014-09-23T21:25:31.501Z,1411507531.501 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-09-23T21:25:31.513Z,1411507531.513 [ThrusterServo] Loaded
2014-09-23T21:25:31.514Z,1411507531.514 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-09-23T21:25:31.514Z,1411507531.514 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-09-23T21:25:37.556Z,1411507537.556 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-09-23T21:25:40.642Z,1411507540.642 [InternalSim] Loaded
2014-09-23T21:25:40.642Z,1411507540.642 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-09-23T21:25:40.643Z,1411507540.643 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-09-23T21:25:40.644Z,1411507540.644 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-09-23T21:25:41.414Z,1411507541.414 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-09-23T21:25:41.416Z,1411507541.416 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-09-23T21:25:41.417Z,1411507541.417 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-09-23T21:25:41.424Z,1411507541.424 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-09-23T21:25:41.425Z,1411507541.425 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409044E0
2014-09-23T21:25:41.425Z,1411507541.425 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 855
2014-09-23T21:25:41.430Z,1411507541.430 [Supervisor](INFO): Main Thread ID is 765
2014-09-23T21:25:41.430Z,1411507541.430 [Supervisor](DEBUG): Running supervisor.
2014-09-23T21:25:41.431Z,1411507541.431 [CommandLine ThreadHandler](INFO): Handler Thread ID is 856
2014-09-23T21:25:41.433Z,1411507541.433 [controlThread ThreadHandler](INFO): Handler Thread ID is 857
2014-09-23T21:25:41.434Z,1411507541.434 [controlThread](DEBUG): Initializing ControlThread
2014-09-23T21:25:41.435Z,1411507541.435 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-09-23T21:25:41.437Z,1411507541.437 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-09-23T21:25:41.437Z,1411507541.437 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-09-23T21:25:41.438Z,1411507541.438 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-09-23T21:25:41.438Z,1411507541.438 [SBIT](INFO): Initialize SBIT Component.
2014-09-23T21:25:41.439Z,1411507541.439 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11701
2014-09-23T21:25:41.439Z,1411507541.439 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2014-09-23T21:25:41.439Z,1411507541.439 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-09-23T21:25:41.440Z,1411507541.440 [IBIT](INFO): Initialize IBIT Component.
2014-09-23T21:25:41.440Z,1411507541.440 [CBIT](DEBUG): Initialize CBIT Component.
2014-09-23T21:25:41.441Z,1411507541.441 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-09-23T21:25:41.442Z,1411507541.442 [logger ThreadHandler](INFO): Handler Thread ID is 858
2014-09-23T21:25:41.467Z,1411507541.467 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-23T21:25:41.467Z,1411507541.467 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-09-23T21:25:41.468Z,1411507541.468 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-09-23T21:25:41.468Z,1411507541.468 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-09-23T21:25:41.469Z,1411507541.469 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-09-23T21:25:41.470Z,1411507541.470 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-09-23T21:25:41.472Z,1411507541.472 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-23T21:25:41.472Z,1411507541.472 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-09-23T21:25:41.473Z,1411507541.473 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-09-23T21:25:41.473Z,1411507541.473 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-09-23T21:25:41.473Z,1411507541.473 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-09-23T21:25:41.474Z,1411507541.474 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-23T21:25:41.483Z,1411507541.483 [InternalSim](DEBUG):  InternalSim initializing...
2014-09-23T21:25:41.491Z,1411507541.491 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 859
2014-09-23T21:25:41.492Z,1411507541.492 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-09-23T21:25:41.499Z,1411507541.499 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-09-23T21:25:41.505Z,1411507541.505 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 861
2014-09-23T21:25:41.506Z,1411507541.506 [WetLabsBB2FL](INFO): Powering down
2014-09-23T21:25:41.539Z,1411507541.539 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 862
2014-09-23T21:25:41.557Z,1411507541.557 [Radio_Surface](INFO): Powering up
2014-09-23T21:25:41.564Z,1411507541.564 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 863
2014-09-23T21:25:41.575Z,1411507541.575 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-09-23T21:25:41.576Z,1411507541.576 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-09-23T21:25:41.576Z,1411507541.576 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-09-23T21:25:41.576Z,1411507541.576 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-09-23T21:25:41.576Z,1411507541.576 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-09-23T21:25:41.577Z,1411507541.577 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-09-23T21:25:41.577Z,1411507541.577 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-09-23T21:25:41.577Z,1411507541.577 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-09-23T21:25:41.577Z,1411507541.577 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-09-23T21:25:41.798Z,1411507541.798 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-09-23T21:25:41.836Z,1411507541.836 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-23T21:25:41.862Z,1411507541.862 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2014-09-23T21:25:41.862Z,1411507541.862 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-09-23T21:25:41.986Z,1411507541.986 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-09-23T21:25:41.989Z,1411507541.989 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-09-23T21:25:42.009Z,1411507542.009 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-23T21:25:42.034Z,1411507542.034 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-09-23T21:25:42.044Z,1411507542.044 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-09-23T21:25:42.077Z,1411507542.077 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-09-23T21:25:42.098Z,1411507542.098 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

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

    <Aggregate Id="StartClock">

        <Sequence/>

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

    </Aggregate>

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

    <Aggregate Id="CheckIn">

        <When>
            <Elapsed><Universal:platform_communications/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
            <And><Universal:platform_conversation/><Eq><True/></Eq></And>
            <Or><Elapsed><Universal:platform_communications/></Elapsed><Gt><Units:hour/><Value>1.0</Value></Gt></Or>
        </When>

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

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

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

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

<!-- Do data comms -->

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped drop weight due to communications timeout</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

    <Aggregate Id="UpdateAndReportMinutesSinceMissionStarted">

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

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

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

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2014-09-23T21:25:42.106Z,1411507542.106 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,Aanderaa_O2,rhodamine,AHRS_sp3003D,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-09-23T21:25:42.248Z,1411507542.248 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-09-23T21:25:42.279Z,1411507542.279 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-09-23T21:25:42.279Z,1411507542.279 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-09-23T21:25:42.454Z,1411507542.454 [DVL_micro](INFO): Initializing
2014-09-23T21:25:42.487Z,1411507542.487 [NAL9602](INFO): Powering up NAL9602
2014-09-23T21:25:42.937Z,1411507542.937 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:42.949Z,1411507542.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:42.959Z,1411507542.959 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:42.981Z,1411507542.981 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:43.001Z,1411507543.001 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:43.125Z,1411507543.125 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-09-23T21:25:43.142Z,1411507543.142 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-09-23T21:25:43.164Z,1411507543.164 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-09-23T21:25:43.170Z,1411507543.170 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-09-23T21:25:43.180Z,1411507543.180 [MassServo](DEBUG): Initializing EZServoServo.
2014-09-23T21:25:43.186Z,1411507543.186 [MassServo](DEBUG): Initializing MassServo.
2014-09-23T21:25:43.193Z,1411507543.193 [RudderServo](DEBUG): Initializing EZServoServo.
2014-09-23T21:25:43.210Z,1411507543.210 [RudderServo](DEBUG): Initializing RudderServo.
2014-09-23T21:25:43.215Z,1411507543.215 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-09-23T21:25:43.222Z,1411507543.222 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-09-23T21:25:43.588Z,1411507543.588 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:43.588Z,1411507543.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:43.590Z,1411507543.590 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:43.603Z,1411507543.603 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:43.604Z,1411507543.604 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:44.027Z,1411507544.027 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2014-09-23T21:25:44.027Z,1411507544.027 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2014-09-23T21:25:44.027Z,1411507544.027 [BuoyancyServo] Communications Fault, FailCount= 1
2014-09-23T21:25:44.027Z,1411507544.027 [BuoyancyServo](ERROR): Communications Fault
2014-09-23T21:25:44.245Z,1411507544.245 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2014-09-23T21:25:44.405Z,1411507544.405 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:44.405Z,1411507544.405 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:44.406Z,1411507544.406 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:44.415Z,1411507544.415 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:44.416Z,1411507544.416 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T21:25:44.430Z,1411507544.430 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-09-23T21:25:44.440Z,1411507544.440 [BuoyancyServo](INFO): Powering down
2014-09-23T21:25:45.264Z,1411507545.264 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:45.733Z,1411507545.733 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:46.030Z,1411507546.030 [Aanderaa_O2](INFO): Powering down
2014-09-23T21:25:46.429Z,1411507546.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:46.871Z,1411507546.871 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:47.022Z,1411507547.022 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2014-09-23T21:25:47.022Z,1411507547.022 [BuoyancyServo] No Fault, FailCount= 1
2014-09-23T21:25:47.447Z,1411507547.447 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:47.480Z,1411507547.480 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-09-23T21:25:47.601Z,1411507547.601 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-09-23T21:25:48.297Z,1411507548.297 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:48.874Z,1411507548.874 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:49.339Z,1411507549.339 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:50.052Z,1411507550.052 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:50.526Z,1411507550.526 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:50.965Z,1411507550.965 [AcousticModem_Benthos_ATM900](DEBUG): 
2014-09-23T21:25:51.164Z,1411507551.164 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:51.626Z,1411507551.626 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:52.103Z,1411507552.103 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series
2014-09-23T21:25:52.103Z,1411507552.103 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2014-09-23T21:25:52.104Z,1411507552.104 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.8.9
2014-09-23T21:25:52.135Z,1411507552.135 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:52.687Z,1411507552.687 [AcousticModem_Benthos_ATM900](DEBUG): Sep 23 2014 21:22:48 
2014-09-23T21:25:52.736Z,1411507552.736 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:53.258Z,1411507553.258 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:53.696Z,1411507553.696 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2014-09-23T21:25:53.704Z,1411507553.704 [NAL9602](INFO): NAL9602 initialized
2014-09-23T21:25:53.749Z,1411507553.749 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:54.157Z,1411507554.157 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2014-09-23T21:25:54.158Z,1411507554.158 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2014-09-23T21:25:54.158Z,1411507554.158 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2014-09-23T21:25:54.217Z,1411507554.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:54.638Z,1411507554.638 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2014-09-23T21:25:54.717Z,1411507554.717 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:55.126Z,1411507555.126 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2014-09-23T21:25:55.127Z,1411507555.127 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2014-09-23T21:25:55.179Z,1411507555.179 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:55.596Z,1411507555.596 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2014-09-23T21:25:55.704Z,1411507555.704 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:56.149Z,1411507556.149 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:56.638Z,1411507556.638 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:57.067Z,1411507557.067 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:57.138Z,1411507557.138 [SBIT](IMPORTANT): Beginning Startup BIT
2014-09-23T21:25:57.140Z,1411507557.140 [CBIT](IMPORTANT): Beginning GF scan
2014-09-23T21:25:57.575Z,1411507557.575 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:58.161Z,1411507558.161 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:58.721Z,1411507558.721 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:59.173Z,1411507559.173 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:25:59.746Z,1411507559.746 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:00.274Z,1411507560.274 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:00.871Z,1411507560.871 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:01.425Z,1411507561.425 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:01.877Z,1411507561.877 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:02.345Z,1411507562.345 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:02.886Z,1411507562.886 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:03.432Z,1411507563.432 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:03.932Z,1411507563.932 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:04.401Z,1411507564.401 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:04.918Z,1411507564.918 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:05.383Z,1411507565.383 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:05.855Z,1411507565.855 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:06.385Z,1411507566.385 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:06.858Z,1411507566.858 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:07.366Z,1411507567.366 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:07.867Z,1411507567.867 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:08.348Z,1411507568.348 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:08.812Z,1411507568.812 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:09.285Z,1411507569.285 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:09.814Z,1411507569.814 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:10.316Z,1411507570.316 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:10.410Z,1411507570.410 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.793280
2014-09-23T21:26:10.779Z,1411507570.779 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:11.223Z,1411507571.223 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:11.674Z,1411507571.674 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:12.080Z,1411507572.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:12.550Z,1411507572.550 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:13.454Z,1411507573.454 [BPC1](FAULT): Failed to receive battery data
2014-09-23T21:26:13.454Z,1411507573.454 [BPC1] Communications Fault, FailCount= 1
2014-09-23T21:26:13.454Z,1411507573.454 [BPC1](ERROR): Communications Fault
2014-09-23T21:26:13.460Z,1411507573.460 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:13.596Z,1411507573.596 [CBIT](ERROR): Communications Fault in component: BPC1
2014-09-23T21:26:13.981Z,1411507573.981 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:14.459Z,1411507574.459 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:14.936Z,1411507574.936 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:15.054Z,1411507575.054 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-23T21:26:15.054Z,1411507575.054 [BPC1] No Fault, FailCount= 1
2014-09-23T21:26:15.508Z,1411507575.508 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:16.008Z,1411507576.008 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:16.569Z,1411507576.569 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:17.102Z,1411507577.102 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:17.698Z,1411507577.698 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:18.238Z,1411507578.238 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:18.792Z,1411507578.792 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:19.337Z,1411507579.337 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:19.894Z,1411507579.894 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:20.416Z,1411507580.416 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:20.906Z,1411507580.906 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:21.450Z,1411507581.450 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:21.957Z,1411507581.957 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:22.470Z,1411507582.470 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:23.047Z,1411507583.047 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:23.547Z,1411507583.547 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:24.064Z,1411507584.064 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:24.214Z,1411507584.214 [CBIT](IMPORTANT): No ground fault detected
2014-09-23T21:26:24.597Z,1411507584.597 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:25.105Z,1411507585.105 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:25.710Z,1411507585.710 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:26.218Z,1411507586.218 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:26.712Z,1411507586.712 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:27.157Z,1411507587.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:27.622Z,1411507587.622 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:28.106Z,1411507588.106 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:28.567Z,1411507588.567 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:29.060Z,1411507589.060 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:29.677Z,1411507589.677 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:30.157Z,1411507590.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:30.614Z,1411507590.614 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:31.120Z,1411507591.120 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:31.619Z,1411507591.619 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:32.154Z,1411507592.154 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:32.650Z,1411507592.650 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:33.157Z,1411507593.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:33.624Z,1411507593.624 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:34.080Z,1411507594.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:34.568Z,1411507594.568 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:35.029Z,1411507595.029 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:35.530Z,1411507595.530 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:36.112Z,1411507596.112 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:36.621Z,1411507596.621 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:37.088Z,1411507597.088 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:37.573Z,1411507597.573 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:38.050Z,1411507598.050 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:38.531Z,1411507598.531 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:38.976Z,1411507598.976 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:39.408Z,1411507599.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:40.035Z,1411507600.035 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:40.859Z,1411507600.859 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:41.407Z,1411507601.407 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:41.988Z,1411507601.988 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:42.445Z,1411507602.445 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:42.939Z,1411507602.939 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:43.543Z,1411507603.543 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:43.951Z,1411507603.951 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:44.384Z,1411507604.384 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:44.936Z,1411507604.936 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:45.331Z,1411507605.331 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:45.703Z,1411507605.703 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:46.139Z,1411507606.139 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:46.544Z,1411507606.544 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:46.957Z,1411507606.957 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:47.385Z,1411507607.385 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:47.777Z,1411507607.777 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:48.194Z,1411507608.194 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:48.568Z,1411507608.568 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:48.956Z,1411507608.956 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:49.357Z,1411507609.357 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:49.818Z,1411507609.818 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:50.208Z,1411507610.208 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:50.612Z,1411507610.612 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:51.022Z,1411507611.022 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:51.419Z,1411507611.419 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:51.431Z,1411507611.431 [SBIT](CRITICAL): SBIT FAILED
2014-09-23T21:26:51.448Z,1411507611.448 [CommandLine](FAULT): Scheduling is paused
2014-09-23T21:26:51.813Z,1411507611.813 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:51.818Z,1411507611.818 [MissionManager](IMPORTANT): Started mission Startup
2014-09-23T21:26:51.818Z,1411507611.818 [Startup] Running Loop=1
2014-09-23T21:26:51.818Z,1411507611.818 [Startup](INFO): Aggregate::initialize Startup
2014-09-23T21:26:51.818Z,1411507611.818 [Startup:A.GoToSurface] Running Loop=1
2014-09-23T21:26:51.819Z,1411507611.819 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-09-23T21:26:51.819Z,1411507611.819 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-09-23T21:26:51.820Z,1411507611.820 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-09-23T21:26:51.820Z,1411507611.820 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-09-23T21:26:51.826Z,1411507611.826 [Startup:StartupSatComms] Running Loop=1
2014-09-23T21:26:51.826Z,1411507611.826 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-09-23T21:26:51.826Z,1411507611.826 [Startup:StartupSatComms:A] Running Loop=1
2014-09-23T21:26:52.221Z,1411507612.221 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:52.227Z,1411507612.227 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-09-23T21:26:52.621Z,1411507612.621 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:53.028Z,1411507613.028 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:53.466Z,1411507613.466 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:54.230Z,1411507614.230 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:54.668Z,1411507614.668 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:55.074Z,1411507615.074 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:55.485Z,1411507615.485 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:55.896Z,1411507615.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:56.391Z,1411507616.391 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:56.830Z,1411507616.830 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:57.272Z,1411507617.272 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:57.681Z,1411507617.681 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:58.138Z,1411507618.138 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:58.570Z,1411507618.570 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:58.980Z,1411507618.980 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:59.476Z,1411507619.476 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:26:59.908Z,1411507619.908 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:00.381Z,1411507620.381 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:00.791Z,1411507620.791 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:01.230Z,1411507621.230 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:01.681Z,1411507621.681 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:02.086Z,1411507622.086 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:02.559Z,1411507622.559 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:02.989Z,1411507622.989 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:03.425Z,1411507623.425 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:03.832Z,1411507623.832 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:04.236Z,1411507624.236 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:04.644Z,1411507624.644 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:05.053Z,1411507625.053 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:05.552Z,1411507625.552 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:05.967Z,1411507625.967 [NAL9602](IMPORTANT): GPS fix at: 1411507448.00
2014-09-23T21:27:05.977Z,1411507625.977 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:05.991Z,1411507625.991 [Startup:StartupSatComms:A] Stopped
2014-09-23T21:27:05.991Z,1411507625.991 [Startup:StartupSatComms:B] Running Loop=1
2014-09-23T21:27:06.442Z,1411507626.442 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:06.452Z,1411507626.452 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-09-23T21:27:07.153Z,1411507627.153 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:07.591Z,1411507627.591 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:08.040Z,1411507628.040 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:08.484Z,1411507628.484 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:08.993Z,1411507628.993 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:09.455Z,1411507629.455 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:09.911Z,1411507629.911 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:10.400Z,1411507630.400 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:10.853Z,1411507630.853 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:11.302Z,1411507631.302 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:11.762Z,1411507631.762 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:12.305Z,1411507632.305 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:12.808Z,1411507632.808 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:13.258Z,1411507633.258 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:13.730Z,1411507633.730 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:14.182Z,1411507634.182 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:14.648Z,1411507634.648 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:15.713Z,1411507635.713 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:16.842Z,1411507636.842 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:19.311Z,1411507639.311 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:19.895Z,1411507639.895 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:20.312Z,1411507640.312 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:20.787Z,1411507640.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:21.233Z,1411507641.233 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:21.640Z,1411507641.640 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:22.059Z,1411507642.059 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:22.456Z,1411507642.456 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:22.902Z,1411507642.902 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:23.340Z,1411507643.340 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:23.761Z,1411507643.761 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:24.167Z,1411507644.167 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:24.646Z,1411507644.646 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:25.107Z,1411507645.107 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:25.545Z,1411507645.545 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:26.002Z,1411507646.002 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:26.429Z,1411507646.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:26.827Z,1411507646.827 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:27.250Z,1411507647.250 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:27.660Z,1411507647.660 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:28.061Z,1411507648.061 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:28.461Z,1411507648.461 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:28.862Z,1411507648.862 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:29.334Z,1411507649.334 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:29.742Z,1411507649.742 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:30.161Z,1411507650.161 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:30.565Z,1411507650.565 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=1205, MT Status=1, MTMSN=130
2014-09-23T21:27:30.625Z,1411507650.625 [NAL9602](INFO): Sent 18 bytes from file Logs/20140923T195300/Courier0016.lzma
2014-09-23T21:27:30.625Z,1411507650.625 [NAL9602](INFO): Packets left to send: 0
2014-09-23T21:27:43.575Z,1411507663.575 [NAL9602](INFO): Stored copy of sent data in Logs/20140923T195300/Courier0016.lzma.parts/0000.sbd
2014-09-23T21:27:43.576Z,1411507663.576 [NAL9602](INFO): Completed sending Logs/20140923T195300/Courier0016.lzma
2014-09-23T21:27:43.588Z,1411507663.588 [BPC1](FAULT): Failed to receive battery data
2014-09-23T21:27:43.588Z,1411507663.588 [BPC1] Communications Fault, FailCount= 1
2014-09-23T21:27:43.588Z,1411507663.588 [BPC1](ERROR): Communications Fault
2014-09-23T21:27:43.593Z,1411507663.593 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:43.643Z,1411507663.643 [CBIT](ERROR): Communications Fault in component: BPC1
2014-09-23T21:27:44.079Z,1411507664.079 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-09-23T21:27:44.079Z,1411507664.079 [DVL_micro] Communications Fault, FailCount= 1
2014-09-23T21:27:44.080Z,1411507664.080 [DVL_micro](ERROR): Communications Fault
2014-09-23T21:27:44.347Z,1411507664.347 [NAL9602](INFO): Received command:ibit
2014-09-23T21:27:44.371Z,1411507664.371 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:44.383Z,1411507664.383 [CommandLine](IMPORTANT): got command ibit
2014-09-23T21:27:44.390Z,1411507664.390 [IBIT](IMPORTANT): Beginning Initiated BIT
2014-09-23T21:27:44.391Z,1411507664.391 [IBIT](IMPORTANT): Beginning control surface checks.
2014-09-23T21:27:44.392Z,1411507664.392 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-09-23T21:27:44.392Z,1411507664.392 [CBIT](IMPORTANT): Beginning GF scan
2014-09-23T21:27:44.847Z,1411507664.847 [DVL_micro](INFO): uninitialize:Powering down
2014-09-23T21:27:44.866Z,1411507664.866 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:45.277Z,1411507665.277 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:45.294Z,1411507665.294 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-23T21:27:45.294Z,1411507665.294 [BPC1] No Fault, FailCount= 1
2014-09-23T21:27:45.685Z,1411507665.685 [NAL9602](IMPORTANT): GPS fix at: 1411507487.00
2014-09-23T21:27:45.765Z,1411507665.765 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:45.789Z,1411507665.789 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-23T21:27:45.789Z,1411507665.789 [DVL_micro] No Fault, FailCount= 1
2014-09-23T21:27:46.160Z,1411507666.160 [DVL_micro](INFO): Initializing
2014-09-23T21:27:46.241Z,1411507666.241 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:46.692Z,1411507666.692 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:47.231Z,1411507667.231 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:47.680Z,1411507667.680 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:48.130Z,1411507668.130 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:48.576Z,1411507668.576 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:49.027Z,1411507669.027 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:49.477Z,1411507669.477 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:49.931Z,1411507669.931 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:50.488Z,1411507670.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:50.941Z,1411507670.941 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:51.427Z,1411507671.427 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:51.883Z,1411507671.883 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:52.332Z,1411507672.332 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:52.811Z,1411507672.811 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:53.807Z,1411507673.807 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:54.541Z,1411507674.541 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:57.050Z,1411507677.050 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:57.556Z,1411507677.556 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:58.047Z,1411507678.047 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:58.508Z,1411507678.508 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:58.989Z,1411507678.989 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:59.446Z,1411507679.446 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:27:59.529Z,1411507679.529 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.775684
2014-09-23T21:27:59.530Z,1411507679.530 [IBIT](FAULT): Control surface position failure.
2014-09-23T21:27:59.918Z,1411507679.918 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:00.422Z,1411507680.422 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:00.911Z,1411507680.911 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:01.347Z,1411507681.347 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:01.773Z,1411507681.773 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:02.230Z,1411507682.230 [NAL9602](INFO): SBD MO Status=1, MOMSN=1206, MT Status=0, MTMSN=0
2014-09-23T21:28:02.278Z,1411507682.278 [NAL9602](INFO): Sent 243 bytes from file Logs/20140923T212504/Courier0000.lzma
2014-09-23T21:28:02.279Z,1411507682.279 [NAL9602](INFO): Packets left to send: 0
2014-09-23T21:28:02.280Z,1411507682.280 [NAL9602](INFO): Stored copy of sent data in Logs/20140923T212504/Courier0000.lzma.parts/0000.sbd
2014-09-23T21:28:02.281Z,1411507682.281 [NAL9602](INFO): Completed sending Logs/20140923T212504/Courier0000.lzma
2014-09-23T21:28:02.318Z,1411507682.318 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:03.317Z,1411507683.317 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:05.807Z,1411507685.807 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:06.413Z,1411507686.413 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:06.964Z,1411507686.964 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:07.422Z,1411507687.422 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:07.877Z,1411507687.877 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:08.321Z,1411507688.321 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:08.769Z,1411507688.769 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:09.217Z,1411507689.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:09.693Z,1411507689.693 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:10.174Z,1411507690.174 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:10.616Z,1411507690.616 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:11.091Z,1411507691.091 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:11.570Z,1411507691.570 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:11.657Z,1411507691.657 [CBIT](IMPORTANT): No ground fault detected
2014-09-23T21:28:12.023Z,1411507692.023 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:12.497Z,1411507692.497 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:13.018Z,1411507693.018 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:13.507Z,1411507693.507 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:13.973Z,1411507693.973 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:14.457Z,1411507694.457 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:14.953Z,1411507694.953 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:15.435Z,1411507695.435 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:15.880Z,1411507695.880 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:16.347Z,1411507696.347 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:16.764Z,1411507696.764 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:17.262Z,1411507697.262 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:17.709Z,1411507697.709 [NAL9602](INFO): SBD MO Status=1, MOMSN=1207, MT Status=0, MTMSN=0
2014-09-23T21:28:17.766Z,1411507697.766 [NAL9602](INFO): Sent 18 bytes from file Logs/20140923T195300/Express0017.lzma
2014-09-23T21:28:17.766Z,1411507697.766 [NAL9602](INFO): Packets left to send: 0
2014-09-23T21:28:30.278Z,1411507710.278 [NAL9602](INFO): Stored copy of sent data in Logs/20140923T195300/Express0017.lzma.parts/0000.sbd
2014-09-23T21:28:30.278Z,1411507710.278 [NAL9602](INFO): Completed sending Logs/20140923T195300/Express0017.lzma
2014-09-23T21:28:30.370Z,1411507710.370 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:30.941Z,1411507710.941 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-09-23T21:28:30.941Z,1411507710.941 [DVL_micro] Communications Fault, FailCount= 1
2014-09-23T21:28:30.941Z,1411507710.941 [DVL_micro](ERROR): Communications Fault
2014-09-23T21:28:31.323Z,1411507711.323 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:31.606Z,1411507711.606 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802422 Longitude: -121.787682
2014-09-23T21:28:31.607Z,1411507711.607 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-09-23T21:28:32.064Z,1411507712.064 [DVL_micro](INFO): uninitialize:Powering down
2014-09-23T21:28:34.127Z,1411507714.127 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:34.159Z,1411507714.159 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2014-09-23T21:28:34.563Z,1411507714.563 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:34.579Z,1411507714.579 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2014-09-23T21:28:34.580Z,1411507714.580 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2014-09-23T21:28:34.580Z,1411507714.580 [IBIT](IMPORTANT): Pressure:11.265132 PSI
2014-09-23T21:28:34.581Z,1411507714.581 [IBIT](IMPORTANT): Humidity:38.501091 %
2014-09-23T21:28:35.020Z,1411507715.020 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:35.052Z,1411507715.052 [IBIT](IMPORTANT): surfaceThreshold: 0.500000 m
2014-09-23T21:28:35.073Z,1411507715.073 [IBIT](IMPORTANT): buoyancyNeutral: 350.000000 cc
2014-09-23T21:28:35.073Z,1411507715.073 [IBIT](IMPORTANT): massDefault: 1.500000 cm
2014-09-23T21:28:35.073Z,1411507715.073 [IBIT](IMPORTANT): stopDepth: 112.000000 m
2014-09-23T21:28:35.074Z,1411507715.074 [IBIT](IMPORTANT): abortDepth: 130.000000 m
2014-09-23T21:28:35.074Z,1411507715.074 [IBIT](IMPORTANT): IBIT FAILED
2014-09-23T21:28:35.075Z,1411507715.075 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-23T21:28:35.075Z,1411507715.075 [DVL_micro] No Fault, FailCount= 1
2014-09-23T21:28:35.494Z,1411507715.494 [DVL_micro](INFO): Initializing
2014-09-23T21:28:35.546Z,1411507715.546 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:35.555Z,1411507715.555 [Startup:StartupSatComms:B](INFO): Timed out from 2014-09-23T21:27:05.0Z
2014-09-23T21:28:35.555Z,1411507715.555 [Startup:StartupSatComms:B] Stopped
2014-09-23T21:28:35.555Z,1411507715.555 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2014-09-23T21:28:35.555Z,1411507715.555 [Startup:StartupSatComms] Stopped
2014-09-23T21:28:35.556Z,1411507715.556 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-09-23T21:28:35.556Z,1411507715.556 [Startup](INFO): Completed Startup
2014-09-23T21:28:35.556Z,1411507715.556 [Startup] Stopped
2014-09-23T21:28:35.557Z,1411507715.557 [Startup](INFO): Aggregate::uninitialize Startup
2014-09-23T21:28:35.557Z,1411507715.557 [Startup:A.GoToSurface] Stopped
2014-09-23T21:28:35.557Z,1411507715.557 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-09-23T21:28:35.960Z,1411507715.960 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:35.969Z,1411507715.969 [MissionManager](IMPORTANT): Started mission Default
2014-09-23T21:28:35.969Z,1411507715.969 [Default] Running Loop=1
2014-09-23T21:28:35.969Z,1411507715.969 [Default](INFO): Aggregate::initialize Default
2014-09-23T21:28:35.970Z,1411507715.970 [Default:B.GoToSurface] Running Loop=1
2014-09-23T21:28:35.970Z,1411507715.970 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-09-23T21:28:35.970Z,1411507715.970 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-09-23T21:28:35.970Z,1411507715.970 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-09-23T21:28:35.971Z,1411507715.971 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-09-23T21:28:35.971Z,1411507715.971 [Default:StartClock] Running Loop=1
2014-09-23T21:28:35.971Z,1411507715.971 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock
2014-09-23T21:28:35.971Z,1411507715.971 [Default:StartClock:A] Running Loop=1
2014-09-23T21:28:35.972Z,1411507715.972 [Default:CheckIn] Running Loop=1
2014-09-23T21:28:35.972Z,1411507715.972 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2014-09-23T21:28:35.972Z,1411507715.972 [Default:CheckIn:A.SetSpeed] Running Loop=1
2014-09-23T21:28:35.972Z,1411507715.972 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2014-09-23T21:28:35.972Z,1411507715.972 [Default:CheckIn:Read_GPS] Running Loop=1
2014-09-23T21:28:35.973Z,1411507715.973 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-09-23T21:28:35.975Z,1411507715.975 [Default:CheckIn:A.SetSpeed] Running Loop=1
2014-09-23T21:28:36.018Z,1411507716.018 [Default:StartClock:A] Stopped
2014-09-23T21:28:36.024Z,1411507716.024 [Default:StartClock](INFO): Completed Default:StartClock
2014-09-23T21:28:36.025Z,1411507716.025 [Default:StartClock] Stopped
2014-09-23T21:28:36.025Z,1411507716.025 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock
2014-09-23T21:28:36.430Z,1411507716.430 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:36.461Z,1411507716.461 [Default:WaitAtTheSurface] Running Loop=1
2014-09-23T21:28:36.461Z,1411507716.461 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2014-09-23T21:28:36.461Z,1411507716.461 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2014-09-23T21:28:36.461Z,1411507716.461 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2014-09-23T21:28:36.824Z,1411507716.824 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:36.833Z,1411507716.833 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2014-09-23T21:28:37.320Z,1411507717.320 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:37.724Z,1411507717.724 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:38.137Z,1411507718.137 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:38.561Z,1411507718.561 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:38.998Z,1411507718.998 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:39.423Z,1411507719.423 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:39.829Z,1411507719.829 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:40.271Z,1411507720.271 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:40.669Z,1411507720.669 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:41.076Z,1411507721.076 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:41.479Z,1411507721.479 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:41.938Z,1411507721.938 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:42.359Z,1411507722.359 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:42.776Z,1411507722.776 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:43.177Z,1411507723.177 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:43.583Z,1411507723.583 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:43.987Z,1411507723.987 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:44.391Z,1411507724.391 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:44.800Z,1411507724.800 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:45.260Z,1411507725.260 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:45.691Z,1411507725.691 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:46.089Z,1411507726.089 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:46.498Z,1411507726.498 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:46.896Z,1411507726.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:47.304Z,1411507727.304 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:47.698Z,1411507727.698 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-09-23T21:28:47.698Z,1411507727.698 [DVL_micro] Data Fault, FailCount= 1
2014-09-23T21:28:47.698Z,1411507727.698 [DVL_micro](ERROR): Data Fault
2014-09-23T21:28:47.757Z,1411507727.757 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:47.775Z,1411507727.775 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-09-23T21:28:48.209Z,1411507728.209 [DVL_micro](INFO): uninitialize:Powering down
2014-09-23T21:28:48.221Z,1411507728.221 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:48.618Z,1411507728.618 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:49.026Z,1411507729.026 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:49.044Z,1411507729.044 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-23T21:28:49.044Z,1411507729.044 [DVL_micro] No Fault, FailCount= 1
2014-09-23T21:28:49.435Z,1411507729.435 [DVL_micro](INFO): Initializing
2014-09-23T21:28:49.447Z,1411507729.447 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:49.864Z,1411507729.864 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:50.272Z,1411507730.272 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:50.664Z,1411507730.664 [BPC1](FAULT): Failed to receive battery data
2014-09-23T21:28:50.664Z,1411507730.664 [BPC1] Communications Fault, FailCount= 1
2014-09-23T21:28:50.664Z,1411507730.664 [BPC1](ERROR): Communications Fault
2014-09-23T21:28:50.670Z,1411507730.670 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:50.688Z,1411507730.688 [CBIT](ERROR): Communications Fault in component: BPC1
2014-09-23T21:28:51.078Z,1411507731.078 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:51.552Z,1411507731.552 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:51.974Z,1411507731.974 [NAL9602](INFO): SBD MO Status=2, MOMSN=1208, MT Status=2, MTMSN=0
2014-09-23T21:28:51.974Z,1411507731.974 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2014-09-23T21:28:51.984Z,1411507731.984 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:52.002Z,1411507732.002 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-23T21:28:52.002Z,1411507732.002 [BPC1] No Fault, FailCount= 1
2014-09-23T21:28:52.444Z,1411507732.444 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:52.885Z,1411507732.885 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:53.278Z,1411507733.278 [NAL9602](IMPORTANT): GPS fix at: 1411507555.00
2014-09-23T21:28:53.289Z,1411507733.289 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:53.300Z,1411507733.300 [Default:CheckIn:Read_GPS] Stopped
2014-09-23T21:28:53.300Z,1411507733.300 [Default:CheckIn:Read_Iridium] Running Loop=1
2014-09-23T21:28:53.713Z,1411507733.713 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:53.739Z,1411507733.739 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-09-23T21:28:54.378Z,1411507734.378 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:54.799Z,1411507734.799 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:55.194Z,1411507735.194 [AcousticModem_Benthos_ATM900](DEBUG): sending wake-up to local modem
2014-09-23T21:28:55.243Z,1411507735.243 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:55.631Z,1411507735.631 [AcousticModem_Benthos_ATM900](DEBUG): confirming wake-up of local modem
2014-09-23T21:28:55.710Z,1411507735.710 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:56.613Z,1411507736.613 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:57.150Z,1411507737.150 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:57.585Z,1411507737.585 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:58.008Z,1411507738.008 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:58.464Z,1411507738.464 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:58.913Z,1411507738.913 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:28:59.316Z,1411507739.316 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:00.245Z,1411507740.245 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:00.793Z,1411507740.793 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:01.182Z,1411507741.182 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:01.578Z,1411507741.578 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:02.059Z,1411507742.059 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:02.471Z,1411507742.471 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:02.871Z,1411507742.871 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:03.272Z,1411507743.272 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:03.686Z,1411507743.686 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:04.098Z,1411507744.098 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:04.499Z,1411507744.499 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:04.972Z,1411507744.972 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:05.414Z,1411507745.414 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:05.860Z,1411507745.860 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:06.254Z,1411507746.254 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:06.698Z,1411507746.698 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:07.140Z,1411507747.140 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:07.544Z,1411507747.544 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:07.949Z,1411507747.949 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:08.886Z,1411507748.886 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:09.436Z,1411507749.436 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:09.810Z,1411507749.810 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:10.246Z,1411507750.246 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:10.712Z,1411507750.712 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:11.117Z,1411507751.117 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:12.099Z,1411507752.099 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:13.019Z,1411507753.019 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:13.564Z,1411507753.564 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:13.965Z,1411507753.965 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003707
2014-09-23T21:29:13.998Z,1411507753.998 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:14.508Z,1411507754.508 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:14.908Z,1411507754.908 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:15.826Z,1411507755.826 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:16.248Z,1411507756.248 [DataOverHttps](INFO): Sending 57 bytes from file Logs/20140923T212504/Courier0004.lzma
2014-09-23T21:29:16.271Z,1411507756.271 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:16.665Z,1411507756.665 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:19.767Z,1411507759.767 [DataOverHttps](INFO): Moved sent file to Logs/20140923T212504/Courier0004.lzma.bak
2014-09-23T21:29:19.767Z,1411507759.767 [DataOverHttps](INFO): SBD MOMSN=1195962
2014-09-23T21:29:19.846Z,1411507759.846 [NAL9602](INFO): Powering down
2014-09-23T21:29:19.906Z,1411507759.906 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:20.614Z,1411507760.614 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:21.043Z,1411507761.043 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:21.922Z,1411507761.922 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:22.345Z,1411507762.345 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:22.779Z,1411507762.779 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:23.258Z,1411507763.258 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:23.655Z,1411507763.655 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:24.065Z,1411507764.065 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:24.930Z,1411507764.930 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:25.524Z,1411507765.524 [DataOverHttps](INFO): Sending 761 bytes from file Logs/20140923T212504/Express0001.lzma
2014-09-23T21:29:25.541Z,1411507765.541 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:25.948Z,1411507765.948 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:26.349Z,1411507766.349 [DataOverHttps](INFO): Moved sent file to Logs/20140923T212504/Express0001.lzma.bak
2014-09-23T21:29:26.349Z,1411507766.349 [DataOverHttps](INFO): SBD MOMSN=1195964
2014-09-23T21:29:26.437Z,1411507766.437 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:27.060Z,1411507767.060 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:27.471Z,1411507767.471 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:28.330Z,1411507768.330 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:28.744Z,1411507768.744 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:29.165Z,1411507769.165 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:29.632Z,1411507769.632 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:30.078Z,1411507770.078 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:30.551Z,1411507770.551 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:31.851Z,1411507771.851 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:32.297Z,1411507772.297 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:32.762Z,1411507772.762 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:33.206Z,1411507773.206 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:33.604Z,1411507773.604 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:34.017Z,1411507774.017 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:34.948Z,1411507774.948 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:35.359Z,1411507775.359 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:35.851Z,1411507775.851 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:36.262Z,1411507776.262 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:36.699Z,1411507776.699 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:37.144Z,1411507777.144 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:38.001Z,1411507778.001 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:38.396Z,1411507778.396 [DataOverHttps](INFO): Sending 789 bytes from file Logs/20140923T212504/Express0005.lzma
2014-09-23T21:29:38.413Z,1411507778.413 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:38.863Z,1411507778.863 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:39.278Z,1411507779.278 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:39.693Z,1411507779.693 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:40.086Z,1411507780.086 [DataOverHttps](INFO): Moved sent file to Logs/20140923T212504/Express0005.lzma.bak
2014-09-23T21:29:40.087Z,1411507780.087 [DataOverHttps](INFO): SBD MOMSN=1195979
2014-09-23T21:29:40.120Z,1411507780.120 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:40.754Z,1411507780.754 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:40.763Z,1411507780.763 [Default:CheckIn:Read_Iridium] Stopped
2014-09-23T21:29:40.763Z,1411507780.763 [Default:CheckIn](INFO): Completed Default:CheckIn
2014-09-23T21:29:40.764Z,1411507780.764 [Default:CheckIn] Stopped
2014-09-23T21:29:40.764Z,1411507780.764 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2014-09-23T21:29:40.764Z,1411507780.764 [Default:CheckIn:A.SetSpeed] Stopped
2014-09-23T21:29:40.764Z,1411507780.764 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2014-09-23T21:29:41.171Z,1411507781.171 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:46.743Z,1411507786.743 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:51.289Z,1411507791.289 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:29:56.228Z,1411507796.228 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:01.230Z,1411507801.230 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:06.259Z,1411507806.259 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:11.252Z,1411507811.252 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:16.282Z,1411507816.282 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:21.217Z,1411507821.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:26.238Z,1411507826.238 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:31.285Z,1411507831.285 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:36.241Z,1411507836.241 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:41.241Z,1411507841.241 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:46.243Z,1411507846.243 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:46.252Z,1411507846.252 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2014-09-23T21:30:46.253Z,1411507846.253 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2014-09-23T21:30:46.253Z,1411507846.253 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2014-09-23T21:30:46.256Z,1411507846.256 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2014-09-23T21:30:46.256Z,1411507846.256 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2014-09-23T21:30:51.749Z,1411507851.749 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:30:51.760Z,1411507851.760 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 2.170129 min 
2014-09-23T21:30:51.762Z,1411507851.762 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2014-09-23T21:30:51.762Z,1411507851.762 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2014-09-23T21:30:51.762Z,1411507851.762 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2014-09-23T21:30:51.762Z,1411507851.762 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2014-09-23T21:30:56.282Z,1411507856.282 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:01.203Z,1411507861.203 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:06.304Z,1411507866.304 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:11.255Z,1411507871.255 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:16.226Z,1411507876.226 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:21.245Z,1411507881.245 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:26.229Z,1411507886.229 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:31.249Z,1411507891.249 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:36.274Z,1411507896.274 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:41.208Z,1411507901.208 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:46.236Z,1411507906.236 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:51.238Z,1411507911.238 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:56.744Z,1411507916.744 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-09-23T21:31:56.745Z,1411507916.745 [DVL_micro] Data Fault, FailCount= 1
2014-09-23T21:31:56.745Z,1411507916.745 [DVL_micro](ERROR): Data Fault
2014-09-23T21:31:56.770Z,1411507916.770 [BPC1](FAULT): Failed to receive battery data
2014-09-23T21:31:56.770Z,1411507916.770 [BPC1] Communications Fault, FailCount= 1
2014-09-23T21:31:56.770Z,1411507916.770 [BPC1](ERROR): Communications Fault
2014-09-23T21:31:56.776Z,1411507916.776 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:31:56.794Z,1411507916.794 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-09-23T21:31:56.794Z,1411507916.794 [CBIT](ERROR): Communications Fault in component: BPC1
2014-09-23T21:32:01.266Z,1411507921.266 [DVL_micro](INFO): uninitialize:Powering down
2014-09-23T21:32:01.289Z,1411507921.289 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:32:06.212Z,1411507926.212 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:32:11.216Z,1411507931.216 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:32:11.234Z,1411507931.234 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-23T21:32:11.234Z,1411507931.234 [DVL_micro] No Fault, FailCount= 1
2014-09-23T21:32:11.235Z,1411507931.235 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-23T21:32:11.235Z,1411507931.235 [BPC1] No Fault, FailCount= 1
2014-09-23T21:32:16.196Z,1411507936.196 [DVL_micro](INFO): Initializing
2014-09-23T21:32:16.280Z,1411507936.280 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:32:21.218Z,1411507941.218 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:32:26.225Z,1411507946.225 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:32:31.214Z,1411507951.214 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:32:36.284Z,1411507956.284 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:32:41.198Z,1411507961.198 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:32:46.279Z,1411507966.279 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:32:51.197Z,1411507971.197 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:32:56.283Z,1411507976.283 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:01.707Z,1411507981.707 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:06.246Z,1411507986.246 [BPC1](DEBUG): Received data from all battery sticks
2014-09-23T21:33:06.253Z,1411507986.253 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:11.233Z,1411507991.233 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:16.358Z,1411507996.358 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:21.200Z,1411508001.200 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:26.285Z,1411508006.285 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:31.195Z,1411508011.195 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:36.249Z,1411508016.249 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:41.239Z,1411508021.239 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:46.268Z,1411508026.268 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:51.211Z,1411508031.211 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:33:56.296Z,1411508036.296 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:01.663Z,1411508041.663 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:06.272Z,1411508046.272 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:11.207Z,1411508051.207 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:16.187Z,1411508056.187 [DataOverHttps](IMPORTANT): SBD MTMSN=20140923T213110
2014-09-23T21:34:16.275Z,1411508056.275 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:21.236Z,1411508061.236 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:26.237Z,1411508066.237 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:31.706Z,1411508071.706 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:36.250Z,1411508076.250 [DataOverHttps](INFO): Received command:ibit
2014-09-23T21:34:36.303Z,1411508076.303 [CommandLine](IMPORTANT): got command ibit
2014-09-23T21:34:36.370Z,1411508076.371 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:36.408Z,1411508076.408 [IBIT](IMPORTANT): Beginning Initiated BIT
2014-09-23T21:34:36.408Z,1411508076.408 [IBIT](IMPORTANT): Beginning control surface checks.
2014-09-23T21:34:36.410Z,1411508076.410 [CBIT](IMPORTANT): Beginning GF scan
2014-09-23T21:34:41.202Z,1411508081.202 [NAL9602](INFO): Powering up
2014-09-23T21:34:41.226Z,1411508081.226 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:41.665Z,1411508081.665 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:42.043Z,1411508082.043 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:42.488Z,1411508082.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:42.923Z,1411508082.923 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:43.430Z,1411508083.430 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:43.896Z,1411508083.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:44.358Z,1411508084.358 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:44.803Z,1411508084.803 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:45.256Z,1411508085.256 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:45.709Z,1411508085.709 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:46.225Z,1411508086.225 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:46.779Z,1411508086.779 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:47.259Z,1411508087.259 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:47.742Z,1411508087.742 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:48.184Z,1411508088.184 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:48.635Z,1411508088.635 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:49.083Z,1411508089.083 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:49.528Z,1411508089.528 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:49.984Z,1411508089.984 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:50.460Z,1411508090.460 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:50.948Z,1411508090.948 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:51.400Z,1411508091.400 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:51.485Z,1411508091.485 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.795479
2014-09-23T21:34:51.485Z,1411508091.485 [IBIT](FAULT): Control surface position failure.
2014-09-23T21:34:51.885Z,1411508091.885 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:52.350Z,1411508092.350 [NAL9602](INFO): NAL9602 initialized
2014-09-23T21:34:52.360Z,1411508092.360 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:52.838Z,1411508092.838 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:53.283Z,1411508093.283 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:53.757Z,1411508093.757 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:54.262Z,1411508094.262 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:54.747Z,1411508094.747 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:55.216Z,1411508095.216 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:55.671Z,1411508095.671 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:34:55.693Z,1411508095.693 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:56.115Z,1411508096.115 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:34:56.138Z,1411508096.138 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:56.564Z,1411508096.564 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:34:56.631Z,1411508096.631 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:57.082Z,1411508097.082 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:34:57.162Z,1411508097.162 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:57.614Z,1411508097.614 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:34:57.682Z,1411508097.682 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:58.138Z,1411508098.138 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:34:58.189Z,1411508098.189 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:58.715Z,1411508098.715 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:34:58.741Z,1411508098.741 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:59.137Z,1411508099.137 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:34:59.149Z,1411508099.149 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:34:59.573Z,1411508099.573 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:34:59.602Z,1411508099.602 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:00.034Z,1411508100.034 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:00.067Z,1411508100.067 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:00.531Z,1411508100.531 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:00.623Z,1411508100.623 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:01.071Z,1411508101.071 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:01.094Z,1411508101.094 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:01.532Z,1411508101.532 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:01.545Z,1411508101.545 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:01.976Z,1411508101.976 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:01.995Z,1411508101.995 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:02.434Z,1411508102.434 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:02.457Z,1411508102.457 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:02.898Z,1411508102.898 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:02.927Z,1411508102.927 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:03.034Z,1411508103.034 [CBIT](IMPORTANT): No ground fault detected
2014-09-23T21:35:03.419Z,1411508103.419 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:03.460Z,1411508103.460 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:03.936Z,1411508103.936 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:03.980Z,1411508103.980 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:04.428Z,1411508104.428 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:04.441Z,1411508104.441 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:04.915Z,1411508104.915 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:05.370Z,1411508105.370 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:05.836Z,1411508105.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:06.321Z,1411508106.321 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:06.785Z,1411508106.785 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:07.311Z,1411508107.311 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:07.773Z,1411508107.773 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:08.233Z,1411508108.233 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:08.692Z,1411508108.692 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:09.145Z,1411508109.145 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:09.674Z,1411508109.674 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:10.175Z,1411508110.175 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:10.696Z,1411508110.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:11.219Z,1411508111.219 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:11.757Z,1411508111.757 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:12.239Z,1411508112.239 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:12.720Z,1411508112.720 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:13.206Z,1411508113.206 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:13.229Z,1411508113.229 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:13.627Z,1411508113.627 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:13.640Z,1411508113.640 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:14.040Z,1411508114.039 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:14.098Z,1411508114.098 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:14.492Z,1411508114.492 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:14.516Z,1411508114.516 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:14.917Z,1411508114.917 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:14.958Z,1411508114.958 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:15.342Z,1411508115.342 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:15.371Z,1411508115.371 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:15.766Z,1411508115.766 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:15.777Z,1411508115.777 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:16.179Z,1411508116.179 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:16.203Z,1411508116.203 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:16.579Z,1411508116.579 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:16.605Z,1411508116.605 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:16.984Z,1411508116.984 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:17.023Z,1411508117.023 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:17.413Z,1411508117.413 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:17.482Z,1411508117.482 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:17.861Z,1411508117.861 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:17.905Z,1411508117.905 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:18.282Z,1411508118.282 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:18.309Z,1411508118.309 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:18.700Z,1411508118.700 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:18.712Z,1411508118.712 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:19.116Z,1411508119.116 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-23T21:35:19.126Z,1411508119.126 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:19.563Z,1411508119.563 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:19.987Z,1411508119.987 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:20.418Z,1411508120.418 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:20.911Z,1411508120.911 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:21.328Z,1411508121.328 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:21.730Z,1411508121.730 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:22.622Z,1411508122.622 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:23.060Z,1411508123.060 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:23.483Z,1411508123.483 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:23.940Z,1411508123.940 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:24.389Z,1411508124.389 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:24.809Z,1411508124.809 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:25.232Z,1411508125.232 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:25.643Z,1411508125.643 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:26.080Z,1411508126.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:26.486Z,1411508126.486 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:26.973Z,1411508126.973 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:27.422Z,1411508127.422 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:27.817Z,1411508127.817 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:28.220Z,1411508128.220 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:28.623Z,1411508128.623 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:29.021Z,1411508129.021 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:29.440Z,1411508129.440 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:29.879Z,1411508129.879 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:30.340Z,1411508130.340 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:30.730Z,1411508130.730 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:31.128Z,1411508131.128 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:31.531Z,1411508131.531 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:31.926Z,1411508131.926 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:32.359Z,1411508132.359 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:32.761Z,1411508132.761 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:33.170Z,1411508133.170 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:33.628Z,1411508133.628 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:34.039Z,1411508134.039 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:34.452Z,1411508134.452 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:34.864Z,1411508134.864 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:35.285Z,1411508135.285 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:35.702Z,1411508135.702 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:36.131Z,1411508136.131 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:36.547Z,1411508136.547 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-09-23T21:35:36.547Z,1411508136.547 [DVL_micro] Data Fault, FailCount= 1
2014-09-23T21:35:36.547Z,1411508136.547 [DVL_micro](ERROR): Data Fault
2014-09-23T21:35:36.592Z,1411508136.592 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:36.621Z,1411508136.621 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-09-23T21:35:37.062Z,1411508137.062 [DVL_micro](INFO): uninitialize:Powering down
2014-09-23T21:35:37.080Z,1411508137.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:37.481Z,1411508137.481 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:37.880Z,1411508137.880 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:37.896Z,1411508137.896 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-23T21:35:37.896Z,1411508137.896 [DVL_micro] No Fault, FailCount= 1
2014-09-23T21:35:38.279Z,1411508138.279 [DVL_micro](INFO): Initializing
2014-09-23T21:35:38.310Z,1411508138.310 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:38.751Z,1411508138.751 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:39.176Z,1411508139.176 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:39.637Z,1411508139.637 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:40.038Z,1411508140.038 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:40.482Z,1411508140.482 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:40.859Z,1411508140.859 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:41.308Z,1411508141.308 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:41.757Z,1411508141.757 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:42.168Z,1411508142.168 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:42.567Z,1411508142.567 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:42.992Z,1411508142.992 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:43.397Z,1411508143.397 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:43.799Z,1411508143.799 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:44.239Z,1411508144.239 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:44.696Z,1411508144.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:45.125Z,1411508145.125 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:45.545Z,1411508145.545 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:45.945Z,1411508145.945 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:46.347Z,1411508146.347 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:46.751Z,1411508146.751 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:47.151Z,1411508147.151 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:47.569Z,1411508147.569 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:48.017Z,1411508148.017 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:48.454Z,1411508148.454 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:48.854Z,1411508148.854 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:49.227Z,1411508149.227 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:49.640Z,1411508149.640 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:50.030Z,1411508150.030 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:50.461Z,1411508150.461 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:50.878Z,1411508150.878 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:51.335Z,1411508151.335 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:51.742Z,1411508151.742 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:52.197Z,1411508152.197 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:52.624Z,1411508152.624 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:53.028Z,1411508153.028 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:53.486Z,1411508153.486 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:53.939Z,1411508153.939 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:54.399Z,1411508154.399 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:54.841Z,1411508154.841 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:55.238Z,1411508155.238 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:55.690Z,1411508155.690 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:56.125Z,1411508156.125 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:56.507Z,1411508156.507 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:56.933Z,1411508156.933 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:57.424Z,1411508157.424 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:57.835Z,1411508157.835 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:58.247Z,1411508158.247 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:58.650Z,1411508158.650 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:59.047Z,1411508159.047 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:59.475Z,1411508159.475 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:35:59.900Z,1411508159.900 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:00.339Z,1411508160.339 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:00.761Z,1411508160.761 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:01.193Z,1411508161.193 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:01.594Z,1411508161.594 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:01.995Z,1411508161.995 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:02.400Z,1411508162.400 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:02.793Z,1411508162.793 [NAL9602](IMPORTANT): GPS fix at: 1411507985.00
2014-09-23T21:36:02.803Z,1411508162.803 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:03.253Z,1411508163.253 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:03.655Z,1411508163.655 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:04.119Z,1411508164.119 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:04.537Z,1411508164.537 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:04.941Z,1411508164.941 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:05.376Z,1411508165.376 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:05.776Z,1411508165.776 [NAL9602](IMPORTANT): GPS fix at: 1411507988.00
2014-09-23T21:36:05.788Z,1411508165.788 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:06.228Z,1411508166.228 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:06.627Z,1411508166.627 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:07.148Z,1411508167.148 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:07.602Z,1411508167.602 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:08.006Z,1411508168.006 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:08.455Z,1411508168.455 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:08.852Z,1411508168.852 [NAL9602](IMPORTANT): GPS fix at: 1411507991.00
2014-09-23T21:36:08.865Z,1411508168.865 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:09.363Z,1411508169.363 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:09.832Z,1411508169.832 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:10.404Z,1411508170.404 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:10.819Z,1411508170.819 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:11.222Z,1411508171.222 [BPC1](FAULT): Failed to receive battery data
2014-09-23T21:36:11.222Z,1411508171.222 [BPC1] Communications Fault, FailCount= 1
2014-09-23T21:36:11.222Z,1411508171.222 [BPC1](ERROR): Communications Fault
2014-09-23T21:36:11.228Z,1411508171.228 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:11.245Z,1411508171.245 [CBIT](ERROR): Communications Fault in component: BPC1
2014-09-23T21:36:11.679Z,1411508171.679 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:12.069Z,1411508172.069 [NAL9602](IMPORTANT): GPS fix at: 1411507994.00
2014-09-23T21:36:12.080Z,1411508172.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:12.524Z,1411508172.524 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:12.542Z,1411508172.542 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-23T21:36:12.542Z,1411508172.542 [BPC1] No Fault, FailCount= 1
2014-09-23T21:36:12.981Z,1411508172.981 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:13.494Z,1411508173.494 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:13.893Z,1411508173.893 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:14.310Z,1411508174.310 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:14.702Z,1411508174.702 [NAL9602](IMPORTANT): GPS fix at: 1411507997.00
2014-09-23T21:36:14.732Z,1411508174.732 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:15.152Z,1411508175.152 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:15.557Z,1411508175.557 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:15.981Z,1411508175.981 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:16.396Z,1411508176.396 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:16.853Z,1411508176.853 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:17.262Z,1411508177.262 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:17.699Z,1411508177.699 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:18.087Z,1411508178.087 [NAL9602](IMPORTANT): GPS fix at: 1411508000.00
2014-09-23T21:36:18.097Z,1411508178.097 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:18.536Z,1411508178.536 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:18.939Z,1411508178.939 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:19.351Z,1411508179.351 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:19.826Z,1411508179.826 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:20.235Z,1411508180.235 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:20.664Z,1411508180.664 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:21.060Z,1411508181.060 [NAL9602](IMPORTANT): GPS fix at: 1411508003.00
2014-09-23T21:36:21.070Z,1411508181.070 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:21.494Z,1411508181.494 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:21.912Z,1411508181.912 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:22.921Z,1411508182.921 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:23.352Z,1411508183.352 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:23.796Z,1411508183.796 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:24.191Z,1411508184.191 [NAL9602](IMPORTANT): GPS fix at: 1411508006.00
2014-09-23T21:36:24.240Z,1411508184.240 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:24.678Z,1411508184.678 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:25.081Z,1411508185.081 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:25.532Z,1411508185.532 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:25.992Z,1411508185.992 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:26.462Z,1411508186.462 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:26.840Z,1411508186.840 [NAL9602](IMPORTANT): GPS fix at: 1411508009.00
2014-09-23T21:36:26.898Z,1411508186.898 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:27.335Z,1411508187.335 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:27.745Z,1411508187.745 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:27.776Z,1411508187.776 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2014-09-23T21:36:28.209Z,1411508188.209 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:28.243Z,1411508188.243 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 355.393982
Voltage: 16.181564
2014-09-23T21:36:28.243Z,1411508188.243 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2014-09-23T21:36:28.243Z,1411508188.243 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2014-09-23T21:36:28.637Z,1411508188.637 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:28.653Z,1411508188.653 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2014-09-23T21:36:28.653Z,1411508188.653 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2014-09-23T21:36:28.654Z,1411508188.654 [IBIT](IMPORTANT): Pressure:11.796142 PSI
2014-09-23T21:36:28.654Z,1411508188.654 [IBIT](IMPORTANT): Humidity:38.711124 %
2014-09-23T21:36:29.065Z,1411508189.065 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:29.097Z,1411508189.097 [IBIT](IMPORTANT): surfaceThreshold: 0.500000 m
2014-09-23T21:36:29.097Z,1411508189.097 [IBIT](IMPORTANT): buoyancyNeutral: 350.000000 cc
2014-09-23T21:36:29.098Z,1411508189.098 [IBIT](IMPORTANT): massDefault: 1.500000 cm
2014-09-23T21:36:29.102Z,1411508189.102 [IBIT](IMPORTANT): stopDepth: 112.000000 m
2014-09-23T21:36:29.103Z,1411508189.103 [IBIT](IMPORTANT): abortDepth: 130.000000 m
2014-09-23T21:36:29.103Z,1411508189.103 [IBIT](IMPORTANT): IBIT FAILED
2014-09-23T21:36:29.502Z,1411508189.502 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:29.511Z,1411508189.511 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2014-09-23T21:36:29.512Z,1411508189.512 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2014-09-23T21:36:29.512Z,1411508189.512 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2014-09-23T21:36:29.512Z,1411508189.512 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2014-09-23T21:36:29.512Z,1411508189.512 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2014-09-23T21:36:29.512Z,1411508189.512 [Default:CheckIn] Running Loop=1
2014-09-23T21:36:29.512Z,1411508189.512 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2014-09-23T21:36:29.513Z,1411508189.513 [Default:CheckIn:A.SetSpeed] Running Loop=1
2014-09-23T21:36:29.513Z,1411508189.513 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2014-09-23T21:36:29.513Z,1411508189.513 [Default:CheckIn:Read_GPS] Running Loop=1
2014-09-23T21:36:29.905Z,1411508189.905 [NAL9602](IMPORTANT): GPS fix at: 1411508012.00
2014-09-23T21:36:29.916Z,1411508189.916 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:29.927Z,1411508189.927 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 7.892285 min 
2014-09-23T21:36:29.927Z,1411508189.927 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2014-09-23T21:36:29.928Z,1411508189.928 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2014-09-23T21:36:29.928Z,1411508189.928 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2014-09-23T21:36:29.928Z,1411508189.928 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2014-09-23T21:36:29.928Z,1411508189.928 [Default:CheckIn:Read_GPS] Stopped
2014-09-23T21:36:29.928Z,1411508189.928 [Default:CheckIn:Read_Iridium] Running Loop=1
2014-09-23T21:36:30.375Z,1411508190.375 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:31.501Z,1411508191.501 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:31.912Z,1411508191.912 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:32.825Z,1411508192.825 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:33.248Z,1411508193.248 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20140923T212504/Courier0008.lzma
2014-09-23T21:36:33.278Z,1411508193.278 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:33.689Z,1411508193.689 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:34.078Z,1411508194.078 [DataOverHttps](INFO): Moved sent file to Logs/20140923T212504/Courier0008.lzma.bak
2014-09-23T21:36:34.078Z,1411508194.078 [DataOverHttps](INFO): SBD MOMSN=1196015
2014-09-23T21:36:34.098Z,1411508194.098 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:34.675Z,1411508194.675 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:35.080Z,1411508195.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:35.998Z,1411508195.998 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:36.456Z,1411508196.456 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:36.876Z,1411508196.876 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:37.270Z,1411508197.270 [NAL9602](INFO): Powering down
2014-09-23T21:36:37.283Z,1411508197.283 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:37.735Z,1411508197.735 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:38.137Z,1411508198.137 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:39.043Z,1411508199.043 [BPC1](FAULT): A bank: No match for serial number:65535 was found in the onboard configuration.
2014-09-23T21:36:39.043Z,1411508199.043 [BPC1](FAULT): Failed to parse bank A battery data
2014-09-23T21:36:39.044Z,1411508199.044 [BPC1] Data Fault, FailCount= 1
2014-09-23T21:36:39.044Z,1411508199.044 [BPC1](ERROR): Data Fault
2014-09-23T21:36:39.049Z,1411508199.049 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:39.100Z,1411508199.100 [CBIT](ERROR): Data Fault in component: BPC1
2014-09-23T21:36:39.469Z,1411508199.469 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20140923T212504/Courier0012.lzma
2014-09-23T21:36:39.499Z,1411508199.499 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:39.898Z,1411508199.898 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:40.288Z,1411508200.288 [DataOverHttps](INFO): Moved sent file to Logs/20140923T212504/Courier0012.lzma.bak
2014-09-23T21:36:40.288Z,1411508200.288 [DataOverHttps](INFO): SBD MOMSN=1196020
2014-09-23T21:36:40.307Z,1411508200.307 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:40.325Z,1411508200.325 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-23T21:36:40.325Z,1411508200.325 [BPC1] No Fault, FailCount= 1
2014-09-23T21:36:40.992Z,1411508200.992 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:41.433Z,1411508201.433 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:42.369Z,1411508202.369 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:42.786Z,1411508202.786 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:43.212Z,1411508203.212 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:43.621Z,1411508203.621 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:44.026Z,1411508204.026 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:44.430Z,1411508204.430 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:45.334Z,1411508205.334 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:45.754Z,1411508205.754 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:46.156Z,1411508206.156 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:46.579Z,1411508206.579 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:46.997Z,1411508206.997 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:47.446Z,1411508207.446 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:48.420Z,1411508208.420 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:48.842Z,1411508208.842 [DataOverHttps](INFO): Sending 904 bytes from file Logs/20140923T212504/Express0009.lzma
2014-09-23T21:36:48.868Z,1411508208.868 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:49.292Z,1411508209.292 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:49.680Z,1411508209.680 [DataOverHttps](INFO): Moved sent file to Logs/20140923T212504/Express0009.lzma.bak
2014-09-23T21:36:49.681Z,1411508209.681 [DataOverHttps](INFO): SBD MOMSN=1196023
2014-09-23T21:36:49.712Z,1411508209.712 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:50.339Z,1411508210.339 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:50.751Z,1411508210.751 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:51.711Z,1411508211.711 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T21:36:52.149Z,1411508212.149 [HFRCMReconstructedInte