2014-09-23T04:18:16.041Z,1411445896.041 [Supervisor](DEBUG): Initializing supervisor.
2014-09-23T04:18:16.044Z,1411445896.044 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-09-23T04:18:16.044Z,1411445896.044 [SyncHandler](INFO): Protected caller Thread ID is 766
2014-09-23T04:18:16.045Z,1411445896.045 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-09-23T04:18:16.046Z,1411445896.046 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-09-23T04:18:16.046Z,1411445896.046 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 767
2014-09-23T04:18:16.049Z,1411445896.049 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-09-23T04:18:16.062Z,1411445896.062 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-09-23T04:18:16.063Z,1411445896.063 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-09-23T04:18:16.063Z,1411445896.063 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 768
2014-09-23T04:18:16.064Z,1411445896.064 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-09-23T04:18:16.065Z,1411445896.065 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-09-23T04:18:16.065Z,1411445896.065 [logger ThreadHandler](INFO): Protected caller Thread ID is 769
2014-09-23T04:18:16.067Z,1411445896.067 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-09-23T04:18:16.068Z,1411445896.068 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-09-23T04:18:16.071Z,1411445896.071 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-09-23T04:18:16.444Z,1411445896.444 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-09-23T04:18:16.445Z,1411445896.445 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-09-23T04:18:16.614Z,1411445896.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-09-23T04:18:16.617Z,1411445896.617 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-09-23T04:18:16.732Z,1411445896.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-09-23T04:18:16.734Z,1411445896.734 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-09-23T04:18:16.866Z,1411445896.866 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-09-23T04:18:16.867Z,1411445896.867 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-09-23T04:18:16.955Z,1411445896.955 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-09-23T04:18:17.110Z,1411445897.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-09-23T04:18:17.111Z,1411445897.111 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-09-23T04:18:17.200Z,1411445897.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-09-23T04:18:17.201Z,1411445897.201 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-09-23T04:18:17.417Z,1411445897.417 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-09-23T04:18:17.420Z,1411445897.420 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-09-23T04:18:17.790Z,1411445897.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-09-23T04:18:17.791Z,1411445897.791 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-09-23T04:18:18.127Z,1411445898.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-09-23T04:18:18.128Z,1411445898.128 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-09-23T04:18:18.677Z,1411445898.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-09-23T04:18:18.678Z,1411445898.678 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-09-23T04:18:18.900Z,1411445898.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-09-23T04:18:18.901Z,1411445898.901 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-09-23T04:18:19.010Z,1411445899.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-09-23T04:18:19.012Z,1411445899.012 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-09-23T04:18:19.468Z,1411445899.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-09-23T04:18:19.469Z,1411445899.469 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-09-23T04:18:19.593Z,1411445899.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-09-23T04:18:19.595Z,1411445899.595 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2014-09-23T04:18:23.205Z,1411445903.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2014-09-23T04:18:23.463Z,1411445903.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2014-09-23T04:18:23.463Z,1411445903.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2014-09-23T04:18:33.874Z,1411445913.874 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2014-09-23T04:18:33.981Z,1411445913.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2014-09-23T04:18:34.083Z,1411445914.083 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2014-09-23T04:18:34.185Z,1411445914.185 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2014-09-23T04:18:34.338Z,1411445914.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2014-09-23T04:18:34.523Z,1411445914.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2014-09-23T04:18:34.631Z,1411445914.631 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2014-09-23T04:18:34.720Z,1411445914.720 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2014-09-23T04:18:34.817Z,1411445914.817 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2014-09-23T04:18:34.920Z,1411445914.920 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2014-09-23T04:18:35.111Z,1411445915.111 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-09-23T04:18:35.116Z,1411445915.116 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-09-23T04:18:35.196Z,1411445915.196 [VerticalControl](DEBUG): Construct VerticalControl.
2014-09-23T04:18:35.330Z,1411445915.330 [VerticalControl] Loaded
2014-09-23T04:18:35.330Z,1411445915.330 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-09-23T04:18:35.331Z,1411445915.331 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-09-23T04:18:35.411Z,1411445915.411 [HorizontalControl] Loaded
2014-09-23T04:18:35.411Z,1411445915.411 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-09-23T04:18:35.412Z,1411445915.412 [SpeedControl](DEBUG): Construct SpeedControl.
2014-09-23T04:18:35.418Z,1411445915.418 [SpeedControl] Loaded
2014-09-23T04:18:35.419Z,1411445915.419 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-09-23T04:18:35.419Z,1411445915.419 [LoopControl](DEBUG): Construct LoopControl.
2014-09-23T04:18:35.420Z,1411445915.420 [LoopControl] Loaded
2014-09-23T04:18:35.420Z,1411445915.420 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-09-23T04:18:35.421Z,1411445915.421 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-09-23T04:18:35.421Z,1411445915.421 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-09-23T04:18:35.557Z,1411445915.557 [SBIT](DEBUG): Construct Startup Built In Test.
2014-09-23T04:18:35.569Z,1411445915.569 [SBIT] Loaded
2014-09-23T04:18:35.570Z,1411445915.570 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-09-23T04:18:35.570Z,1411445915.570 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-09-23T04:18:35.602Z,1411445915.602 [IBIT] Loaded
2014-09-23T04:18:35.603Z,1411445915.603 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-09-23T04:18:35.605Z,1411445915.605 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-09-23T04:18:35.740Z,1411445915.740 [CBIT] Loaded
2014-09-23T04:18:35.740Z,1411445915.740 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-09-23T04:18:35.740Z,1411445915.740 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-09-23T04:18:35.741Z,1411445915.741 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-09-23T04:18:35.774Z,1411445915.774 [DepthRateCalculator] Loaded
2014-09-23T04:18:35.774Z,1411445915.774 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-09-23T04:18:35.780Z,1411445915.780 [PitchRateCalculator] Loaded
2014-09-23T04:18:35.780Z,1411445915.780 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-09-23T04:18:35.793Z,1411445915.793 [SpeedCalculator] Loaded
2014-09-23T04:18:35.793Z,1411445915.793 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-09-23T04:18:35.812Z,1411445915.812 [TempGradientCalculator] Loaded
2014-09-23T04:18:35.812Z,1411445915.812 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-09-23T04:18:35.818Z,1411445915.818 [YawRateCalculator] Loaded
2014-09-23T04:18:35.818Z,1411445915.818 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-09-23T04:18:35.819Z,1411445915.819 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-09-23T04:18:35.819Z,1411445915.819 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-09-23T04:18:46.156Z,1411445926.156 [HFRCMReconstructedInterpolator] Loaded
2014-09-23T04:18:46.156Z,1411445926.156 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-09-23T04:18:46.157Z,1411445926.157 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-09-23T04:18:46.158Z,1411445926.158 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-09-23T04:18:46.469Z,1411445926.469 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-09-23T04:18:46.470Z,1411445926.470 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-09-23T04:18:46.572Z,1411445926.572 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-09-23T04:18:46.572Z,1411445926.572 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-09-23T04:18:46.622Z,1411445926.622 [DeadReckonWithRespectToWater] Loaded
2014-09-23T04:18:46.622Z,1411445926.622 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-09-23T04:18:46.673Z,1411445926.673 [DeadReckonWithRespectToSeafloor] Loaded
2014-09-23T04:18:46.673Z,1411445926.673 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-09-23T04:18:46.729Z,1411445926.729 [DeadReckonUsingDVLWaterTrack] Loaded
2014-09-23T04:18:46.729Z,1411445926.729 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-09-23T04:18:46.749Z,1411445926.749 [NavChart] Loaded
2014-09-23T04:18:46.750Z,1411445926.750 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-09-23T04:18:46.754Z,1411445926.754 [UniversalFixResidualReporter] Loaded
2014-09-23T04:18:46.754Z,1411445926.754 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-09-23T04:18:46.755Z,1411445926.755 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-09-23T04:18:46.756Z,1411445926.756 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-09-23T04:18:46.765Z,1411445926.765 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-09-23T04:18:46.766Z,1411445926.766 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-09-23T04:18:46.976Z,1411445926.976 [Aanderaa_O2] Loaded
2014-09-23T04:18:46.976Z,1411445926.976 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-09-23T04:18:47.016Z,1411445927.016 [CTD_NeilBrown] Loaded
2014-09-23T04:18:47.017Z,1411445927.017 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-09-23T04:18:47.018Z,1411445927.018 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405D94E0
2014-09-23T04:18:47.018Z,1411445927.018 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 852
2014-09-23T04:18:47.038Z,1411445927.038 [rhodamine] Loaded
2014-09-23T04:18:47.038Z,1411445927.038 [ComponentRegistry](DEBUG): SyncComponent "rhodamine" handled in the control thread.
2014-09-23T04:18:47.078Z,1411445927.078 [WetLabsBB2FL] Loaded
2014-09-23T04:18:47.078Z,1411445927.078 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-09-23T04:18:47.079Z,1411445927.079 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406094E0
2014-09-23T04:18:47.079Z,1411445927.079 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 853
2014-09-23T04:18:47.080Z,1411445927.080 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-09-23T04:18:47.081Z,1411445927.081 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-09-23T04:18:47.816Z,1411445927.816 [AHRS_sp3003D] Loaded
2014-09-23T04:18:47.816Z,1411445927.816 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-09-23T04:18:47.866Z,1411445927.866 [AcousticModem_Benthos_ATM900] Loaded
2014-09-23T04:18:47.867Z,1411445927.867 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2014-09-23T04:18:48.029Z,1411445928.029 [DataOverHttps] Loaded
2014-09-23T04:18:48.030Z,1411445928.030 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2014-09-23T04:18:48.045Z,1411445928.045 [Depth_Keller] Loaded
2014-09-23T04:18:48.045Z,1411445928.045 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-09-23T04:18:48.051Z,1411445928.051 [DropWeight] Loaded
2014-09-23T04:18:48.051Z,1411445928.051 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-09-23T04:18:48.209Z,1411445928.209 [DVL_micro] Loaded
2014-09-23T04:18:48.209Z,1411445928.209 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-09-23T04:18:48.317Z,1411445928.317 [NAL9602] Loaded
2014-09-23T04:18:48.317Z,1411445928.317 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-09-23T04:18:48.364Z,1411445928.364 [Onboard] Loaded
2014-09-23T04:18:48.364Z,1411445928.364 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-09-23T04:18:48.371Z,1411445928.371 [Radio_Surface] Loaded
2014-09-23T04:18:48.372Z,1411445928.372 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2014-09-23T04:18:48.373Z,1411445928.373 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408534E0
2014-09-23T04:18:48.373Z,1411445928.373 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 854
2014-09-23T04:18:50.473Z,1411445930.473 [BPC1] Loaded
2014-09-23T04:18:50.473Z,1411445930.473 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2014-09-23T04:18:50.474Z,1411445930.474 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-09-23T04:18:50.474Z,1411445930.474 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-09-23T04:18:55.453Z,1411445935.453 [BuoyancyServo] Loaded
2014-09-23T04:18:55.453Z,1411445935.453 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-09-23T04:18:55.467Z,1411445935.467 [ElevatorServo] Loaded
2014-09-23T04:18:55.467Z,1411445935.467 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-09-23T04:18:55.480Z,1411445935.480 [MassServo] Loaded
2014-09-23T04:18:55.480Z,1411445935.480 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-09-23T04:18:55.493Z,1411445935.493 [RudderServo] Loaded
2014-09-23T04:18:55.493Z,1411445935.493 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-09-23T04:18:55.506Z,1411445935.506 [ThrusterServo] Loaded
2014-09-23T04:18:55.506Z,1411445935.506 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-09-23T04:18:55.507Z,1411445935.507 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-09-23T04:18:55.507Z,1411445935.507 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-09-23T04:18:55.721Z,1411445935.721 [InternalSim] Loaded
2014-09-23T04:18:55.721Z,1411445935.721 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-09-23T04:18:55.722Z,1411445935.722 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-09-23T04:18:55.723Z,1411445935.723 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-09-23T04:18:55.767Z,1411445935.767 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-09-23T04:18:55.769Z,1411445935.769 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-09-23T04:18:55.770Z,1411445935.770 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-09-23T04:18:55.776Z,1411445935.776 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-09-23T04:18:55.777Z,1411445935.777 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409044E0
2014-09-23T04:18:55.778Z,1411445935.778 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 855
2014-09-23T04:18:55.782Z,1411445935.782 [Supervisor](INFO): Main Thread ID is 764
2014-09-23T04:18:55.783Z,1411445935.783 [Supervisor](DEBUG): Running supervisor.
2014-09-23T04:18:55.783Z,1411445935.783 [CommandLine ThreadHandler](INFO): Handler Thread ID is 856
2014-09-23T04:18:55.786Z,1411445935.786 [controlThread ThreadHandler](INFO): Handler Thread ID is 857
2014-09-23T04:18:55.787Z,1411445935.787 [controlThread](DEBUG): Initializing ControlThread
2014-09-23T04:18:55.787Z,1411445935.787 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-09-23T04:18:55.789Z,1411445935.789 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-09-23T04:18:55.790Z,1411445935.790 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-09-23T04:18:55.790Z,1411445935.790 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-09-23T04:18:55.791Z,1411445935.791 [SBIT](INFO): Initialize SBIT Component.
2014-09-23T04:18:55.791Z,1411445935.791 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11693
2014-09-23T04:18:55.792Z,1411445935.792 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-09-23T04:18:55.792Z,1411445935.792 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-09-23T04:18:55.792Z,1411445935.792 [IBIT](INFO): Initialize IBIT Component.
2014-09-23T04:18:55.793Z,1411445935.793 [CBIT](DEBUG): Initialize CBIT Component.
2014-09-23T04:18:55.794Z,1411445935.794 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-09-23T04:18:55.794Z,1411445935.794 [logger ThreadHandler](INFO): Handler Thread ID is 858
2014-09-23T04:18:55.817Z,1411445935.817 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-23T04:18:55.817Z,1411445935.817 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-09-23T04:18:55.817Z,1411445935.817 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-09-23T04:18:55.818Z,1411445935.818 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-09-23T04:18:55.818Z,1411445935.818 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-09-23T04:18:55.819Z,1411445935.819 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-09-23T04:18:55.821Z,1411445935.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-23T04:18:55.821Z,1411445935.821 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-09-23T04:18:55.822Z,1411445935.822 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-09-23T04:18:55.822Z,1411445935.822 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-09-23T04:18:55.822Z,1411445935.822 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-09-23T04:18:55.823Z,1411445935.823 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-23T04:18:55.833Z,1411445935.833 [InternalSim](DEBUG):  InternalSim initializing...
2014-09-23T04:18:55.841Z,1411445935.841 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 859
2014-09-23T04:18:55.842Z,1411445935.842 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-09-23T04:18:55.849Z,1411445935.849 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-09-23T04:18:55.859Z,1411445935.859 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 861
2014-09-23T04:18:55.861Z,1411445935.861 [WetLabsBB2FL](INFO): Powering down
2014-09-23T04:18:55.897Z,1411445935.897 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 862
2014-09-23T04:18:55.903Z,1411445935.903 [Radio_Surface](INFO): Powering up
2014-09-23T04:18:55.917Z,1411445935.917 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 863
2014-09-23T04:18:55.927Z,1411445935.927 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-09-23T04:18:55.928Z,1411445935.928 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-09-23T04:18:55.928Z,1411445935.928 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-09-23T04:18:55.929Z,1411445935.929 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-09-23T04:18:55.929Z,1411445935.929 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-09-23T04:18:55.929Z,1411445935.929 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-09-23T04:18:55.930Z,1411445935.930 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-09-23T04:18:55.930Z,1411445935.930 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-09-23T04:18:55.930Z,1411445935.930 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-09-23T04:18:56.182Z,1411445936.182 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-09-23T04:18:56.212Z,1411445936.212 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-23T04:18:56.237Z,1411445936.237 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2014-09-23T04:18:56.238Z,1411445936.238 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-09-23T04:18:56.357Z,1411445936.357 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-09-23T04:18:56.361Z,1411445936.361 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-09-23T04:18:56.365Z,1411445936.365 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-23T04:18:56.406Z,1411445936.406 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-09-23T04:18:56.412Z,1411445936.412 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-09-23T04:18:56.436Z,1411445936.436 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-09-23T04:18:56.462Z,1411445936.462 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

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

    <Aggregate Id="StartClock">

        <Sequence/>

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

    </Aggregate>

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

    <Aggregate Id="CheckIn">

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

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

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

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

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

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="UpdateAndReportMinutesSinceMissionStarted">

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

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

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

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2014-09-23T04:18:56.483Z,1411445936.483 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,Aanderaa_O2,rhodamine,AHRS_sp3003D,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-09-23T04:18:56.614Z,1411445936.614 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-09-23T04:18:56.644Z,1411445936.644 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-09-23T04:18:56.645Z,1411445936.645 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-09-23T04:18:56.798Z,1411445936.798 [DVL_micro](INFO): Initializing
2014-09-23T04:18:56.837Z,1411445936.837 [NAL9602](INFO): Powering up NAL9602
2014-09-23T04:18:57.188Z,1411445937.188 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:18:57.210Z,1411445937.210 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:57.227Z,1411445937.227 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:57.239Z,1411445937.239 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:57.250Z,1411445937.250 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:57.386Z,1411445937.386 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-09-23T04:18:57.403Z,1411445937.403 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-09-23T04:18:57.425Z,1411445937.425 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-09-23T04:18:57.431Z,1411445937.431 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-09-23T04:18:57.453Z,1411445937.453 [MassServo](DEBUG): Initializing EZServoServo.
2014-09-23T04:18:57.459Z,1411445937.459 [MassServo](DEBUG): Initializing MassServo.
2014-09-23T04:18:57.465Z,1411445937.465 [RudderServo](DEBUG): Initializing EZServoServo.
2014-09-23T04:18:57.471Z,1411445937.471 [RudderServo](DEBUG): Initializing RudderServo.
2014-09-23T04:18:57.489Z,1411445937.489 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-09-23T04:18:57.496Z,1411445937.496 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-09-23T04:18:57.783Z,1411445937.783 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:18:57.783Z,1411445937.783 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:57.792Z,1411445937.792 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:57.793Z,1411445937.793 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:57.793Z,1411445937.793 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:58.196Z,1411445938.196 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:18:58.204Z,1411445938.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:58.205Z,1411445938.205 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:58.206Z,1411445938.206 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:58.207Z,1411445938.207 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-23T04:18:58.885Z,1411445938.885 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:18:59.329Z,1411445939.329 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:18:59.950Z,1411445939.950 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:00.168Z,1411445940.168 [Aanderaa_O2](INFO): Powering down
2014-09-23T04:19:00.611Z,1411445940.611 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:01.184Z,1411445941.184 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:01.661Z,1411445941.661 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:02.517Z,1411445942.517 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:03.362Z,1411445943.362 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:04.016Z,1411445944.016 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:04.776Z,1411445944.776 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:05.174Z,1411445945.174 [AcousticModem_Benthos_ATM900](DEBUG): 
2014-09-23T04:19:05.220Z,1411445945.220 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:05.651Z,1411445945.651 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:06.088Z,1411445946.088 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:06.501Z,1411445946.501 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series
2014-09-23T04:19:06.501Z,1411445946.501 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2014-09-23T04:19:06.502Z,1411445946.502 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.8.9
2014-09-23T04:19:06.549Z,1411445946.549 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:06.937Z,1411445946.937 [AcousticModem_Benthos_ATM900](DEBUG): Sep 23 2014 04:16:09 
2014-09-23T04:19:06.975Z,1411445946.975 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:07.462Z,1411445947.462 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:07.858Z,1411445947.858 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2014-09-23T04:19:07.866Z,1411445947.866 [NAL9602](INFO): NAL9602 initialized
2014-09-23T04:19:07.896Z,1411445947.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:08.306Z,1411445948.306 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2014-09-23T04:19:08.307Z,1411445948.307 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2014-09-23T04:19:08.307Z,1411445948.307 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2014-09-23T04:19:08.378Z,1411445948.378 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:08.771Z,1411445948.771 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2014-09-23T04:19:08.901Z,1411445948.901 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:09.300Z,1411445949.300 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2014-09-23T04:19:09.301Z,1411445949.301 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2014-09-23T04:19:09.381Z,1411445949.381 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:09.797Z,1411445949.797 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2014-09-23T04:19:09.858Z,1411445949.858 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:10.343Z,1411445950.343 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:10.899Z,1411445950.899 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:11.368Z,1411445951.368 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:11.405Z,1411445951.405 [SBIT](IMPORTANT): Beginning Startup BIT
2014-09-23T04:19:11.407Z,1411445951.407 [CBIT](IMPORTANT): Beginning GF scan
2014-09-23T04:19:12.107Z,1411445952.107 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:12.646Z,1411445952.646 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:13.199Z,1411445953.199 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:13.671Z,1411445953.671 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:14.592Z,1411445954.592 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:15.114Z,1411445955.114 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:15.642Z,1411445955.642 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:16.239Z,1411445956.239 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:16.724Z,1411445956.724 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:17.265Z,1411445957.265 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:17.817Z,1411445957.817 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:18.315Z,1411445958.315 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:18.823Z,1411445958.823 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:19.357Z,1411445959.357 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:19.849Z,1411445959.849 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:20.366Z,1411445960.366 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:20.975Z,1411445960.975 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:21.451Z,1411445961.451 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:21.944Z,1411445961.944 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:22.453Z,1411445962.453 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:22.958Z,1411445962.958 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:23.430Z,1411445963.430 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:23.956Z,1411445963.956 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:24.488Z,1411445964.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:24.949Z,1411445964.949 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:25.457Z,1411445965.457 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:25.935Z,1411445965.935 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:26.408Z,1411445966.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:26.883Z,1411445966.883 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:27.842Z,1411445967.842 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:28.386Z,1411445968.386 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:28.871Z,1411445968.871 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:29.404Z,1411445969.404 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:29.911Z,1411445969.911 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:30.388Z,1411445970.388 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:30.861Z,1411445970.861 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:31.367Z,1411445971.367 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:31.933Z,1411445971.933 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:32.416Z,1411445972.416 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:32.917Z,1411445972.917 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:33.394Z,1411445973.394 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:34.015Z,1411445974.015 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:34.571Z,1411445974.571 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:35.081Z,1411445975.081 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:35.610Z,1411445975.610 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:36.107Z,1411445976.107 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:36.603Z,1411445976.603 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:37.102Z,1411445977.102 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:37.613Z,1411445977.613 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:38.193Z,1411445978.193 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:38.325Z,1411445978.325 [CBIT](FAULT): Chan 4 High side GF detected
mA:
CHAN 5 (24V): 0.094600
CHAN 4 (Batt): 0.000574
CHAN 2 (12V): 0.010426
CHAN 1 (5V): 0.000287
CHAN 0 (3.3V): 0.002678
OPEN: 0.000287
Full Scale Calc: 0.392
2014-09-23T04:19:38.787Z,1411445978.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:39.379Z,1411445979.379 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:39.864Z,1411445979.864 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:40.331Z,1411445980.331 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:40.822Z,1411445980.822 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:41.343Z,1411445981.343 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:41.836Z,1411445981.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:42.296Z,1411445982.296 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:42.809Z,1411445982.809 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:43.290Z,1411445983.290 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:43.746Z,1411445983.746 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:44.234Z,1411445984.234 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:44.748Z,1411445984.748 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:45.228Z,1411445985.228 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:45.722Z,1411445985.722 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:46.186Z,1411445986.186 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:46.643Z,1411445986.643 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:47.131Z,1411445987.131 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:47.584Z,1411445987.584 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:48.141Z,1411445988.141 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:48.610Z,1411445988.610 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:49.086Z,1411445989.086 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:49.563Z,1411445989.563 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:50.028Z,1411445990.028 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:50.505Z,1411445990.505 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:51.009Z,1411445991.009 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:51.554Z,1411445991.554 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:52.011Z,1411445992.011 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:52.519Z,1411445992.519 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:52.949Z,1411445992.949 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:53.381Z,1411445993.381 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:53.967Z,1411445993.967 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:54.859Z,1411445994.859 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:55.461Z,1411445995.461 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:56.036Z,1411445996.036 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:56.503Z,1411445996.503 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:56.978Z,1411445996.978 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:57.541Z,1411445997.541 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:58.045Z,1411445998.045 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:58.482Z,1411445998.482 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:58.910Z,1411445998.910 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:59.336Z,1411445999.336 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:19:59.739Z,1411445999.739 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:00.157Z,1411446000.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:00.682Z,1411446000.682 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:01.113Z,1411446001.113 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:01.533Z,1411446001.533 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:01.930Z,1411446001.930 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:02.329Z,1411446002.329 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:02.731Z,1411446002.731 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:03.132Z,1411446003.132 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:03.533Z,1411446003.533 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:03.932Z,1411446003.932 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:04.375Z,1411446004.375 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:04.747Z,1411446004.747 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:05.145Z,1411446005.145 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:05.157Z,1411446005.157 [SBIT](IMPORTANT): SBIT PASSED
2014-09-23T04:20:05.553Z,1411446005.553 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:05.558Z,1411446005.558 [MissionManager](IMPORTANT): Started mission Startup
2014-09-23T04:20:05.558Z,1411446005.558 [Startup] Running Loop=1
2014-09-23T04:20:05.558Z,1411446005.558 [Startup](INFO): Aggregate::initialize Startup
2014-09-23T04:20:05.558Z,1411446005.558 [Startup:A.GoToSurface] Running Loop=1
2014-09-23T04:20:05.558Z,1411446005.558 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-09-23T04:20:05.559Z,1411446005.559 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-09-23T04:20:05.559Z,1411446005.559 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-09-23T04:20:05.560Z,1411446005.560 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-09-23T04:20:05.566Z,1411446005.566 [Startup:StartupSatComms] Running Loop=1
2014-09-23T04:20:05.566Z,1411446005.566 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-09-23T04:20:05.566Z,1411446005.566 [Startup:StartupSatComms:A] Running Loop=1
2014-09-23T04:20:05.954Z,1411446005.954 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:05.960Z,1411446005.960 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-09-23T04:20:06.368Z,1411446006.368 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:06.778Z,1411446006.778 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:07.171Z,1411446007.171 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:07.568Z,1411446007.568 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:07.975Z,1411446007.975 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:08.359Z,1411446008.359 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:08.805Z,1411446008.805 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:09.196Z,1411446009.196 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:09.618Z,1411446009.618 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:10.049Z,1411446010.049 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:10.443Z,1411446010.443 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:10.850Z,1411446010.850 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:11.266Z,1411446011.266 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:11.656Z,1411446011.656 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:12.087Z,1411446012.087 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:12.513Z,1411446012.513 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:12.906Z,1411446012.906 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:13.312Z,1411446013.312 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:13.682Z,1411446013.682 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:14.124Z,1411446014.124 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:14.521Z,1411446014.521 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:14.908Z,1411446014.908 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:15.331Z,1411446015.331 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:15.736Z,1411446015.736 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:16.135Z,1411446016.135 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:16.527Z,1411446016.527 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:16.923Z,1411446016.923 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:17.340Z,1411446017.340 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:17.757Z,1411446017.757 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:18.157Z,1411446018.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:18.575Z,1411446018.575 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:18.985Z,1411446018.985 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:19.386Z,1411446019.386 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:19.774Z,1411446019.774 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:20.177Z,1411446020.177 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:20.597Z,1411446020.597 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:20.992Z,1411446020.992 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:21.409Z,1411446021.409 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:21.813Z,1411446021.813 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:22.228Z,1411446022.228 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:22.604Z,1411446022.604 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:22.993Z,1411446022.993 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:23.409Z,1411446023.409 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:23.810Z,1411446023.810 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:24.213Z,1411446024.213 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:24.646Z,1411446024.646 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:25.007Z,1411446025.007 [NAL9602](IMPORTANT): GPS fix at: 1411445853.00
2014-09-23T04:20:25.018Z,1411446025.018 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:25.064Z,1411446025.064 [Startup:StartupSatComms:A] Stopped
2014-09-23T04:20:25.064Z,1411446025.064 [Startup:StartupSatComms:B] Running Loop=1
2014-09-23T04:20:25.504Z,1411446025.504 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:25.514Z,1411446025.514 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-09-23T04:20:26.172Z,1411446026.172 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:26.702Z,1411446026.702 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:27.318Z,1411446027.318 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:27.803Z,1411446027.803 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:28.263Z,1411446028.263 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:28.704Z,1411446028.704 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:29.129Z,1411446029.129 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:30.096Z,1411446030.096 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:31.106Z,1411446031.106 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:31.554Z,1411446031.554 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:31.952Z,1411446031.952 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:32.361Z,1411446032.361 [AcousticModem_Benthos_ATM900](DEBUG): sending wake-up to local modem
2014-09-23T04:20:32.434Z,1411446032.434 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:32.849Z,1411446032.849 [AcousticModem_Benthos_ATM900](DEBUG): confirming wake-up of local modem
2014-09-23T04:20:33.374Z,1411446033.374 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:33.779Z,1411446033.779 [AcousticModem_Benthos_ATM900](INFO): serial response: Rx Time:04:17:35.7127

