2015-02-13T08:34:29.096Z,1423816469.096 [Supervisor](DEBUG): Initializing supervisor.
2015-02-13T08:34:29.098Z,1423816469.098 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-02-13T08:34:29.099Z,1423816469.099 [SyncHandler](INFO): Protected caller Thread ID is 769
2015-02-13T08:34:29.099Z,1423816469.099 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-02-13T08:34:29.100Z,1423816469.100 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-02-13T08:34:29.100Z,1423816469.100 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770
2015-02-13T08:34:29.103Z,1423816469.103 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-02-13T08:34:29.115Z,1423816469.115 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-02-13T08:34:29.116Z,1423816469.116 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-02-13T08:34:29.116Z,1423816469.116 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771
2015-02-13T08:34:29.117Z,1423816469.117 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-02-13T08:34:29.118Z,1423816469.118 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-02-13T08:34:29.119Z,1423816469.119 [logger ThreadHandler](INFO): Protected caller Thread ID is 772
2015-02-13T08:34:29.121Z,1423816469.121 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-02-13T08:34:29.121Z,1423816469.121 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-02-13T08:34:29.126Z,1423816469.126 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-02-13T08:34:29.460Z,1423816469.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-02-13T08:34:29.460Z,1423816469.460 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-02-13T08:34:29.625Z,1423816469.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-02-13T08:34:29.625Z,1423816469.625 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-02-13T08:34:29.736Z,1423816469.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-02-13T08:34:29.737Z,1423816469.737 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-02-13T08:34:29.862Z,1423816469.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-02-13T08:34:29.862Z,1423816469.862 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-02-13T08:34:29.950Z,1423816469.950 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-02-13T08:34:30.113Z,1423816470.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-02-13T08:34:30.114Z,1423816470.114 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-02-13T08:34:30.201Z,1423816470.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-02-13T08:34:30.204Z,1423816470.204 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-02-13T08:34:30.415Z,1423816470.415 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-02-13T08:34:30.416Z,1423816470.416 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-02-13T08:34:30.779Z,1423816470.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-02-13T08:34:30.779Z,1423816470.779 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-02-13T08:34:31.092Z,1423816471.092 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-02-13T08:34:31.093Z,1423816471.093 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-02-13T08:34:31.608Z,1423816471.608 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-02-13T08:34:31.609Z,1423816471.609 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-02-13T08:34:31.850Z,1423816471.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-02-13T08:34:31.851Z,1423816471.851 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-02-13T08:34:31.957Z,1423816471.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-02-13T08:34:31.958Z,1423816471.958 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-02-13T08:34:32.380Z,1423816472.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-02-13T08:34:32.381Z,1423816472.381 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-02-13T08:34:32.495Z,1423816472.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-02-13T08:34:32.497Z,1423816472.497 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2015-02-13T08:34:32.501Z,1423816472.501 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2015-02-13T08:34:32.752Z,1423816472.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-02-13T08:34:32.752Z,1423816472.752 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2015-02-13T08:34:46.408Z,1423816486.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2015-02-13T08:34:47.328Z,1423816487.328 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2015-02-13T08:34:47.427Z,1423816487.427 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2015-02-13T08:34:47.536Z,1423816487.536 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2015-02-13T08:34:47.681Z,1423816487.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2015-02-13T08:34:47.856Z,1423816487.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2015-02-13T08:34:47.959Z,1423816487.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2015-02-13T08:34:48.052Z,1423816488.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2015-02-13T08:34:48.154Z,1423816488.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2015-02-13T08:34:48.252Z,1423816488.252 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2015-02-13T08:34:48.436Z,1423816488.436 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2015-02-13T08:34:48.443Z,1423816488.443 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-02-13T08:34:48.521Z,1423816488.521 [VerticalControl](DEBUG): Construct VerticalControl.
2015-02-13T08:34:48.646Z,1423816488.646 [VerticalControl] Loaded
2015-02-13T08:34:48.646Z,1423816488.646 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-02-13T08:34:48.647Z,1423816488.647 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-02-13T08:34:48.723Z,1423816488.723 [HorizontalControl] Loaded
2015-02-13T08:34:48.723Z,1423816488.723 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-02-13T08:34:48.724Z,1423816488.724 [SpeedControl](DEBUG): Construct SpeedControl.
2015-02-13T08:34:48.730Z,1423816488.730 [SpeedControl] Loaded
2015-02-13T08:34:48.731Z,1423816488.731 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-02-13T08:34:48.731Z,1423816488.731 [LoopControl](DEBUG): Construct LoopControl.
2015-02-13T08:34:48.732Z,1423816488.732 [LoopControl] Loaded
2015-02-13T08:34:48.732Z,1423816488.732 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-02-13T08:34:48.733Z,1423816488.733 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-02-13T08:34:48.733Z,1423816488.733 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-02-13T08:34:48.868Z,1423816488.868 [SBIT](DEBUG): Construct Startup Built In Test.
2015-02-13T08:34:48.880Z,1423816488.880 [SBIT] Loaded
2015-02-13T08:34:48.880Z,1423816488.880 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-02-13T08:34:48.881Z,1423816488.881 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-02-13T08:34:48.911Z,1423816488.911 [IBIT] Loaded
2015-02-13T08:34:48.912Z,1423816488.912 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-02-13T08:34:48.914Z,1423816488.914 [CBIT](DEBUG): Construct CBIT Built In Test.
2015-02-13T08:34:49.052Z,1423816489.052 [CBIT] Loaded
2015-02-13T08:34:49.053Z,1423816489.053 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-02-13T08:34:49.053Z,1423816489.053 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-02-13T08:34:49.054Z,1423816489.054 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-02-13T08:34:49.085Z,1423816489.085 [DepthRateCalculator] Loaded
2015-02-13T08:34:49.085Z,1423816489.085 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-02-13T08:34:49.091Z,1423816489.091 [PitchRateCalculator] Loaded
2015-02-13T08:34:49.091Z,1423816489.091 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-02-13T08:34:49.103Z,1423816489.103 [SpeedCalculator] Loaded
2015-02-13T08:34:49.104Z,1423816489.104 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-02-13T08:34:49.121Z,1423816489.121 [TempGradientCalculator] Loaded
2015-02-13T08:34:49.121Z,1423816489.121 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-02-13T08:34:49.127Z,1423816489.127 [YawRateCalculator] Loaded
2015-02-13T08:34:49.127Z,1423816489.127 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-02-13T08:34:49.128Z,1423816489.128 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-02-13T08:34:49.128Z,1423816489.128 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-02-13T08:34:50.269Z,1423816490.269 [HFRCMReconstructedInterpolator] Loaded
2015-02-13T08:34:50.269Z,1423816490.269 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2015-02-13T08:34:50.270Z,1423816490.270 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-02-13T08:34:50.271Z,1423816490.271 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-02-13T08:34:50.566Z,1423816490.566 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-02-13T08:34:50.567Z,1423816490.567 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-02-13T08:34:50.679Z,1423816490.679 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-02-13T08:34:50.679Z,1423816490.679 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-02-13T08:34:50.727Z,1423816490.727 [DeadReckonUsingSpeedCalculator] Loaded
2015-02-13T08:34:50.728Z,1423816490.728 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-02-13T08:34:50.746Z,1423816490.746 [NavChart] Loaded
2015-02-13T08:34:50.746Z,1423816490.746 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-02-13T08:34:50.751Z,1423816490.751 [UniversalFixResidualReporter] Loaded
2015-02-13T08:34:50.751Z,1423816490.751 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-02-13T08:34:50.752Z,1423816490.752 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-02-13T08:34:50.752Z,1423816490.752 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-02-13T08:34:50.762Z,1423816490.762 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-02-13T08:34:50.762Z,1423816490.762 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-02-13T08:34:50.893Z,1423816490.893 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-02-13T08:34:50.894Z,1423816490.894 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-02-13T08:34:51.556Z,1423816491.556 [DataOverHttps] Loaded
2015-02-13T08:34:51.557Z,1423816491.557 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-02-13T08:34:51.638Z,1423816491.638 [Depth_Keller] Loaded
2015-02-13T08:34:51.638Z,1423816491.638 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-02-13T08:34:51.920Z,1423816491.920 [DVL_micro] Loaded
2015-02-13T08:34:51.921Z,1423816491.921 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2015-02-13T08:34:52.022Z,1423816492.022 [NAL9602] Loaded
2015-02-13T08:34:52.022Z,1423816492.022 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-02-13T08:34:52.067Z,1423816492.067 [Onboard] Loaded
2015-02-13T08:34:52.068Z,1423816492.068 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-02-13T08:34:52.075Z,1423816492.075 [Radio_Surface] Loaded
2015-02-13T08:34:52.075Z,1423816492.075 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-02-13T08:34:52.077Z,1423816492.077 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408074E0
2015-02-13T08:34:52.077Z,1423816492.077 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 854
2015-02-13T08:34:52.127Z,1423816492.127 [PNI_TCM] Loaded
2015-02-13T08:34:52.128Z,1423816492.128 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2015-02-13T08:34:54.141Z,1423816494.141 [BPC1] Loaded
2015-02-13T08:34:54.142Z,1423816494.142 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-02-13T08:34:54.142Z,1423816494.142 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-02-13T08:34:54.143Z,1423816494.143 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-02-13T08:35:07.471Z,1423816507.471 [BuoyancyServo] Loaded
2015-02-13T08:35:07.471Z,1423816507.471 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-02-13T08:35:07.484Z,1423816507.484 [ElevatorServo] Loaded
2015-02-13T08:35:07.484Z,1423816507.484 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-02-13T08:35:07.497Z,1423816507.497 [MassServo] Loaded
2015-02-13T08:35:07.497Z,1423816507.497 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-02-13T08:35:07.509Z,1423816507.509 [RudderServo] Loaded
2015-02-13T08:35:07.510Z,1423816507.510 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-02-13T08:35:07.522Z,1423816507.522 [ThrusterServo] Loaded
2015-02-13T08:35:07.522Z,1423816507.522 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-02-13T08:35:07.523Z,1423816507.523 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-02-13T08:35:07.523Z,1423816507.523 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-02-13T08:35:07.815Z,1423816507.815 [ExternalSim] Loaded
2015-02-13T08:35:07.815Z,1423816507.815 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2015-02-13T08:35:07.816Z,1423816507.816 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-02-13T08:35:07.816Z,1423816507.816 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-02-13T08:35:07.858Z,1423816507.858 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-02-13T08:35:07.860Z,1423816507.860 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-02-13T08:35:07.861Z,1423816507.861 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-02-13T08:35:07.868Z,1423816507.868 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-02-13T08:35:07.869Z,1423816507.869 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408B84E0
2015-02-13T08:35:07.869Z,1423816507.869 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 855
2015-02-13T08:35:07.874Z,1423816507.874 [Supervisor](INFO): Main Thread ID is 767
2015-02-13T08:35:07.874Z,1423816507.874 [Supervisor](DEBUG): Running supervisor.
2015-02-13T08:35:07.875Z,1423816507.875 [CommandLine ThreadHandler](INFO): Handler Thread ID is 856
2015-02-13T08:35:07.878Z,1423816507.878 [controlThread ThreadHandler](INFO): Handler Thread ID is 857
2015-02-13T08:35:07.878Z,1423816507.878 [controlThread](DEBUG): Initializing ControlThread
2015-02-13T08:35:07.879Z,1423816507.879 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-02-13T08:35:07.881Z,1423816507.881 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-02-13T08:35:07.881Z,1423816507.881 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-02-13T08:35:07.882Z,1423816507.882 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-02-13T08:35:07.882Z,1423816507.882 [SBIT](INFO): Initialize SBIT Component.
2015-02-13T08:35:07.883Z,1423816507.883 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12012
2015-02-13T08:35:07.883Z,1423816507.883 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-02-13T08:35:07.883Z,1423816507.883 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-02-13T08:35:07.884Z,1423816507.884 [IBIT](INFO): Initialize IBIT Component.
2015-02-13T08:35:07.885Z,1423816507.885 [CBIT](DEBUG): Initialize CBIT Component.
2015-02-13T08:35:07.885Z,1423816507.885 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2015-02-13T08:35:07.885Z,1423816507.885 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2015-02-13T08:35:07.886Z,1423816507.886 [logger ThreadHandler](INFO): Handler Thread ID is 858
2015-02-13T08:35:07.911Z,1423816507.911 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-02-13T08:35:07.912Z,1423816507.912 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-02-13T08:35:07.912Z,1423816507.912 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-02-13T08:35:07.912Z,1423816507.912 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-02-13T08:35:07.913Z,1423816507.913 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-02-13T08:35:07.914Z,1423816507.914 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2015-02-13T08:35:07.916Z,1423816507.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-02-13T08:35:07.916Z,1423816507.916 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-02-13T08:35:07.917Z,1423816507.917 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-02-13T08:35:07.917Z,1423816507.917 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-02-13T08:35:07.922Z,1423816507.922 [ExternalSim](INFO): ExternalSim initializing...
2015-02-13T08:35:07.932Z,1423816507.932 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 859
2015-02-13T08:35:07.939Z,1423816507.939 [Radio_Surface](INFO): Powering up
2015-02-13T08:35:07.956Z,1423816507.956 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 860
2015-02-13T08:35:07.967Z,1423816507.967 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-02-13T08:35:07.968Z,1423816507.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-02-13T08:35:07.968Z,1423816507.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-02-13T08:35:07.968Z,1423816507.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-02-13T08:35:07.968Z,1423816507.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-02-13T08:35:07.969Z,1423816507.969 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-02-13T08:35:07.969Z,1423816507.969 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-02-13T08:35:07.969Z,1423816507.969 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-02-13T08:35:07.969Z,1423816507.969 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-02-13T08:38:17.287Z,1423816697.287 [ExternalSim](ERROR): Error making socket connection to simulation server at tellum.shore.mbari.org: Could not connect due to error: Connection timed out
2015-02-13T08:38:17.333Z,1423816697.333 [ExternalSim](DEBUG): beaconLat = 36.80340
2015-02-13T08:38:17.333Z,1423816697.333 [ExternalSim](DEBUG): beaconLon = -121.82230
2015-02-13T08:38:17.333Z,1423816697.333 [ExternalSim](DEBUG): beaconDepth = 25.00
2015-02-13T08:38:17.366Z,1423816697.366 [ExternalSim](DEBUG): Simulator initialized
2015-02-13T08:38:17.426Z,1423816697.426 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-02-13T08:38:17.468Z,1423816697.468 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-02-13T08:38:17.493Z,1423816697.493 [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>


2015-02-13T08:38:17.494Z,1423816697.494 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-02-13T08:38:17.578Z,1423816697.578 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2015-02-13T08:38:17.582Z,1423816697.582 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2015-02-13T08:38:17.611Z,1423816697.611 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2015-02-13T08:38:17.621Z,1423816697.621 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2015-02-13T08:38:17.665Z,1423816697.665 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-02-13T08:38:17.674Z,1423816697.674 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2015-02-13T08:38:17.693Z,1423816697.693 [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>


2015-02-13T08:38:17.699Z,1423816697.699 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,DataOverHttps,Depth_Keller,DVL_micro,NAL9602,Onboard,PNI_TCM,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-02-13T08:38:17.868Z,1423816697.868 [DVL_micro](INFO): Initializing
2015-02-13T08:38:17.880Z,1423816697.880 [NAL9602](INFO): Powering up NAL9602
2015-02-13T08:38:18.051Z,1423816698.051 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:18.057Z,1423816698.057 [DeadReckonUsingMultipleVelocitySources](FAULT): The allowance for input measurements to start up has expired, but I still cannot determine the rotation from vehicle frame to navigation frame.
2015-02-13T08:38:18.057Z,1423816698.057 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2015-02-13T08:38:18.057Z,1423816698.057 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-02-13T08:38:18.058Z,1423816698.058 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:38:18.077Z,1423816698.077 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-02-13T08:38:18.091Z,1423816698.091 [DeadReckonUsingSpeedCalculator](FAULT): The allowance for input measurements to start up has expired, but I still cannot determine the rotation from vehicle frame to navigation frame.
2015-02-13T08:38:18.091Z,1423816698.091 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2015-02-13T08:38:18.091Z,1423816698.091 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-02-13T08:38:18.104Z,1423816698.104 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-02-13T08:38:18.220Z,1423816698.220 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-02-13T08:38:18.237Z,1423816698.237 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-02-13T08:38:18.244Z,1423816698.244 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-02-13T08:38:18.250Z,1423816698.250 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-02-13T08:38:18.269Z,1423816698.269 [MassServo](DEBUG): Initializing EZServoServo.
2015-02-13T08:38:18.276Z,1423816698.276 [MassServo](DEBUG): Initializing MassServo.
2015-02-13T08:38:18.282Z,1423816698.282 [RudderServo](DEBUG): Initializing EZServoServo.
2015-02-13T08:38:18.288Z,1423816698.288 [RudderServo](DEBUG): Initializing RudderServo.
2015-02-13T08:38:18.294Z,1423816698.294 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-02-13T08:38:18.300Z,1423816698.300 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-02-13T08:38:18.343Z,1423816698.343 [CommandLine](FAULT): Scheduling is paused
2015-02-13T08:38:18.344Z,1423816698.344 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-02-13T08:38:18.344Z,1423816698.344 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-02-13T08:38:18.515Z,1423816698.515 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:18.534Z,1423816698.534 [SBIT](IMPORTANT): Beginning Startup BIT
2015-02-13T08:38:18.537Z,1423816698.537 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-02-13T08:38:18.537Z,1423816698.537 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2015-02-13T08:38:18.537Z,1423816698.537 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-02-13T08:38:18.537Z,1423816698.537 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2015-02-13T08:38:18.538Z,1423816698.538 [CBIT](IMPORTANT): Beginning GF scan
2015-02-13T08:38:22.431Z,1423816702.431 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:22.432Z,1423816702.432 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-02-13T08:38:22.432Z,1423816702.432 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-02-13T08:38:22.738Z,1423816702.738 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:22.740Z,1423816702.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:38:23.203Z,1423816703.203 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:23.204Z,1423816703.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:38:23.543Z,1423816703.543 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:23.544Z,1423816703.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:38:23.925Z,1423816703.925 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003707
2015-02-13T08:38:23.949Z,1423816703.949 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:23.950Z,1423816703.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:38:24.353Z,1423816704.353 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:24.354Z,1423816704.354 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:38:24.751Z,1423816704.751 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:24.752Z,1423816704.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:38:25.143Z,1423816705.143 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:25.561Z,1423816705.561 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:25.950Z,1423816705.950 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:26.358Z,1423816706.358 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:26.770Z,1423816706.770 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:27.162Z,1423816707.162 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:27.563Z,1423816707.563 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:27.968Z,1423816707.968 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:28.376Z,1423816708.376 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:28.741Z,1423816708.741 [NAL9602](INFO): NAL9602 initialized
2015-02-13T08:38:28.769Z,1423816708.769 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:29.170Z,1423816709.170 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:29.580Z,1423816709.580 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:29.979Z,1423816709.979 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:30.464Z,1423816710.464 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:30.763Z,1423816710.763 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:31.212Z,1423816711.212 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:31.570Z,1423816711.570 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:31.692Z,1423816711.692 [SBIT](FAULT): Mass: EXPECTED:0.006000 ACTUAL:0.003951
2015-02-13T08:38:31.693Z,1423816711.693 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.634922
2015-02-13T08:38:32.003Z,1423816712.003 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:32.452Z,1423816712.452 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:32.767Z,1423816712.767 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2015-02-13T08:38:32.767Z,1423816712.767 [PNI_TCM] Data Fault, FailCount= 1
2015-02-13T08:38:32.768Z,1423816712.768 [PNI_TCM](ERROR): Data Fault
2015-02-13T08:38:32.772Z,1423816712.772 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:32.858Z,1423816712.858 [CBIT](ERROR): Data Fault in component: PNI_TCM
2015-02-13T08:38:32.858Z,1423816712.858 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2015-02-13T08:38:33.178Z,1423816713.178 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:33.581Z,1423816713.581 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:33.961Z,1423816713.961 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:34.379Z,1423816714.379 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:34.812Z,1423816714.812 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:35.173Z,1423816715.173 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:35.572Z,1423816715.572 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:35.971Z,1423816715.971 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:36.354Z,1423816716.354 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:36.774Z,1423816716.774 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:37.166Z,1423816717.166 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:37.569Z,1423816717.569 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:37.967Z,1423816717.967 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:38.368Z,1423816718.368 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:38.770Z,1423816718.770 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:39.173Z,1423816719.173 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:39.578Z,1423816719.578 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:39.975Z,1423816719.975 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:40.367Z,1423816720.367 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:40.753Z,1423816720.753 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:41.186Z,1423816721.186 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:41.555Z,1423816721.555 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:41.951Z,1423816721.951 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:42.367Z,1423816722.367 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:42.752Z,1423816722.752 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:43.153Z,1423816723.153 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:43.597Z,1423816723.597 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:43.982Z,1423816723.982 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:44.392Z,1423816724.392 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:44.751Z,1423816724.751 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:44.905Z,1423816724.905 [CBIT](IMPORTANT): No ground fault detected
2015-02-13T08:38:45.200Z,1423816725.200 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:45.552Z,1423816725.552 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:45.997Z,1423816725.997 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:46.365Z,1423816726.365 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:46.794Z,1423816726.794 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:47.158Z,1423816727.158 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:47.551Z,1423816727.551 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:47.989Z,1423816727.989 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:48.401Z,1423816728.401 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:48.806Z,1423816728.806 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:49.150Z,1423816729.150 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:49.562Z,1423816729.562 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:49.955Z,1423816729.955 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:50.361Z,1423816730.361 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:50.806Z,1423816730.806 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:51.161Z,1423816731.161 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:51.603Z,1423816731.603 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:52.005Z,1423816732.005 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:52.359Z,1423816732.359 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:52.772Z,1423816732.772 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:53.190Z,1423816733.190 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:53.573Z,1423816733.573 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:53.961Z,1423816733.961 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:54.378Z,1423816734.378 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:54.809Z,1423816734.809 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:55.184Z,1423816735.184 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:55.605Z,1423816735.605 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:56.003Z,1423816736.003 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:56.382Z,1423816736.382 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:56.776Z,1423816736.776 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:57.241Z,1423816737.241 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:57.638Z,1423816737.638 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:57.980Z,1423816737.980 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:58.379Z,1423816738.379 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:58.786Z,1423816738.786 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:59.168Z,1423816739.168 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:59.604Z,1423816739.604 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:38:59.961Z,1423816739.961 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:00.424Z,1423816740.424 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:00.796Z,1423816740.796 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:01.288Z,1423816741.288 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:01.673Z,1423816741.673 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:02.069Z,1423816742.069 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2015-02-13T08:39:02.592Z,1423816742.592 [NAL9602](FAULT): received: 
2015-02-13T08:39:02.592Z,1423816742.592 [NAL9602] Data Fault, FailCount= 1
2015-02-13T08:39:02.592Z,1423816742.592 [NAL9602](ERROR): Data Fault
2015-02-13T08:39:02.618Z,1423816742.618 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:02.816Z,1423816742.816 [CBIT](ERROR): Data Fault in component: NAL9602
2015-02-13T08:39:02.824Z,1423816742.824 [NAL9602](INFO): Powering down
2015-02-13T08:39:02.835Z,1423816742.835 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:03.259Z,1423816743.259 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:03.642Z,1423816743.642 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:03.718Z,1423816743.718 [CBIT](INFO): Clearing failed state for component NAL9602
2015-02-13T08:39:03.718Z,1423816743.718 [NAL9602] No Fault, FailCount= 1
2015-02-13T08:39:04.040Z,1423816744.040 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:04.450Z,1423816744.450 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:04.843Z,1423816744.843 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:05.254Z,1423816745.254 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:05.640Z,1423816745.640 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:06.040Z,1423816746.040 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:06.441Z,1423816746.441 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:06.850Z,1423816746.850 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:07.275Z,1423816747.275 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:07.648Z,1423816747.648 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:08.039Z,1423816748.039 [NAL9602](INFO): Powering up NAL9602
2015-02-13T08:39:08.049Z,1423816748.049 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:08.456Z,1423816748.456 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:08.852Z,1423816748.852 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:09.237Z,1423816749.237 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:09.656Z,1423816749.656 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:10.058Z,1423816750.058 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:10.447Z,1423816750.447 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:10.853Z,1423816750.853 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:11.255Z,1423816751.255 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:11.671Z,1423816751.671 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:12.059Z,1423816752.059 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:12.463Z,1423816752.463 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:12.508Z,1423816752.508 [SBIT](CRITICAL): SBIT FAILED
2015-02-13T08:39:12.869Z,1423816752.869 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:12.872Z,1423816752.872 [MissionManager](IMPORTANT): Started mission Startup
2015-02-13T08:39:12.872Z,1423816752.872 [Startup] Running Loop=1
2015-02-13T08:39:12.872Z,1423816752.872 [Startup](INFO): Aggregate::initialize Startup
2015-02-13T08:39:12.872Z,1423816752.872 [Startup:A.GoToSurface] Running Loop=1
2015-02-13T08:39:12.872Z,1423816752.872 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-02-13T08:39:12.873Z,1423816752.873 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-02-13T08:39:12.873Z,1423816752.873 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-02-13T08:39:12.874Z,1423816752.874 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-02-13T08:39:12.880Z,1423816752.880 [Startup:StartupSatComms] Running Loop=1
2015-02-13T08:39:12.880Z,1423816752.880 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2015-02-13T08:39:12.880Z,1423816752.880 [Startup:StartupSatComms:A] Running Loop=1
2015-02-13T08:39:13.260Z,1423816753.260 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:13.279Z,1423816753.279 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-02-13T08:39:13.677Z,1423816753.677 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:14.069Z,1423816754.069 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:14.466Z,1423816754.466 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:14.881Z,1423816754.881 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:15.264Z,1423816755.264 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:15.681Z,1423816755.681 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:16.043Z,1423816756.043 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:16.451Z,1423816756.451 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:16.851Z,1423816756.851 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:17.237Z,1423816757.237 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:17.667Z,1423816757.667 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:18.047Z,1423816758.047 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:18.443Z,1423816758.443 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:18.867Z,1423816758.867 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:22.110Z,1423816762.110 [NAL9602](INFO): NAL9602 initialized
2015-02-13T08:39:22.120Z,1423816762.120 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:22.218Z,1423816762.218 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:22.578Z,1423816762.578 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:22.970Z,1423816762.970 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:23.379Z,1423816763.379 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:23.766Z,1423816763.766 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:24.168Z,1423816764.168 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:24.568Z,1423816764.568 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:24.969Z,1423816764.969 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:25.370Z,1423816765.370 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:25.775Z,1423816765.775 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:26.184Z,1423816766.184 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:26.567Z,1423816766.567 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:26.989Z,1423816766.989 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:27.357Z,1423816767.357 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:27.791Z,1423816767.791 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:28.158Z,1423816768.158 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:28.592Z,1423816768.592 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:28.981Z,1423816768.981 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:29.360Z,1423816769.360 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:29.753Z,1423816769.753 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:30.157Z,1423816770.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:30.559Z,1423816770.559 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:30.955Z,1423816770.955 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:31.352Z,1423816771.352 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:31.757Z,1423816771.757 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:32.156Z,1423816772.156 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:32.558Z,1423816772.558 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:32.958Z,1423816772.958 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:33.358Z,1423816773.358 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:33.760Z,1423816773.760 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:34.153Z,1423816774.153 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:34.560Z,1423816774.560 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:34.953Z,1423816774.953 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:35.354Z,1423816775.354 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:35.758Z,1423816775.758 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:36.159Z,1423816776.159 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:36.560Z,1423816776.560 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:36.960Z,1423816776.960 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:37.356Z,1423816777.356 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:37.757Z,1423816777.757 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:38.158Z,1423816778.158 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:38.559Z,1423816778.559 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:38.959Z,1423816778.959 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:39.360Z,1423816779.360 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:39.805Z,1423816779.805 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:40.157Z,1423816780.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:40.558Z,1423816780.558 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:40.959Z,1423816780.959 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:41.387Z,1423816781.387 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:41.760Z,1423816781.760 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:42.197Z,1423816782.197 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:42.557Z,1423816782.557 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:42.990Z,1423816782.990 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:43.388Z,1423816783.388 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:43.759Z,1423816783.759 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:44.159Z,1423816784.159 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:44.560Z,1423816784.560 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:44.956Z,1423816784.956 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:45.357Z,1423816785.357 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:45.757Z,1423816785.757 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:46.151Z,1423816786.151 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:46.567Z,1423816786.567 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:46.967Z,1423816786.967 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:47.360Z,1423816787.360 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:47.780Z,1423816787.780 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:48.158Z,1423816788.158 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:48.569Z,1423816788.569 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:48.974Z,1423816788.974 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:49.371Z,1423816789.371 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:49.775Z,1423816789.775 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:50.176Z,1423816790.176 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:50.573Z,1423816790.573 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:50.978Z,1423816790.978 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:51.382Z,1423816791.382 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:51.777Z,1423816791.777 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:52.176Z,1423816792.176 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:52.560Z,1423816792.560 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:53.003Z,1423816793.003 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:53.358Z,1423816793.358 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:53.758Z,1423816793.758 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:54.200Z,1423816794.200 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:54.587Z,1423816794.587 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:54.984Z,1423816794.984 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:55.439Z,1423816795.439 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:55.745Z,1423816795.745 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2015-02-13T08:39:56.264Z,1423816796.264 [NAL9602](FAULT): received: 
2015-02-13T08:39:56.264Z,1423816796.264 [NAL9602] Data Fault, FailCount= 2
2015-02-13T08:39:56.264Z,1423816796.264 [NAL9602](ERROR): Data Fault
2015-02-13T08:39:56.278Z,1423816796.278 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:56.291Z,1423816796.291 [CBIT](ERROR): Data Fault in component: NAL9602
2015-02-13T08:39:56.368Z,1423816796.368 [NAL9602](INFO): Powering down
2015-02-13T08:39:56.378Z,1423816796.378 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:56.744Z,1423816796.744 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:57.115Z,1423816797.115 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:57.168Z,1423816797.168 [CBIT](INFO): Clearing failed state for component NAL9602
2015-02-13T08:39:57.168Z,1423816797.168 [NAL9602] No Fault, FailCount= 2
2015-02-13T08:39:57.593Z,1423816797.593 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:57.950Z,1423816797.950 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:58.317Z,1423816798.317 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:58.726Z,1423816798.726 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:59.119Z,1423816799.119 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:59.519Z,1423816799.519 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:39:59.916Z,1423816799.916 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:00.313Z,1423816800.313 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:00.718Z,1423816800.718 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:01.118Z,1423816801.118 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:01.506Z,1423816801.506 [NAL9602](INFO): Powering up NAL9602
2015-02-13T08:40:01.518Z,1423816801.518 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:01.912Z,1423816801.912 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:02.317Z,1423816802.317 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:02.717Z,1423816802.717 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:03.110Z,1423816803.110 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:03.522Z,1423816803.522 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:03.919Z,1423816803.919 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:04.315Z,1423816804.315 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:04.720Z,1423816804.720 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:05.112Z,1423816805.112 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:05.517Z,1423816805.517 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:05.956Z,1423816805.956 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:06.318Z,1423816806.318 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:06.719Z,1423816806.719 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:07.124Z,1423816807.124 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:07.516Z,1423816807.516 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:07.916Z,1423816807.916 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:08.317Z,1423816808.317 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:08.718Z,1423816808.718 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:09.207Z,1423816809.207 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:09.526Z,1423816809.526 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:09.931Z,1423816809.931 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:10.349Z,1423816810.349 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:10.720Z,1423816810.720 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:11.124Z,1423816811.124 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:11.543Z,1423816811.543 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:11.934Z,1423816811.934 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:12.314Z,1423816812.314 [NAL9602](INFO): NAL9602 initialized
2015-02-13T08:40:12.356Z,1423816812.356 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:12.745Z,1423816812.745 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:13.144Z,1423816813.144 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:13.148Z,1423816813.148 [Startup:StartupSatComms:A](INFO): Timed out from 2015-02-13T08:39:12.9Z
2015-02-13T08:40:13.148Z,1423816813.148 [Startup:StartupSatComms:A] Stopped
2015-02-13T08:40:13.148Z,1423816813.148 [Startup:StartupSatComms:B] Running Loop=1
2015-02-13T08:40:13.533Z,1423816813.533 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:13.537Z,1423816813.537 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-02-13T08:40:14.277Z,1423816814.277 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:14.375Z,1423816814.375 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:14.726Z,1423816814.726 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:17.892Z,1423816817.892 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:17.981Z,1423816817.981 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:18.317Z,1423816818.317 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20150213T082514/Courier0004.lzma
2015-02-13T08:40:18.360Z,1423816818.360 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:18.734Z,1423816818.734 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:19.113Z,1423816819.113 [DataOverHttps](INFO): Moved sent file to Logs/20150213T082514/Courier0004.lzma.bak
2015-02-13T08:40:19.113Z,1423816819.113 [DataOverHttps](INFO): SBD MOMSN=1529478
2015-02-13T08:40:19.144Z,1423816819.144 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:19.829Z,1423816819.829 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:19.935Z,1423816819.935 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:20.335Z,1423816820.335 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:23.553Z,1423816823.553 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:23.637Z,1423816823.637 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:24.026Z,1423816824.026 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:24.451Z,1423816824.451 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:24.852Z,1423816824.852 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:25.215Z,1423816825.215 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:25.645Z,1423816825.645 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:28.793Z,1423816828.793 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:28.913Z,1423816828.913 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:29.222Z,1423816829.222 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20150213T083429/Courier0000.lzma
2015-02-13T08:40:29.284Z,1423816829.284 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:29.634Z,1423816829.634 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:30.014Z,1423816830.014 [DataOverHttps](INFO): Moved sent file to Logs/20150213T083429/Courier0000.lzma.bak
2015-02-13T08:40:30.014Z,1423816830.014 [DataOverHttps](INFO): SBD MOMSN=1529480
2015-02-13T08:40:30.044Z,1423816830.044 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:31.016Z,1423816831.016 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:31.101Z,1423816831.101 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:31.479Z,1423816831.479 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:34.643Z,1423816834.643 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:34.731Z,1423816834.731 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:35.090Z,1423816835.090 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:35.482Z,1423816835.482 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:35.884Z,1423816835.884 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:36.284Z,1423816836.284 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:36.681Z,1423816836.681 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:39.885Z,1423816839.885 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:39.980Z,1423816839.980 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:40.308Z,1423816840.308 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20150213T082514/Express0005.lzma
2015-02-13T08:40:40.316Z,1423816840.316 [NAL9602](INFO): SBD MO Status=2, MOMSN=5664, MT Status=2, MTMSN=0
2015-02-13T08:40:40.330Z,1423816840.330 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-02-13T08:40:40.339Z,1423816840.339 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:40.731Z,1423816840.731 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:41.107Z,1423816841.107 [DataOverHttps](INFO): Moved sent file to Logs/20150213T082514/Express0005.lzma.bak
2015-02-13T08:40:41.107Z,1423816841.107 [DataOverHttps](INFO): SBD MOMSN=1529486
2015-02-13T08:40:41.180Z,1423816841.180 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:42.052Z,1423816842.052 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:42.134Z,1423816842.134 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:42.491Z,1423816842.491 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:45.708Z,1423816845.708 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:45.800Z,1423816845.800 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:46.193Z,1423816846.193 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:46.601Z,1423816846.601 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:47.014Z,1423816847.014 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:47.366Z,1423816847.366 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:47.783Z,1423816847.783 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:51.432Z,1423816851.432 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:51.554Z,1423816851.554 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:51.877Z,1423816851.877 [DataOverHttps](INFO): Sending 859 bytes from file Logs/20150213T083429/Express0001.lzma
2015-02-13T08:40:51.914Z,1423816851.914 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:52.300Z,1423816852.300 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:52.673Z,1423816852.673 [DataOverHttps](INFO): Moved sent file to Logs/20150213T083429/Express0001.lzma.bak
2015-02-13T08:40:52.673Z,1423816852.673 [DataOverHttps](INFO): SBD MOMSN=1529488
2015-02-13T08:40:52.740Z,1423816852.740 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:59.703Z,1423816859.703 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:59.707Z,1423816859.707 [Startup:StartupSatComms:B] Stopped
2015-02-13T08:40:59.707Z,1423816859.707 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2015-02-13T08:40:59.707Z,1423816859.707 [Startup:StartupSatComms] Stopped
2015-02-13T08:40:59.707Z,1423816859.707 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2015-02-13T08:40:59.708Z,1423816859.708 [Startup](INFO): Completed Startup
2015-02-13T08:40:59.708Z,1423816859.708 [Startup] Stopped
2015-02-13T08:40:59.708Z,1423816859.708 [Startup](INFO): Aggregate::uninitialize Startup
2015-02-13T08:40:59.708Z,1423816859.708 [Startup:A.GoToSurface] Stopped
2015-02-13T08:40:59.708Z,1423816859.708 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-02-13T08:40:59.802Z,1423816859.802 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:40:59.809Z,1423816859.809 [MissionManager](IMPORTANT): Started mission Default
2015-02-13T08:40:59.809Z,1423816859.809 [Default] Running Loop=1
2015-02-13T08:40:59.809Z,1423816859.809 [Default](INFO): Aggregate::initialize Default
2015-02-13T08:40:59.809Z,1423816859.809 [Default:B.GoToSurface] Running Loop=1
2015-02-13T08:40:59.809Z,1423816859.809 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-02-13T08:40:59.810Z,1423816859.810 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-02-13T08:40:59.810Z,1423816859.810 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-02-13T08:40:59.816Z,1423816859.816 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-02-13T08:40:59.816Z,1423816859.816 [Default:StartClock] Running Loop=1
2015-02-13T08:40:59.816Z,1423816859.816 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock
2015-02-13T08:40:59.816Z,1423816859.816 [Default:StartClock:A] Running Loop=1
2015-02-13T08:40:59.833Z,1423816859.833 [Default:StartClock:A] Stopped
2015-02-13T08:40:59.833Z,1423816859.833 [Default:StartClock](INFO): Completed Default:StartClock
2015-02-13T08:40:59.833Z,1423816859.833 [Default:StartClock] Stopped
2015-02-13T08:40:59.833Z,1423816859.833 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock
2015-02-13T08:41:00.198Z,1423816860.198 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:00.202Z,1423816860.202 [Default:WaitAtTheSurface] Running Loop=1
2015-02-13T08:41:00.203Z,1423816860.203 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2015-02-13T08:41:00.203Z,1423816860.203 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-02-13T08:41:00.203Z,1423816860.203 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2015-02-13T08:41:00.550Z,1423816860.550 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:00.561Z,1423816860.561 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-02-13T08:41:08.371Z,1423816868.371 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:10.590Z,1423816870.590 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:15.559Z,1423816875.559 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:20.565Z,1423816880.565 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:25.532Z,1423816885.532 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2015-02-13T08:41:25.532Z,1423816885.532 [DVL_micro] Data Fault, FailCount= 1
2015-02-13T08:41:25.532Z,1423816885.532 [DVL_micro](ERROR): Data Fault
2015-02-13T08:41:25.597Z,1423816885.597 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:25.598Z,1423816885.598 [DeadReckonUsingMultipleVelocitySources](FAULT): The allowance for input measurements to start up has expired, but I still cannot determine the rotation from vehicle frame to navigation frame.
2015-02-13T08:41:25.598Z,1423816885.598 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2015-02-13T08:41:25.598Z,1423816885.598 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-02-13T08:41:25.604Z,1423816885.604 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-02-13T08:41:25.605Z,1423816885.605 [DeadReckonUsingSpeedCalculator](FAULT): The allowance for input measurements to start up has expired, but I still cannot determine the rotation from vehicle frame to navigation frame.
2015-02-13T08:41:25.605Z,1423816885.605 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2015-02-13T08:41:25.605Z,1423816885.605 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-02-13T08:41:25.606Z,1423816885.606 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-02-13T08:41:25.626Z,1423816885.626 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-02-13T08:41:25.626Z,1423816885.626 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-02-13T08:41:25.626Z,1423816885.626 [CBIT](ERROR): Data Fault in component: DVL_micro
2015-02-13T08:41:25.631Z,1423816885.631 [CBIT](CRITICAL): Data Fault in component: DVL_micro
2015-02-13T08:41:30.533Z,1423816890.533 [DVL_micro](INFO): uninitialize:Powering down
2015-02-13T08:41:30.553Z,1423816890.553 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:30.580Z,1423816890.580 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-02-13T08:41:30.580Z,1423816890.580 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2015-02-13T08:41:30.581Z,1423816890.581 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-02-13T08:41:30.581Z,1423816890.581 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2015-02-13T08:41:35.597Z,1423816895.597 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:35.598Z,1423816895.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-02-13T08:41:35.598Z,1423816895.598 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-02-13T08:41:40.544Z,1423816900.544 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:40.545Z,1423816900.545 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:41:45.593Z,1423816905.593 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:45.594Z,1423816905.594 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:41:50.532Z,1423816910.532 [NAL9602](INFO): SBD MO Status=2, MOMSN=5664, MT Status=2, MTMSN=0
2015-02-13T08:41:50.533Z,1423816910.533 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2015-02-13T08:41:50.588Z,1423816910.588 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:50.589Z,1423816910.589 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:41:55.551Z,1423816915.551 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:41:55.552Z,1423816915.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:42:00.559Z,1423816920.559 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:00.560Z,1423816920.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:42:05.592Z,1423816925.592 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:05.597Z,1423816925.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T08:42:13.342Z,1423816933.342 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:15.587Z,1423816935.587 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:20.536Z,1423816940.536 [NAL9602](INFO): SBD MO Status=0, MOMSN=5664, MT Status=0, MTMSN=0
2015-02-13T08:42:20.536Z,1423816940.536 [NAL9602](INFO): No messages in MT queue
2015-02-13T08:42:20.550Z,1423816940.550 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:25.595Z,1423816945.595 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:30.586Z,1423816950.586 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:35.536Z,1423816955.536 [NAL9602](IMPORTANT): GPS fix at: 1423816946.00
2015-02-13T08:42:35.574Z,1423816955.574 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:40.570Z,1423816960.570 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:40.571Z,1423816960.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:42:40.576Z,1423816960.576 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:42:45.622Z,1423816965.622 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:45.627Z,1423816965.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:42:45.628Z,1423816965.628 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:42:50.567Z,1423816970.567 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:50.569Z,1423816970.569 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:42:50.569Z,1423816970.569 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:42:55.586Z,1423816975.586 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:42:55.587Z,1423816975.587 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:42:55.588Z,1423816975.588 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:00.531Z,1423816980.531 [NAL9602](INFO): Powering down
2015-02-13T08:43:00.594Z,1423816980.594 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:00.596Z,1423816980.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:00.597Z,1423816980.597 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:05.573Z,1423816985.573 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:05.574Z,1423816985.574 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:05.575Z,1423816985.575 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:13.725Z,1423816993.725 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:13.726Z,1423816993.726 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:13.727Z,1423816993.727 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:15.553Z,1423816995.553 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:15.555Z,1423816995.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:15.556Z,1423816995.556 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:20.551Z,1423817000.551 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:20.553Z,1423817000.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:20.554Z,1423817000.554 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:20.557Z,1423817000.557 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-02-13T08:43:20.557Z,1423817000.557 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-02-13T08:43:20.557Z,1423817000.557 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-02-13T08:43:20.560Z,1423817000.560 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-02-13T08:43:20.560Z,1423817000.560 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-02-13T08:43:25.600Z,1423817005.600 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:25.602Z,1423817005.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:25.603Z,1423817005.603 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:25.612Z,1423817005.612 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 2.346704 min 
2015-02-13T08:43:25.625Z,1423817005.625 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-02-13T08:43:25.625Z,1423817005.625 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-02-13T08:43:25.625Z,1423817005.625 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-02-13T08:43:25.625Z,1423817005.625 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-02-13T08:43:30.581Z,1423817010.581 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:30.583Z,1423817010.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:30.583Z,1423817010.583 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:35.555Z,1423817015.555 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:35.556Z,1423817015.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:35.557Z,1423817015.557 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:40.580Z,1423817020.580 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:40.581Z,1423817020.581 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:40.583Z,1423817020.583 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:45.559Z,1423817025.559 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:45.560Z,1423817025.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:45.561Z,1423817025.561 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:50.639Z,1423817030.639 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:50.640Z,1423817030.640 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:50.641Z,1423817030.641 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:55.587Z,1423817035.587 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:43:55.589Z,1423817035.589 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:43:55.590Z,1423817035.590 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:44:00.550Z,1423817040.550 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:44:00.551Z,1423817040.551 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:44:00.552Z,1423817040.552 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:44:05.563Z,1423817045.563 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:44:05.581Z,1423817045.581 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:44:05.584Z,1423817045.584 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:44:10.624Z,1423817050.624 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:44:10.625Z,1423817050.625 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:44:10.626Z,1423817050.626 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:44:18.647Z,1423817058.647 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T08:44:18.649Z,1423817058.649 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expired, but velocity or orientation is invalid, and horizontal path length since last fix is nonzero.
2015-02-13T08:44:18.650Z,1423817058.650 [DeadReckonUsingSpeedCalculator](DEBUG): Will not write estimated position: allowance for input measurements to start up has not expir