2014-09-23T04:20:33.780Z,1411446033.780 [AcousticModem_Benthos_ATM900](INFO): received an acoustic signal
2014-09-23T04:20:33.780Z,1411446033.780 [AcousticModem_Benthos_ATM900](INFO): serial response: range request

2014-09-23T04:20:33.781Z,1411446033.781 [AcousticModem_Benthos_ATM900](FAULT): unknown deviceResponse_: range request

2014-09-23T04:20:33.781Z,1411446033.781 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2014-09-23T04:20:33.781Z,1411446033.781 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2014-09-23T04:20:33.781Z,1411446033.781 [AcousticModem_Benthos_ATM900](INFO): publishing receive ping time
2014-09-23T04:20:33.972Z,1411446033.972 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:33.989Z,1411446033.989 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2014-09-23T04:20:34.352Z,1411446034.352 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-09-23T04:20:34.596Z,1411446034.596 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:35.012Z,1411446035.012 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:35.446Z,1411446035.446 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:35.463Z,1411446035.463 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2014-09-23T04:20:35.464Z,1411446035.464 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 0
2014-09-23T04:20:35.860Z,1411446035.860 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:36.284Z,1411446036.284 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:36.737Z,1411446036.737 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:37.755Z,1411446037.755 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:38.146Z,1411446038.146 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-09-23T04:20:38.146Z,1411446038.146 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-09-23T04:20:38.459Z,1411446038.459 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:38.912Z,1411446038.912 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:39.362Z,1411446039.362 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:39.845Z,1411446039.845 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:40.811Z,1411446040.811 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:41.318Z,1411446041.318 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:41.829Z,1411446041.829 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:42.294Z,1411446042.294 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:42.750Z,1411446042.750 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:43.214Z,1411446043.214 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:43.644Z,1411446043.644 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:44.067Z,1411446044.067 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:44.999Z,1411446044.999 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:45.561Z,1411446045.561 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:45.951Z,1411446045.951 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:46.339Z,1411446046.339 [AcousticModem_Benthos_ATM900](DEBUG): 
2014-09-23T04:20:46.369Z,1411446046.369 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:46.784Z,1411446046.784 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:47.697Z,1411446047.697 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:48.106Z,1411446048.106 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series
2014-09-23T04:20:48.107Z,1411446048.107 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2014-09-23T04:20:48.108Z,1411446048.108 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.8.9
2014-09-23T04:20:48.108Z,1411446048.108 [AcousticModem_Benthos_ATM900](DEBUG): Sep 23 2014 04:17:50 
2014-09-23T04:20:48.237Z,1411446048.237 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:48.686Z,1411446048.686 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:49.080Z,1411446049.080 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2014-09-23T04:20:49.103Z,1411446049.103 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:49.491Z,1411446049.491 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2014-09-23T04:20:49.492Z,1411446049.492 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2014-09-23T04:20:49.492Z,1411446049.492 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2014-09-23T04:20:49.527Z,1411446049.527 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:49.884Z,1411446049.884 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2014-09-23T04:20:49.910Z,1411446049.910 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:50.305Z,1411446050.305 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2014-09-23T04:20:50.306Z,1411446050.306 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2014-09-23T04:20:50.340Z,1411446050.340 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:50.750Z,1411446050.750 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2014-09-23T04:20:50.783Z,1411446050.783 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:51.182Z,1411446051.182 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:52.097Z,1411446052.097 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:52.674Z,1411446052.674 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:53.041Z,1411446053.041 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:53.440Z,1411446053.440 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:53.896Z,1411446053.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:54.838Z,1411446054.838 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:55.354Z,1411446055.354 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:55.800Z,1411446055.800 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:56.263Z,1411446056.263 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:56.707Z,1411446056.707 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:57.134Z,1411446057.134 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:57.539Z,1411446057.539 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:57.942Z,1411446057.942 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:58.347Z,1411446058.347 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:59.330Z,1411446059.330 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:20:59.896Z,1411446059.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:00.295Z,1411446060.295 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:00.694Z,1411446060.694 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:01.132Z,1411446061.132 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:02.048Z,1411446062.048 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:02.613Z,1411446062.613 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:03.005Z,1411446063.005 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:03.415Z,1411446063.415 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:03.848Z,1411446063.848 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:04.245Z,1411446064.245 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:04.654Z,1411446064.654 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:05.050Z,1411446065.050 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:05.459Z,1411446065.459 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:06.427Z,1411446066.427 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:06.951Z,1411446066.951 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:07.356Z,1411446067.356 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:07.758Z,1411446067.758 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:08.185Z,1411446068.185 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:09.108Z,1411446069.108 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:09.865Z,1411446069.865 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:10.260Z,1411446070.260 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:10.670Z,1411446070.670 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:11.128Z,1411446071.128 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:11.524Z,1411446071.524 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:11.958Z,1411446071.958 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:12.360Z,1411446072.360 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:13.302Z,1411446073.302 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:13.858Z,1411446073.858 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:14.268Z,1411446074.268 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:14.665Z,1411446074.665 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:15.084Z,1411446075.084 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:15.994Z,1411446075.994 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:16.509Z,1411446076.509 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:16.979Z,1411446076.979 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:17.404Z,1411446077.404 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:17.844Z,1411446077.844 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:18.241Z,1411446078.241 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:18.643Z,1411446078.643 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:19.047Z,1411446079.047 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:19.452Z,1411446079.452 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:20.372Z,1411446080.372 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:20.924Z,1411446080.924 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:21.313Z,1411446081.313 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:21.719Z,1411446081.719 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:22.155Z,1411446082.155 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:23.073Z,1411446083.073 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:23.572Z,1411446083.572 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:24.003Z,1411446084.003 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:24.414Z,1411446084.414 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:24.834Z,1411446084.834 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:25.217Z,1411446085.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:25.226Z,1411446085.226 [Startup:StartupSatComms:B](INFO): Timed out from 2014-09-23T04:20:25.1Z
2014-09-23T04:21:25.226Z,1411446085.226 [Startup:StartupSatComms:B] Stopped
2014-09-23T04:21:25.226Z,1411446085.226 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2014-09-23T04:21:25.227Z,1411446085.227 [Startup:StartupSatComms] Stopped
2014-09-23T04:21:25.227Z,1411446085.227 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-09-23T04:21:25.228Z,1411446085.228 [Startup](INFO): Completed Startup
2014-09-23T04:21:25.228Z,1411446085.228 [Startup] Stopped
2014-09-23T04:21:25.228Z,1411446085.228 [Startup](INFO): Aggregate::uninitialize Startup
2014-09-23T04:21:25.228Z,1411446085.228 [Startup:A.GoToSurface] Stopped
2014-09-23T04:21:25.228Z,1411446085.228 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-09-23T04:21:25.729Z,1411446085.729 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:25.752Z,1411446085.752 [MissionManager](IMPORTANT): Started mission Default
2014-09-23T04:21:25.753Z,1411446085.753 [Default] Running Loop=1
2014-09-23T04:21:25.753Z,1411446085.753 [Default](INFO): Aggregate::initialize Default
2014-09-23T04:21:25.753Z,1411446085.753 [Default:B.GoToSurface] Running Loop=1
2014-09-23T04:21:25.753Z,1411446085.753 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-09-23T04:21:25.753Z,1411446085.753 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-09-23T04:21:25.758Z,1411446085.758 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-09-23T04:21:25.758Z,1411446085.758 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-09-23T04:21:25.758Z,1411446085.758 [Default:StartClock] Running Loop=1
2014-09-23T04:21:25.758Z,1411446085.758 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock
2014-09-23T04:21:25.759Z,1411446085.759 [Default:StartClock:A] Running Loop=1
2014-09-23T04:21:25.759Z,1411446085.759 [Default:CheckIn] Running Loop=1
2014-09-23T04:21:25.759Z,1411446085.759 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2014-09-23T04:21:25.759Z,1411446085.759 [Default:CheckIn:A.SetSpeed] Running Loop=1
2014-09-23T04:21:25.759Z,1411446085.759 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2014-09-23T04:21:25.759Z,1411446085.759 [Default:CheckIn:Read_GPS] Running Loop=1
2014-09-23T04:21:25.760Z,1411446085.760 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-09-23T04:21:25.771Z,1411446085.771 [Default:CheckIn:A.SetSpeed] Running Loop=1
2014-09-23T04:21:25.804Z,1411446085.804 [Default:StartClock:A] Stopped
2014-09-23T04:21:25.804Z,1411446085.804 [Default:StartClock](INFO): Completed Default:StartClock
2014-09-23T04:21:25.804Z,1411446085.804 [Default:StartClock] Stopped
2014-09-23T04:21:25.804Z,1411446085.804 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock
2014-09-23T04:21:26.214Z,1411446086.214 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:26.244Z,1411446086.244 [Default:WaitAtTheSurface] Running Loop=1
2014-09-23T04:21:26.244Z,1411446086.244 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2014-09-23T04:21:26.244Z,1411446086.244 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2014-09-23T04:21:26.244Z,1411446086.244 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2014-09-23T04:21:27.192Z,1411446087.192 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:27.213Z,1411446087.213 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2014-09-23T04:21:27.759Z,1411446087.759 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:28.193Z,1411446088.193 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:28.690Z,1411446088.690 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:29.125Z,1411446089.125 [NAL9602](IMPORTANT): GPS fix at: 1411445917.00
2014-09-23T04:21:29.135Z,1411446089.135 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:29.145Z,1411446089.145 [Default:CheckIn:Read_GPS] Stopped
2014-09-23T04:21:29.145Z,1411446089.145 [Default:CheckIn:Read_Iridium] Running Loop=1
2014-09-23T04:21:29.587Z,1411446089.587 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:29.597Z,1411446089.597 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-09-23T04:21:30.264Z,1411446090.264 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:30.741Z,1411446090.741 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:31.176Z,1411446091.176 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:31.576Z,1411446091.576 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:31.980Z,1411446091.980 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:32.381Z,1411446092.381 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:32.780Z,1411446092.780 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:33.193Z,1411446093.193 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:33.605Z,1411446093.605 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:34.061Z,1411446094.061 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:34.491Z,1411446094.491 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:34.907Z,1411446094.907 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:35.305Z,1411446095.305 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:35.762Z,1411446095.762 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:36.170Z,1411446096.170 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:36.567Z,1411446096.567 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:36.979Z,1411446096.979 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:37.423Z,1411446097.423 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:37.860Z,1411446097.860 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:38.266Z,1411446098.266 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:39.186Z,1411446099.186 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:39.700Z,1411446099.700 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:40.080Z,1411446100.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:40.584Z,1411446100.584 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:41.003Z,1411446101.003 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:41.446Z,1411446101.446 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:41.842Z,1411446101.842 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:42.281Z,1411446102.281 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:43.224Z,1411446103.224 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:43.923Z,1411446103.923 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:44.334Z,1411446104.334 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:44.731Z,1411446104.731 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:45.159Z,1411446105.159 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:45.579Z,1411446105.579 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:45.980Z,1411446105.980 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:46.383Z,1411446106.383 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:46.787Z,1411446106.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:47.776Z,1411446107.776 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:48.280Z,1411446108.280 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:48.646Z,1411446108.646 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:49.054Z,1411446109.054 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:49.489Z,1411446109.489 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:50.415Z,1411446110.415 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:50.981Z,1411446110.981 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:51.391Z,1411446111.391 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:51.822Z,1411446111.822 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:52.237Z,1411446112.237 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:52.654Z,1411446112.654 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:53.065Z,1411446113.065 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:53.470Z,1411446113.470 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:53.874Z,1411446113.874 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:54.886Z,1411446114.886 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:55.463Z,1411446115.463 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:55.857Z,1411446115.857 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:56.304Z,1411446116.304 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:56.715Z,1411446116.715 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:57.663Z,1411446117.663 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:58.244Z,1411446118.244 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:58.627Z,1411446118.627 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:59.030Z,1411446119.030 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:59.453Z,1411446119.453 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:21:59.856Z,1411446119.856 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:00.256Z,1411446120.256 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:00.663Z,1411446120.663 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:01.065Z,1411446121.065 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:02.017Z,1411446122.017 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:02.525Z,1411446122.525 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:02.962Z,1411446122.962 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-09-23T04:22:02.962Z,1411446122.962 [DVL_micro] Data Fault, FailCount= 1
2014-09-23T04:22:02.962Z,1411446122.962 [DVL_micro](ERROR): Data Fault
2014-09-23T04:22:02.974Z,1411446122.974 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:02.993Z,1411446122.993 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-09-23T04:22:03.449Z,1411446123.449 [DVL_micro](INFO): uninitialize:Powering down
2014-09-23T04:22:03.480Z,1411446123.480 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:03.870Z,1411446123.870 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-09-23T04:22:03.870Z,1411446123.870 [DVL_micro] Hardware Fault, FailCount= 1
2014-09-23T04:22:03.870Z,1411446123.870 [DVL_micro](ERROR): Hardware Fault
2014-09-23T04:22:03.895Z,1411446123.895 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:04.833Z,1411446124.833 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:04.852Z,1411446124.852 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-23T04:22:04.852Z,1411446124.852 [DVL_micro] No Fault, FailCount= 1
2014-09-23T04:22:05.247Z,1411446125.247 [DVL_micro](INFO): Initializing
2014-09-23T04:22:05.386Z,1411446125.386 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:05.803Z,1411446125.803 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:06.215Z,1411446126.215 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:06.652Z,1411446126.652 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:07.050Z,1411446127.050 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:07.470Z,1411446127.470 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:07.867Z,1411446127.867 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:08.298Z,1411446128.298 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:09.216Z,1411446129.216 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:20.005Z,1411446140.005 [BPC1](FAULT): Failed to receive battery data
2014-09-23T04:22:20.005Z,1411446140.005 [BPC1] Communications Fault, FailCount= 1
2014-09-23T04:22:20.005Z,1411446140.005 [BPC1](ERROR): Communications Fault
2014-09-23T04:22:20.011Z,1411446140.011 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:20.029Z,1411446140.029 [CBIT](ERROR): Communications Fault in component: BPC1
2014-09-23T04:22:20.521Z,1411446140.521 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-09-23T04:22:20.521Z,1411446140.521 [DVL_micro] Communications Fault, FailCount= 2
2014-09-23T04:22:20.521Z,1411446140.521 [DVL_micro](ERROR): Communications Fault
2014-09-23T04:22:20.564Z,1411446140.564 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:20.595Z,1411446140.595 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-09-23T04:22:21.032Z,1411446141.032 [DVL_micro](INFO): uninitialize:Powering down
2014-09-23T04:22:21.043Z,1411446141.043 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:21.479Z,1411446141.479 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:21.518Z,1411446141.518 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-23T04:22:21.518Z,1411446141.518 [BPC1] No Fault, FailCount= 1
2014-09-23T04:22:22.454Z,1411446142.454 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:22.472Z,1411446142.472 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-23T04:22:22.472Z,1411446142.472 [DVL_micro] No Fault, FailCount= 2
2014-09-23T04:22:22.874Z,1411446142.874 [DVL_micro](INFO): Initializing
2014-09-23T04:22:23.036Z,1411446143.036 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:23.441Z,1411446143.441 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:23.844Z,1411446143.844 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:24.269Z,1411446144.269 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:24.674Z,1411446144.674 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:25.077Z,1411446145.077 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:25.487Z,1411446145.487 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:25.888Z,1411446145.888 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:26.832Z,1411446146.832 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:27.411Z,1411446147.411 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:27.778Z,1411446147.778 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:28.190Z,1411446148.190 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:28.602Z,1411446148.602 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:29.524Z,1411446149.524 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:30.084Z,1411446150.084 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:30.490Z,1411446150.490 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:30.903Z,1411446150.903 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:31.328Z,1411446151.328 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:31.739Z,1411446151.739 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:32.143Z,1411446152.143 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:32.576Z,1411446152.576 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:32.976Z,1411446152.976 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:33.925Z,1411446153.925 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:34.427Z,1411446154.427 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:34.881Z,1411446154.881 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:35.348Z,1411446155.348 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:35.760Z,1411446155.760 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:36.708Z,1411446156.708 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:37.280Z,1411446157.280 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:37.717Z,1411446157.717 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:38.203Z,1411446158.203 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:38.629Z,1411446158.629 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:39.008Z,1411446159.008 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:39.431Z,1411446159.431 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:39.837Z,1411446159.837 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:40.264Z,1411446160.264 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:41.206Z,1411446161.206 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:41.729Z,1411446161.729 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:42.137Z,1411446162.137 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:42.540Z,1411446162.540 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:42.957Z,1411446162.957 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:43.904Z,1411446163.904 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:44.432Z,1411446164.432 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:44.813Z,1411446164.813 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:45.217Z,1411446165.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:45.645Z,1411446165.645 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:46.047Z,1411446166.047 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:46.459Z,1411446166.459 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:46.908Z,1411446166.908 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:47.319Z,1411446167.319 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:48.241Z,1411446168.241 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:48.746Z,1411446168.746 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:49.153Z,1411446169.153 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:49.553Z,1411446169.553 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:50.038Z,1411446170.038 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:50.929Z,1411446170.929 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:51.565Z,1411446171.565 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:52.033Z,1411446172.033 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:52.504Z,1411446172.504 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:53.091Z,1411446173.091 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:53.567Z,1411446173.567 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:53.964Z,1411446173.964 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:54.380Z,1411446174.380 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:55.328Z,1411446175.328 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:55.921Z,1411446175.921 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:56.349Z,1411446176.349 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:56.787Z,1411446176.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:57.222Z,1411446177.222 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:58.215Z,1411446178.215 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:58.990Z,1411446178.990 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:22:59.580Z,1411446179.580 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:00.070Z,1411446180.070 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:00.578Z,1411446180.578 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:01.092Z,1411446181.092 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:01.520Z,1411446181.520 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:02.427Z,1411446182.427 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:02.996Z,1411446182.996 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:03.392Z,1411446183.392 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:03.790Z,1411446183.790 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:04.246Z,1411446184.246 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:05.156Z,1411446185.156 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:05.918Z,1411446185.918 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:06.362Z,1411446186.362 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:06.819Z,1411446186.819 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:07.265Z,1411446187.265 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:07.728Z,1411446187.728 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:08.158Z,1411446188.158 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:08.558Z,1411446188.558 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:09.486Z,1411446189.486 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:10.047Z,1411446190.047 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:10.455Z,1411446190.455 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:10.865Z,1411446190.865 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:11.291Z,1411446191.291 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:11.703Z,1411446191.703 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:12.146Z,1411446192.146 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:12.566Z,1411446192.566 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:12.982Z,1411446192.982 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:13.444Z,1411446193.444 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:13.836Z,1411446193.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:14.239Z,1411446194.239 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:14.651Z,1411446194.651 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:15.044Z,1411446195.044 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:15.473Z,1411446195.473 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:15.894Z,1411446195.894 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:16.302Z,1411446196.302 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:16.751Z,1411446196.751 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:17.179Z,1411446197.179 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:17.580Z,1411446197.580 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:18.034Z,1411446198.034 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:18.529Z,1411446198.529 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:18.994Z,1411446198.994 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:19.403Z,1411446199.403 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:20.427Z,1411446200.427 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:21.051Z,1411446201.051 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:21.485Z,1411446201.485 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:21.883Z,1411446201.883 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:22.333Z,1411446202.333 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:22.748Z,1411446202.748 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:23.164Z,1411446203.164 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:23.603Z,1411446203.603 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:24.539Z,1411446204.539 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:25.041Z,1411446205.041 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:25.443Z,1411446205.443 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:25.844Z,1411446205.844 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:26.320Z,1411446206.320 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:26.821Z,1411446206.821 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:27.359Z,1411446207.359 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:27.815Z,1411446207.815 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:27.921Z,1411446207.921 [CommandLine](IMPORTANT): got command restart system
2014-09-23T04:23:28.784Z,1411446208.784 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:43.011Z,1411446223.011 [BPC1](FAULT): Failed to receive battery data
2014-09-23T04:23:43.012Z,1411446223.012 [BPC1] Communications Fault, FailCount= 1
2014-09-23T04:23:43.012Z,1411446223.012 [BPC1](ERROR): Communications Fault
2014-09-23T04:23:43.021Z,1411446223.021 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:43.041Z,1411446223.041 [CBIT](ERROR): Communications Fault in component: BPC1
2014-09-23T04:23:43.453Z,1411446223.453 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-09-23T04:23:43.454Z,1411446223.454 [DVL_micro] Communications Fault, FailCount= 1
2014-09-23T04:23:43.454Z,1411446223.454 [DVL_micro](ERROR): Communications Fault
2014-09-23T04:23:43.466Z,1411446223.466 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:43.486Z,1411446223.486 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-09-23T04:23:43.834Z,1411446223.834 [DVL_micro](INFO): uninitialize:Powering down
2014-09-23T04:23:43.845Z,1411446223.845 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:44.263Z,1411446224.263 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:44.280Z,1411446224.280 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-23T04:23:44.280Z,1411446224.280 [BPC1] No Fault, FailCount= 1
2014-09-23T04:23:44.721Z,1411446224.721 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:44.740Z,1411446224.740 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-23T04:23:44.740Z,1411446224.740 [DVL_micro] No Fault, FailCount= 1
2014-09-23T04:23:45.095Z,1411446225.095 [DVL_micro](INFO): Initializing
2014-09-23T04:23:45.107Z,1411446225.107 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:45.528Z,1411446225.528 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:45.980Z,1411446225.980 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:46.352Z,1411446226.352 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:46.753Z,1411446226.753 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:47.153Z,1411446227.153 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:47.554Z,1411446227.554 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:48.466Z,1411446228.466 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:58.588Z,1411446238.588 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:58.597Z,1411446238.597 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2014-09-23T04:23:58.597Z,1411446238.597 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2014-09-23T04:23:58.598Z,1411446238.598 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2014-09-23T04:23:58.600Z,1411446238.600 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2014-09-23T04:23:58.600Z,1411446238.600 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2014-09-23T04:23:59.034Z,1411446239.034 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:59.042Z,1411446239.042 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 2.386873 min 
2014-09-23T04:23:59.044Z,1411446239.044 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2014-09-23T04:23:59.044Z,1411446239.044 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2014-09-23T04:23:59.045Z,1411446239.045 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2014-09-23T04:23:59.045Z,1411446239.045 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2014-09-23T04:23:59.411Z,1411446239.411 [AcousticModem_Benthos_ATM900](DEBUG): sending wake-up to local modem
2014-09-23T04:23:59.432Z,1411446239.432 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:23:59.824Z,1411446239.824 [AcousticModem_Benthos_ATM900](DEBUG): confirming wake-up of local modem
2014-09-23T04:23:59.893Z,1411446239.893 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:24:00.822Z,1411446240.822 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:24:12.025Z,1411446252.025 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:24:12.561Z,1411446252.561 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-09-23T04:24:12.561Z,1411446252.561 [DVL_micro] Communications Fault, FailCount= 2
2014-09-23T04:24:12.561Z,1411446252.561 [DVL_micro](ERROR): Communications Fault
2014-09-23T04:24:12.618Z,1411446252.618 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:24:12.645Z,1411446252.645 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-09-23T04:24:12.991Z,1411446252.991 [DVL_micro](INFO): uninitialize:Powering down
2014-09-23T04:24:13.003Z,1411446253.003 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:24:13.422Z,1411446253.422 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:24:14.331Z,1411446254.331 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:24:14.349Z,1411446254.349 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-23T04:24:14.349Z,1411446254.349 [DVL_micro] No Fault, FailCount= 2
2014-09-23T04:24:14.728Z,1411446254.728 [DVL_micro](INFO): Initializing
2014-09-23T04:24:15.681Z,1411446255.681 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-23T04:24:15.681Z,1411446255.681 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:15.754Z,1411446255.754 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-09-23T04:24:15.754Z,1411446255.754 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:15.755Z,1411446255.755 [CommandLine](INFO): Join timeout helper Thread ID is 920
2014-09-23T04:24:15.774Z,1411446255.774 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-09-23T04:24:15.774Z,1411446255.774 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:15.775Z,1411446255.775 [NavChartDb](INFO): Join timeout helper Thread ID is 921
2014-09-23T04:24:15.787Z,1411446255.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:24:15.840Z,1411446255.840 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-23T04:24:15.840Z,1411446255.840 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:15.850Z,1411446255.850 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2014-09-23T04:24:15.850Z,1411446255.850 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:15.851Z,1411446255.851 [Radio_Surface](INFO): Join timeout helper Thread ID is 922
2014-09-23T04:24:15.975Z,1411446255.975 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-23T04:24:15.975Z,1411446255.975 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:15.976Z,1411446255.976 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-09-23T04:24:15.976Z,1411446255.976 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:15.976Z,1411446255.976 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 923
2014-09-23T04:24:16.111Z,1411446256.111 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-23T04:24:16.113Z,1411446256.113 [WetLabsBB2FL](INFO): Powering down
2014-09-23T04:24:16.115Z,1411446256.115 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:16.115Z,1411446256.115 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-09-23T04:24:16.116Z,1411446256.116 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:16.116Z,1411446256.116 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 924
2014-09-23T04:24:16.230Z,1411446256.230 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:24:16.380Z,1411446256.380 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-23T04:24:16.380Z,1411446256.380 [CTD_NeilBrown](INFO): Powering down
2014-09-23T04:24:16.382Z,1411446256.382 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:16.396Z,1411446256.396 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-09-23T04:24:16.396Z,1411446256.396 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:16.397Z,1411446256.397 [logger](INFO): Join timeout helper Thread ID is 925
2014-09-23T04:24:16.411Z,1411446256.411 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-23T04:24:16.412Z,1411446256.412 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:16.416Z,1411446256.416 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-09-23T04:24:16.416Z,1411446256.416 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:16.416Z,1411446256.416 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-09-23T04:24:16.416Z,1411446256.416 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:16.417Z,1411446256.417 [controlThread](INFO): Join timeout helper Thread ID is 926
2014-09-23T04:24:16.628Z,1411446256.628 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-23T04:24:16.647Z,1411446256.647 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-23T04:24:16.647Z,1411446256.647 [controlThread](DEBUG): Uninitializing ControlThread
2014-09-23T04:24:16.648Z,1411446256.648 [Aanderaa_O2](INFO): Powering down
2014-09-23T04:24:16.649Z,1411446256.649 [AHRS_sp3003D](INFO): Powering down
2014-09-23T04:24:16.736Z,1411446256.736 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-09-23T04:24:16.928Z,1411446256.928 [DVL_micro](INFO): uninitialize:Powering down
2014-09-23T04:24:16.929Z,1411446256.929 [NAL9602](INFO): Powering down
2014-09-23T04:24:16.931Z,1411446256.931 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-23T04:24:16.933Z,1411446256.933 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-09-23T04:24:16.933Z,1411446256.933 [Default] Stopped
2014-09-23T04:24:16.934Z,1411446256.934 [Default](INFO): Aggregate::uninitialize Default
2014-09-23T04:24:16.934Z,1411446256.934 [Default:B.GoToSurface] Stopped
2014-09-23T04:24:16.934Z,1411446256.934 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-09-23T04:24:16.934Z,1411446256.934 [Default:CheckIn] Stopped
2014-09-23T04:24:16.934Z,1411446256.934 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2014-09-23T04:24:16.934Z,1411446256.934 [Default:CheckIn:A.SetSpeed] Stopped
2014-09-23T04:24:16.934Z,1411446256.934 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2014-09-23T04:24:16.934Z,1411446256.934 [Default:CheckIn:Read_Iridium] Stopped
2014-09-23T04:24:16.934Z,1411446256.934 [Default:WaitAtTheSurface] Stopped
2014-09-23T04:24:16.934Z,1411446256.934 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface
2014-09-23T04:24:16.934Z,1411446256.934 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Stopped
2014-09-23T04:24:16.935Z,1411446256.935 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Uninitialize.
2014-09-23T04:24:16.938Z,1411446256.938 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-09-23T04:24:16.938Z,1411446256.938 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-09-23T04:24:16.939Z,1411446256.939 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-09-23T04:24:16.939Z,1411446256.939 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-09-23T04:24:16.939Z,1411446256.939 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-09-23T04:24:16.940Z,1411446256.940 [BuoyancyServo](INFO): Powering down
2014-09-23T04:24:16.952Z,1411446256.952 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-09-23T04:24:16.952Z,1411446256.952 [ElevatorServo](INFO): Powering down
2014-09-23T04:24:16.953Z,1411446256.953 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-09-23T04:24:16.953Z,1411446256.953 [MassServo](INFO): Powering down
2014-09-23T04:24:16.954Z,1411446256.954 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-09-23T04:24:16.954Z,1411446256.954 [RudderServo](INFO): Powering down
2014-09-23T04:24:16.955Z,1411446256.955 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-09-23T04:24:16.955Z,1411446256.955 [ThrusterServo](INFO): Powering down
2014-09-23T04:24:16.956Z,1411446256.956 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-09-23T04:24:16.957Z,1411446256.957 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-09-23T04:24:16.957Z,1411446256.957 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-09-23T04:24:16.989Z,1411446256.989 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:17.042Z,1411446257.042 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:17.046Z,1411446257.046 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:17.103Z,1411446257.103 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:17.159Z,1411446257.159 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-23T04:24:17.220Z,1411446257.220 [logger ThreadHandler](INFO): Thread cancelled.