2014-09-25T18:11:40.432Z,1411668700.432 [Supervisor](DEBUG): Initializing supervisor. 2014-09-25T18:11:40.434Z,1411668700.434 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0 2014-09-25T18:11:40.435Z,1411668700.435 [SyncHandler](INFO): Protected caller Thread ID is 4252 2014-09-25T18:11:40.435Z,1411668700.435 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2014-09-25T18:11:40.436Z,1411668700.436 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0 2014-09-25T18:11:40.437Z,1411668700.437 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4253 2014-09-25T18:11:40.440Z,1411668700.440 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2014-09-25T18:11:40.452Z,1411668700.452 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2014-09-25T18:11:40.453Z,1411668700.453 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0 2014-09-25T18:11:40.454Z,1411668700.454 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4254 2014-09-25T18:11:40.455Z,1411668700.455 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2014-09-25T18:11:40.456Z,1411668700.456 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0 2014-09-25T18:11:40.456Z,1411668700.456 [logger ThreadHandler](INFO): Protected caller Thread ID is 4255 2014-09-25T18:11:40.458Z,1411668700.458 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2014-09-25T18:11:40.459Z,1411668700.459 [Supervisor](INFO): Looking for Config files in directory: Config/ 2014-09-25T18:11:40.462Z,1411668700.462 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2014-09-25T18:11:40.858Z,1411668700.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2014-09-25T18:11:40.861Z,1411668700.861 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2014-09-25T18:11:41.020Z,1411668701.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2014-09-25T18:11:41.020Z,1411668701.020 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2014-09-25T18:11:41.131Z,1411668701.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2014-09-25T18:11:41.132Z,1411668701.132 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2014-09-25T18:11:41.258Z,1411668701.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2014-09-25T18:11:41.260Z,1411668701.260 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2014-09-25T18:11:41.348Z,1411668701.348 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2014-09-25T18:11:41.495Z,1411668701.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2014-09-25T18:11:41.495Z,1411668701.495 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2014-09-25T18:11:41.583Z,1411668701.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2014-09-25T18:11:41.584Z,1411668701.584 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2014-09-25T18:11:41.785Z,1411668701.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2014-09-25T18:11:41.786Z,1411668701.786 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2014-09-25T18:11:42.124Z,1411668702.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2014-09-25T18:11:42.127Z,1411668702.127 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2014-09-25T18:11:42.430Z,1411668702.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2014-09-25T18:11:42.431Z,1411668702.431 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2014-09-25T18:11:42.932Z,1411668702.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2014-09-25T18:11:42.932Z,1411668702.932 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2014-09-25T18:11:43.136Z,1411668703.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2014-09-25T18:11:43.137Z,1411668703.137 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2014-09-25T18:11:43.242Z,1411668703.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2014-09-25T18:11:43.242Z,1411668703.242 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2014-09-25T18:11:43.678Z,1411668703.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2014-09-25T18:11:55.876Z,1411668715.876 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2014-09-25T18:11:56.139Z,1411668716.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2014-09-25T18:11:56.142Z,1411668716.142 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2014-09-25T18:11:56.940Z,1411668716.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2014-09-25T18:11:57.186Z,1411668717.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2014-09-25T18:11:57.187Z,1411668717.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2014-09-25T18:11:57.305Z,1411668717.305 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg 2014-09-25T18:11:57.403Z,1411668717.403 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2014-09-25T18:11:57.504Z,1411668717.504 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2014-09-25T18:11:57.602Z,1411668717.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2014-09-25T18:11:57.742Z,1411668717.742 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2014-09-25T18:11:57.913Z,1411668717.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2014-09-25T18:11:58.016Z,1411668718.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2014-09-25T18:11:58.104Z,1411668718.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2014-09-25T18:11:58.206Z,1411668718.206 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2014-09-25T18:11:58.304Z,1411668718.304 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2014-09-25T18:11:58.464Z,1411668718.464 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2014-09-25T18:11:58.470Z,1411668718.470 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2014-09-25T18:11:58.536Z,1411668718.536 [VerticalControl](DEBUG): Construct VerticalControl. 2014-09-25T18:11:58.661Z,1411668718.661 [VerticalControl] Loaded 2014-09-25T18:11:58.661Z,1411668718.661 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2014-09-25T18:11:58.662Z,1411668718.662 [HorizontalControl](DEBUG): Construct HorizontalControl. 2014-09-25T18:11:58.737Z,1411668718.737 [HorizontalControl] Loaded 2014-09-25T18:11:58.737Z,1411668718.737 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2014-09-25T18:11:58.738Z,1411668718.738 [SpeedControl](DEBUG): Construct SpeedControl. 2014-09-25T18:11:58.744Z,1411668718.744 [SpeedControl] Loaded 2014-09-25T18:11:58.744Z,1411668718.744 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2014-09-25T18:11:58.745Z,1411668718.745 [LoopControl](DEBUG): Construct LoopControl. 2014-09-25T18:11:58.745Z,1411668718.745 [LoopControl] Loaded 2014-09-25T18:11:58.746Z,1411668718.746 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2014-09-25T18:11:58.746Z,1411668718.746 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2014-09-25T18:11:58.747Z,1411668718.747 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2014-09-25T18:11:58.870Z,1411668718.870 [SBIT](DEBUG): Construct Startup Built In Test. 2014-09-25T18:11:58.881Z,1411668718.881 [SBIT] Loaded 2014-09-25T18:11:58.882Z,1411668718.882 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2014-09-25T18:11:58.882Z,1411668718.882 [IBIT](DEBUG): Construct Initiated Built In Test. 2014-09-25T18:11:58.913Z,1411668718.913 [IBIT] Loaded 2014-09-25T18:11:58.913Z,1411668718.913 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2014-09-25T18:11:58.915Z,1411668718.915 [CBIT](DEBUG): Construct CBIT Built In Test. 2014-09-25T18:11:59.039Z,1411668719.039 [CBIT] Loaded 2014-09-25T18:11:59.039Z,1411668719.039 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2014-09-25T18:11:59.040Z,1411668719.040 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2014-09-25T18:11:59.040Z,1411668719.040 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2014-09-25T18:11:59.069Z,1411668719.069 [DepthRateCalculator] Loaded 2014-09-25T18:11:59.069Z,1411668719.069 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2014-09-25T18:11:59.075Z,1411668719.075 [PitchRateCalculator] Loaded 2014-09-25T18:11:59.075Z,1411668719.075 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2014-09-25T18:11:59.087Z,1411668719.087 [SpeedCalculator] Loaded 2014-09-25T18:11:59.087Z,1411668719.087 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2014-09-25T18:11:59.105Z,1411668719.105 [TempGradientCalculator] Loaded 2014-09-25T18:11:59.105Z,1411668719.105 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2014-09-25T18:11:59.110Z,1411668719.110 [YawRateCalculator] Loaded 2014-09-25T18:11:59.111Z,1411668719.111 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2014-09-25T18:11:59.111Z,1411668719.111 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2014-09-25T18:11:59.112Z,1411668719.112 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2014-09-25T18:12:00.393Z,1411668720.393 [HFRCMReconstructedInterpolator] Loaded 2014-09-25T18:12:00.393Z,1411668720.393 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread. 2014-09-25T18:12:00.394Z,1411668720.394 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2014-09-25T18:12:00.395Z,1411668720.395 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2014-09-25T18:12:00.669Z,1411668720.669 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2014-09-25T18:12:00.670Z,1411668720.670 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2014-09-25T18:12:00.763Z,1411668720.763 [DeadReckonUsingMultipleVelocitySources] Loaded 2014-09-25T18:12:00.763Z,1411668720.763 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2014-09-25T18:12:00.809Z,1411668720.809 [DeadReckonWithRespectToWater] Loaded 2014-09-25T18:12:00.809Z,1411668720.809 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread. 2014-09-25T18:12:00.856Z,1411668720.856 [DeadReckonWithRespectToSeafloor] Loaded 2014-09-25T18:12:00.857Z,1411668720.857 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2014-09-25T18:12:00.909Z,1411668720.909 [DeadReckonUsingDVLWaterTrack] Loaded 2014-09-25T18:12:00.909Z,1411668720.909 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread. 2014-09-25T18:12:00.927Z,1411668720.927 [NavChart] Loaded 2014-09-25T18:12:00.927Z,1411668720.927 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2014-09-25T18:12:00.932Z,1411668720.932 [UniversalFixResidualReporter] Loaded 2014-09-25T18:12:00.933Z,1411668720.933 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2014-09-25T18:12:00.933Z,1411668720.933 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2014-09-25T18:12:00.934Z,1411668720.934 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2014-09-25T18:12:00.947Z,1411668720.947 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2014-09-25T18:12:00.948Z,1411668720.948 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2014-09-25T18:12:01.147Z,1411668721.147 [Aanderaa_O2] Loaded 2014-09-25T18:12:01.147Z,1411668721.147 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2014-09-25T18:12:01.184Z,1411668721.184 [CTD_NeilBrown] Loaded 2014-09-25T18:12:01.185Z,1411668721.185 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2014-09-25T18:12:01.186Z,1411668721.186 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405D94E0 2014-09-25T18:12:01.186Z,1411668721.186 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4337 2014-09-25T18:12:01.205Z,1411668721.205 [rhodamine] Loaded 2014-09-25T18:12:01.205Z,1411668721.205 [ComponentRegistry](DEBUG): SyncComponent "rhodamine" handled in the control thread. 2014-09-25T18:12:01.242Z,1411668721.242 [WetLabsBB2FL] Loaded 2014-09-25T18:12:01.242Z,1411668721.242 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2014-09-25T18:12:01.243Z,1411668721.243 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406094E0 2014-09-25T18:12:01.244Z,1411668721.244 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4338 2014-09-25T18:12:01.244Z,1411668721.244 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2014-09-25T18:12:01.245Z,1411668721.245 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2014-09-25T18:12:13.229Z,1411668733.229 [AHRS_sp3003D] Loaded 2014-09-25T18:12:13.230Z,1411668733.230 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2014-09-25T18:12:13.231Z,1411668733.231 [AcousticModem_Benthos_ATM900](CRITICAL): Could not find element AcousticModem_Benthos_ATM900.loadControl 2014-09-25T18:12:13.232Z,1411668733.232 [AcousticModem_Benthos_ATM900](ERROR): Could not open device '/dev/invalid' at AcousticModem_Benthos_ATM900.loadControl 2014-09-25T18:12:13.232Z,1411668733.232 [AcousticModem_Benthos_ATM900](CRITICAL): Could not find element AcousticModem_Benthos_ATM900.uart 2014-09-25T18:12:13.233Z,1411668733.233 [AcousticModem_Benthos_ATM900](CRITICAL): Could not find element AcousticModem_Benthos_ATM900.baud 2014-09-25T18:12:13.278Z,1411668733.278 [AcousticModem_Benthos_ATM900] Loaded 2014-09-25T18:12:13.278Z,1411668733.278 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2014-09-25T18:12:13.351Z,1411668733.351 [DataOverHttps] Loaded 2014-09-25T18:12:13.351Z,1411668733.351 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2014-09-25T18:12:13.365Z,1411668733.365 [Depth_Keller] Loaded 2014-09-25T18:12:13.366Z,1411668733.366 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2014-09-25T18:12:13.371Z,1411668733.371 [DropWeight] Loaded 2014-09-25T18:12:13.371Z,1411668733.371 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2014-09-25T18:12:13.517Z,1411668733.517 [DVL_micro] Loaded 2014-09-25T18:12:13.517Z,1411668733.517 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2014-09-25T18:12:13.617Z,1411668733.617 [NAL9602] Loaded 2014-09-25T18:12:13.617Z,1411668733.617 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2014-09-25T18:12:13.660Z,1411668733.660 [Onboard] Loaded 2014-09-25T18:12:13.661Z,1411668733.661 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2014-09-25T18:12:13.668Z,1411668733.668 [Radio_Surface] Loaded 2014-09-25T18:12:13.668Z,1411668733.668 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2014-09-25T18:12:13.670Z,1411668733.670 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408534E0 2014-09-25T18:12:13.670Z,1411668733.670 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4339 2014-09-25T18:12:15.604Z,1411668735.604 [BPC1] Loaded 2014-09-25T18:12:15.604Z,1411668735.604 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2014-09-25T18:12:15.604Z,1411668735.604 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2014-09-25T18:12:15.605Z,1411668735.605 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2014-09-25T18:12:15.734Z,1411668735.734 [BuoyancyServo] Loaded 2014-09-25T18:12:15.734Z,1411668735.734 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2014-09-25T18:12:15.747Z,1411668735.747 [ElevatorServo] Loaded 2014-09-25T18:12:15.747Z,1411668735.747 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2014-09-25T18:12:15.759Z,1411668735.759 [MassServo] Loaded 2014-09-25T18:12:15.760Z,1411668735.760 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2014-09-25T18:12:15.772Z,1411668735.772 [RudderServo] Loaded 2014-09-25T18:12:15.772Z,1411668735.772 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2014-09-25T18:12:15.784Z,1411668735.784 [ThrusterServo] Loaded 2014-09-25T18:12:15.784Z,1411668735.784 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2014-09-25T18:12:15.785Z,1411668735.785 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2014-09-25T18:12:15.786Z,1411668735.786 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2014-09-25T18:12:15.986Z,1411668735.986 [InternalSim] Loaded 2014-09-25T18:12:15.986Z,1411668735.986 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2014-09-25T18:12:15.987Z,1411668735.987 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2014-09-25T18:12:15.987Z,1411668735.987 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2014-09-25T18:12:16.026Z,1411668736.026 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2014-09-25T18:12:16.028Z,1411668736.028 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2014-09-25T18:12:16.029Z,1411668736.029 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2014-09-25T18:12:16.036Z,1411668736.036 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2014-09-25T18:12:16.037Z,1411668736.037 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409044E0 2014-09-25T18:12:16.037Z,1411668736.037 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4340 2014-09-25T18:12:16.042Z,1411668736.042 [Supervisor](INFO): Main Thread ID is 764 2014-09-25T18:12:16.042Z,1411668736.042 [Supervisor](DEBUG): Running supervisor. 2014-09-25T18:12:16.043Z,1411668736.043 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4341 2014-09-25T18:12:16.046Z,1411668736.046 [controlThread ThreadHandler](INFO): Handler Thread ID is 4342 2014-09-25T18:12:16.046Z,1411668736.046 [controlThread](DEBUG): Initializing ControlThread 2014-09-25T18:12:16.047Z,1411668736.047 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2014-09-25T18:12:16.049Z,1411668736.049 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2014-09-25T18:12:16.049Z,1411668736.049 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2014-09-25T18:12:16.050Z,1411668736.050 [LoopControl](DEBUG): Initialize LoopControlComponent. 2014-09-25T18:12:16.050Z,1411668736.050 [SBIT](INFO): Initialize SBIT Component. 2014-09-25T18:12:16.051Z,1411668736.051 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11709 2014-09-25T18:12:16.051Z,1411668736.051 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2014-09-25T18:12:16.051Z,1411668736.051 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 2014-09-25T18:12:16.052Z,1411668736.052 [IBIT](INFO): Initialize IBIT Component. 2014-09-25T18:12:16.053Z,1411668736.053 [CBIT](DEBUG): Initialize CBIT Component. 2014-09-25T18:12:16.053Z,1411668736.053 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2014-09-25T18:12:16.054Z,1411668736.054 [logger ThreadHandler](INFO): Handler Thread ID is 4343 2014-09-25T18:12:16.074Z,1411668736.074 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4344 2014-09-25T18:12:16.075Z,1411668736.075 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2014-09-25T18:12:16.078Z,1411668736.078 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2014-09-25T18:12:16.079Z,1411668736.079 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2014-09-25T18:12:16.079Z,1411668736.079 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2014-09-25T18:12:16.079Z,1411668736.079 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2014-09-25T18:12:16.080Z,1411668736.080 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2014-09-25T18:12:16.081Z,1411668736.081 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s. 2014-09-25T18:12:16.083Z,1411668736.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2014-09-25T18:12:16.083Z,1411668736.083 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component. 2014-09-25T18:12:16.083Z,1411668736.083 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2014-09-25T18:12:16.084Z,1411668736.084 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component. 2014-09-25T18:12:16.084Z,1411668736.084 [NavChart](DEBUG): Initialize NavChart Navigation. 2014-09-25T18:12:16.084Z,1411668736.084 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2014-09-25T18:12:16.090Z,1411668736.090 [InternalSim](DEBUG): InternalSim initializing... 2014-09-25T18:12:16.102Z,1411668736.102 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2014-09-25T18:12:16.104Z,1411668736.104 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4346 2014-09-25T18:12:16.105Z,1411668736.105 [WetLabsBB2FL](INFO): Powering down 2014-09-25T18:12:16.131Z,1411668736.131 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4347 2014-09-25T18:12:16.142Z,1411668736.142 [Radio_Surface](INFO): Powering up 2014-09-25T18:12:16.170Z,1411668736.170 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4348 2014-09-25T18:12:16.181Z,1411668736.181 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2014-09-25T18:12:16.181Z,1411668736.181 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2014-09-25T18:12:16.182Z,1411668736.182 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2014-09-25T18:12:16.182Z,1411668736.182 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2014-09-25T18:12:16.182Z,1411668736.182 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2014-09-25T18:12:16.182Z,1411668736.182 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2014-09-25T18:12:16.183Z,1411668736.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2014-09-25T18:12:16.183Z,1411668736.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2014-09-25T18:12:16.183Z,1411668736.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2014-09-25T18:12:16.232Z,1411668736.232 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2014-09-25T18:12:16.260Z,1411668736.260 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2014-09-25T18:12:16.285Z,1411668736.285 [MissionManager](DEBUG): 2014-09-25T18:12:16.294Z,1411668736.294 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2014-09-25T18:12:16.382Z,1411668736.382 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h 2014-09-25T18:12:16.385Z,1411668736.385 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h 2014-09-25T18:12:16.410Z,1411668736.410 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2014-09-25T18:12:16.443Z,1411668736.443 [Default:CheckIn:A.SetSpeed](DEBUG): Construct. 2014-09-25T18:12:16.449Z,1411668736.449 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2014-09-25T18:12:16.476Z,1411668736.476 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct. 2014-09-25T18:12:16.481Z,1411668736.481 [MissionManager](DEBUG): 0 0 5.0 1.0 400 Burn 300 Dropped drop weight due to communications timeout 5.0 Default mission has been running for 5 2014-09-25T18:12:16.500Z,1411668736.500 [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-25T18:12:16.647Z,1411668736.647 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2014-09-25T18:12:16.674Z,1411668736.674 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:12:16.674Z,1411668736.674 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:12:16.674Z,1411668736.674 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:12:16.674Z,1411668736.674 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1 2014-09-25T18:12:16.674Z,1411668736.674 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:12:16.879Z,1411668736.879 [DVL_micro](INFO): Initializing 2014-09-25T18:12:17.105Z,1411668737.105 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:17.121Z,1411668737.121 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:17.145Z,1411668737.145 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:17.159Z,1411668737.159 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:17.178Z,1411668737.178 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:17.292Z,1411668737.292 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2014-09-25T18:12:17.300Z,1411668737.300 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2014-09-25T18:12:17.314Z,1411668737.314 [ElevatorServo](DEBUG): Initializing EZServoServo. 2014-09-25T18:12:17.320Z,1411668737.320 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2014-09-25T18:12:17.342Z,1411668737.342 [MassServo](DEBUG): Initializing EZServoServo. 2014-09-25T18:12:17.348Z,1411668737.348 [MassServo](DEBUG): Initializing MassServo. 2014-09-25T18:12:17.355Z,1411668737.355 [RudderServo](DEBUG): Initializing EZServoServo. 2014-09-25T18:12:17.360Z,1411668737.360 [RudderServo](DEBUG): Initializing RudderServo. 2014-09-25T18:12:17.366Z,1411668737.366 [ThrusterServo](DEBUG): Initializing EZServoServo. 2014-09-25T18:12:17.372Z,1411668737.372 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2014-09-25T18:12:17.418Z,1411668737.418 [CommandLine](FAULT): Scheduling is paused 2014-09-25T18:12:17.419Z,1411668737.419 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:12:17.572Z,1411668737.572 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:12:17.769Z,1411668737.769 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:17.770Z,1411668737.770 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:17.771Z,1411668737.771 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:17.772Z,1411668737.772 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:17.777Z,1411668737.777 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:18.024Z,1411668738.024 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:18.033Z,1411668738.033 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:18.034Z,1411668738.034 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:18.035Z,1411668738.035 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:18.036Z,1411668738.036 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-09-25T18:12:18.692Z,1411668738.692 [NAL9602](INFO): Powering up NAL9602 2014-09-25T18:12:18.820Z,1411668738.820 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:18.860Z,1411668738.860 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:12:18.860Z,1411668738.860 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1 2014-09-25T18:12:19.274Z,1411668739.274 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:19.698Z,1411668739.698 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:19.992Z,1411668739.992 [Aanderaa_O2](INFO): Powering down 2014-09-25T18:12:20.455Z,1411668740.455 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:20.855Z,1411668740.855 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:12:20.855Z,1411668740.855 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:12:20.856Z,1411668740.856 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:12:20.856Z,1411668740.856 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2 2014-09-25T18:12:20.856Z,1411668740.856 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:12:21.146Z,1411668741.146 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:21.333Z,1411668741.333 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:12:21.693Z,1411668741.693 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:12:21.774Z,1411668741.774 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:22.451Z,1411668742.451 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:23.268Z,1411668743.268 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:23.476Z,1411668743.476 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:12:23.476Z,1411668743.476 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2 2014-09-25T18:12:23.869Z,1411668743.869 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:24.306Z,1411668744.306 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:24.710Z,1411668744.710 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:12:24.710Z,1411668744.710 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:12:24.710Z,1411668744.710 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:12:24.710Z,1411668744.710 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3 2014-09-25T18:12:24.710Z,1411668744.710 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:12:24.980Z,1411668744.980 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:25.027Z,1411668745.027 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:12:25.379Z,1411668745.379 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:12:25.412Z,1411668745.412 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:26.085Z,1411668746.085 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:26.740Z,1411668746.740 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:26.790Z,1411668746.790 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:12:26.790Z,1411668746.790 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3 2014-09-25T18:12:27.405Z,1411668747.405 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:27.828Z,1411668747.828 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:28.471Z,1411668748.471 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:28.876Z,1411668748.876 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:12:28.876Z,1411668748.876 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:12:28.877Z,1411668748.877 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:12:28.877Z,1411668748.877 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4 2014-09-25T18:12:28.877Z,1411668748.877 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:12:28.921Z,1411668748.921 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:28.989Z,1411668748.989 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:12:29.337Z,1411668749.337 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:12:29.394Z,1411668749.394 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:29.806Z,1411668749.806 [NAL9602](INFO): NAL9602 initialized 2014-09-25T18:12:30.020Z,1411668750.020 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:30.511Z,1411668750.511 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:30.564Z,1411668750.564 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:12:30.564Z,1411668750.564 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4 2014-09-25T18:12:30.940Z,1411668750.940 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:31.417Z,1411668751.417 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:32.154Z,1411668752.154 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:32.209Z,1411668752.209 [SBIT](IMPORTANT): Beginning Startup BIT 2014-09-25T18:12:32.211Z,1411668752.211 [CBIT](IMPORTANT): Beginning GF scan 2014-09-25T18:12:32.582Z,1411668752.582 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:12:32.582Z,1411668752.582 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:12:32.582Z,1411668752.582 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:12:32.582Z,1411668752.582 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5 2014-09-25T18:12:32.582Z,1411668752.582 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:12:32.652Z,1411668752.652 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:32.756Z,1411668752.756 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:12:33.094Z,1411668753.094 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:12:33.188Z,1411668753.188 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:33.732Z,1411668753.732 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:34.197Z,1411668754.197 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:34.354Z,1411668754.354 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:12:34.354Z,1411668754.354 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5 2014-09-25T18:12:34.739Z,1411668754.739 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:35.263Z,1411668755.263 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:35.732Z,1411668755.732 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:36.211Z,1411668756.211 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:12:36.211Z,1411668756.211 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:12:36.212Z,1411668756.212 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:12:36.212Z,1411668756.212 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6 2014-09-25T18:12:36.212Z,1411668756.212 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:12:36.256Z,1411668756.256 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:36.453Z,1411668756.453 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:12:36.784Z,1411668756.784 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:12:36.834Z,1411668756.834 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:37.294Z,1411668757.294 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:37.785Z,1411668757.785 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:37.899Z,1411668757.899 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:12:37.899Z,1411668757.899 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6 2014-09-25T18:12:38.288Z,1411668758.288 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:38.751Z,1411668758.751 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:39.232Z,1411668759.232 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:39.732Z,1411668759.732 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:40.177Z,1411668760.177 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:12:40.178Z,1411668760.178 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:12:40.178Z,1411668760.178 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:12:40.178Z,1411668760.178 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7 2014-09-25T18:12:40.178Z,1411668760.178 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:12:40.267Z,1411668760.267 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:40.379Z,1411668760.379 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:12:40.722Z,1411668760.722 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:12:40.772Z,1411668760.772 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:41.294Z,1411668761.294 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:41.825Z,1411668761.825 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:41.993Z,1411668761.993 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:12:41.994Z,1411668761.994 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7 2014-09-25T18:12:42.358Z,1411668762.358 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:42.856Z,1411668762.856 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:43.426Z,1411668763.426 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:43.927Z,1411668763.927 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:12:43.927Z,1411668763.927 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:12:43.928Z,1411668763.928 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:12:43.928Z,1411668763.928 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8 2014-09-25T18:12:43.928Z,1411668763.928 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:12:43.985Z,1411668763.985 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:44.133Z,1411668764.133 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:12:44.133Z,1411668764.133 [CBIT](CRITICAL): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:12:44.484Z,1411668764.484 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:12:44.533Z,1411668764.533 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:45.095Z,1411668765.095 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:45.234Z,1411668765.234 [SBIT](FAULT): Mass: EXPECTED:0.006000 ACTUAL:0.002641 2014-09-25T18:12:45.659Z,1411668765.659 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:46.212Z,1411668766.212 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:46.796Z,1411668766.796 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:47.326Z,1411668767.326 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:47.815Z,1411668767.815 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:48.369Z,1411668768.369 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:48.836Z,1411668768.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:49.342Z,1411668769.342 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:49.898Z,1411668769.898 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:50.418Z,1411668770.418 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:50.915Z,1411668770.915 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:51.391Z,1411668771.391 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:51.929Z,1411668771.929 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:52.518Z,1411668772.518 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:53.062Z,1411668773.062 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:53.612Z,1411668773.612 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:54.127Z,1411668774.127 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:54.626Z,1411668774.626 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:55.194Z,1411668775.194 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:55.671Z,1411668775.671 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:56.184Z,1411668776.184 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:56.656Z,1411668776.656 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:57.167Z,1411668777.167 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:57.630Z,1411668777.630 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:58.078Z,1411668778.078 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:58.535Z,1411668778.535 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:59.032Z,1411668779.032 [BPC1](ERROR): B bank expecting battery number:1 and read number: uart read:X $S,01,5D4E,02,00,04,62%50 $C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72 $B11,0A,FFEB%34 $B12,0A,FFE8%4D $B13,0A,FFEA%35 $B14,0A,FFE8%4B $B15,0A,FFE9%4B $B16,0A,FFEB%33 $B17,0A,0000%35 $B18,0A,0000%3A $S,01,57F4,02,00,04,62%20 $C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72 $B11,02,000A,01,026C,03,0001,08,0B5F,09,403F,0A,FFEC,0B,FFED%30 $B11,0C,0001,0D,0063,0E,005C,0F,163B,10,1644,11,42B1,12,4634%37 $B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%41 $B11,1A,0031,1B,4357,1C,009D%49 $B12,02,000A,01,026C,03,0001,08,0B66,09,4041,0A,FFED,0B,FFEC%30 $B12,0C,0001,0D,0063,0E,0061,0F,1768,10,17CA,11,49EA,12,4638%48 $B12,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4F $B12,1A,0031,1B,4357,1C,0093%3D $B13,02,000A,01,026C,03,0001,08,0B71,09,4041,0A,FFEE,0B,FFEC%36 $B13,0C,0001,0D,0063,0E,005E,0F,16A6,10,170A,11,4B7F,12,43F2%41 $B13,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4E $B13,1A,0031,1B,4333,1C,0068%3A $B14,02,000A,01,026C,03,0001,08,0B6D,09,4040,0A,FFEE,0B,FFEC%44 $B14,0C,0001,0D,0063,0E,005C,0F,1644,10,16A8,11,4A38,12,42CC%32 $B14,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%49 $B14,1A,0031,1B,4357,1C,008D%4D $B15,02,000A,01,026C,03,0001,08,0B70,09,4040,0A,FFF3,0B,FFEE%43 $B15,0C,0001,0D,0063,0E,005D,0F,165B,10,16B0,11,672E,12,4A85%3E $B15,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%48 $B15,1A,0031,1B,4357,1C,008A%49 $B16,02,000A,01,026C,03,0001,08,0B61,09,4055,0A,FFF4,0B,FFEF%40 $B16,0C,0001,0D,0063,0E,005D,0F,165C,10,16B1,11,6FCC,12,4EEA%36 $B16,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4B $B16,1A,0031,1B,4357,1C,00B9%48 $B17,02,000A,01,026C,03,0001,08,0B6B,09,3D3D,0A,0000,0B,0000%47 $B17,0C,0001,0D,0060,0E,005B,0F,15E9,10,16FD,11,FFFF,12,FFFF%4F $B17,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4A $B17,1A,0031,1B,4357,1C,00A5%46 $B18,02,000A,01,026C,03,0001,08,0B71,09,3CB8,0A,0000,0B,0000%30 $B18,0C,0001,0D,0064,0E,005A,0F,15A9,10,169E,11,FFFF,12,FFFF%3D $B18,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%45 $B18,1A,0031,1B,4357,1C,00AE%39 2014-09-25T18:12:59.037Z,1411668779.037 [BPC1](FAULT): Failed to parse bank B battery data 2014-09-25T18:12:59.037Z,1411668779.037 [BPC1] Data Fault, FailCount= 1 2014-09-25T18:12:59.037Z,1411668779.037 [BPC1](ERROR): Data Fault 2014-09-25T18:12:59.052Z,1411668779.052 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:12:59.185Z,1411668779.185 [CBIT](ERROR): Data Fault in component: BPC1 2014-09-25T18:12:59.186Z,1411668779.186 [CBIT](IMPORTANT): No ground fault detected 2014-09-25T18:12:59.629Z,1411668779.629 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:00.097Z,1411668780.097 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:00.554Z,1411668780.554 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:00.663Z,1411668780.663 [CBIT](INFO): Clearing failed state for component BPC1 2014-09-25T18:13:00.664Z,1411668780.664 [BPC1] No Fault, FailCount= 1 2014-09-25T18:13:01.120Z,1411668781.120 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:01.620Z,1411668781.620 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:02.093Z,1411668782.093 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:02.554Z,1411668782.554 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:03.106Z,1411668783.106 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:03.557Z,1411668783.557 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:04.007Z,1411668784.007 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:04.476Z,1411668784.476 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:04.965Z,1411668784.965 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:05.464Z,1411668785.464 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:05.951Z,1411668785.951 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:06.429Z,1411668786.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:06.900Z,1411668786.900 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:07.392Z,1411668787.392 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:07.862Z,1411668787.862 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:08.298Z,1411668788.298 [NAL9602](IMPORTANT): GPS fix at: 1411668777.00 2014-09-25T18:13:08.317Z,1411668788.317 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:08.839Z,1411668788.839 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:09.352Z,1411668789.352 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:09.900Z,1411668789.900 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:10.380Z,1411668790.380 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:10.878Z,1411668790.878 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:11.370Z,1411668791.370 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:11.870Z,1411668791.870 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:12.341Z,1411668792.341 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:12.872Z,1411668792.872 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:13.495Z,1411668793.495 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:13.985Z,1411668793.985 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:14.453Z,1411668794.453 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:15.012Z,1411668795.012 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:15.878Z,1411668795.878 [NAL9602](INFO): Powering down 2014-09-25T18:13:15.919Z,1411668795.919 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:16.516Z,1411668796.516 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:17.160Z,1411668797.160 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:17.661Z,1411668797.661 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:18.196Z,1411668798.196 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:18.731Z,1411668798.731 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:19.164Z,1411668799.164 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:19.598Z,1411668799.598 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:20.102Z,1411668800.102 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:20.561Z,1411668800.561 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:20.976Z,1411668800.976 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:21.375Z,1411668801.375 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:21.784Z,1411668801.784 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:22.188Z,1411668802.188 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:22.593Z,1411668802.593 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:22.986Z,1411668802.986 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:23.390Z,1411668803.390 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:23.854Z,1411668803.854 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:24.250Z,1411668804.250 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:24.652Z,1411668804.652 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:25.053Z,1411668805.053 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:25.454Z,1411668805.454 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:25.854Z,1411668805.854 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:25.889Z,1411668805.889 [SBIT](CRITICAL): SBIT FAILED 2014-09-25T18:13:26.263Z,1411668806.263 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:26.290Z,1411668806.290 [MissionManager](IMPORTANT): Started mission Startup 2014-09-25T18:13:26.291Z,1411668806.291 [Startup] Running Loop=1 2014-09-25T18:13:26.291Z,1411668806.291 [Startup](INFO): Aggregate::initialize Startup 2014-09-25T18:13:26.291Z,1411668806.291 [Startup:A.GoToSurface] Running Loop=1 2014-09-25T18:13:26.291Z,1411668806.291 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-09-25T18:13:26.292Z,1411668806.292 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2014-09-25T18:13:26.292Z,1411668806.292 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2014-09-25T18:13:26.292Z,1411668806.292 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s. 2014-09-25T18:13:26.298Z,1411668806.298 [Startup:StartupSatComms] Running Loop=1 2014-09-25T18:13:26.299Z,1411668806.299 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2014-09-25T18:13:26.299Z,1411668806.299 [Startup:StartupSatComms:A] Running Loop=1 2014-09-25T18:13:26.704Z,1411668806.704 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:26.717Z,1411668806.717 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2014-09-25T18:13:27.161Z,1411668807.161 [NAL9602](INFO): Powering up 2014-09-25T18:13:27.171Z,1411668807.171 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:27.604Z,1411668807.604 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:28.031Z,1411668808.031 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:28.867Z,1411668808.867 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:29.271Z,1411668809.271 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:29.704Z,1411668809.704 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:30.151Z,1411668810.151 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:30.613Z,1411668810.613 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:31.071Z,1411668811.071 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:31.536Z,1411668811.536 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:31.968Z,1411668811.968 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:32.396Z,1411668812.396 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:32.827Z,1411668812.827 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:33.245Z,1411668813.245 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:33.696Z,1411668813.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:34.148Z,1411668814.148 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:34.579Z,1411668814.579 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:35.000Z,1411668815.000 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:35.424Z,1411668815.424 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:35.847Z,1411668815.847 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:36.279Z,1411668816.279 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:36.752Z,1411668816.752 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:37.187Z,1411668817.187 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:37.632Z,1411668817.632 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:38.036Z,1411668818.036 [NAL9602](INFO): NAL9602 initialized 2014-09-25T18:13:38.046Z,1411668818.046 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:38.481Z,1411668818.481 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:38.916Z,1411668818.916 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:39.371Z,1411668819.371 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:39.863Z,1411668819.863 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:40.298Z,1411668820.298 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:40.718Z,1411668820.718 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:41.159Z,1411668821.159 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:41.597Z,1411668821.597 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:42.049Z,1411668822.049 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:42.497Z,1411668822.497 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:42.984Z,1411668822.984 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:43.437Z,1411668823.437 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:43.915Z,1411668823.915 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:44.332Z,1411668824.332 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:44.783Z,1411668824.783 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:45.272Z,1411668825.272 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:45.712Z,1411668825.712 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:46.217Z,1411668826.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:46.674Z,1411668826.674 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:47.107Z,1411668827.107 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:47.525Z,1411668827.525 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:47.960Z,1411668827.960 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:48.408Z,1411668828.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:48.842Z,1411668828.842 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:49.294Z,1411668829.294 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:49.824Z,1411668829.824 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:50.228Z,1411668830.228 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:50.652Z,1411668830.652 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:51.067Z,1411668831.067 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:51.485Z,1411668831.485 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:51.887Z,1411668831.887 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:52.284Z,1411668832.284 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:52.739Z,1411668832.739 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:53.147Z,1411668833.147 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:53.544Z,1411668833.544 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:53.973Z,1411668833.973 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:54.394Z,1411668834.394 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:54.796Z,1411668834.796 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:55.196Z,1411668835.196 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:55.618Z,1411668835.618 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:56.075Z,1411668836.075 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:56.484Z,1411668836.484 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:56.898Z,1411668836.898 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:57.334Z,1411668837.334 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:57.773Z,1411668837.773 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:58.148Z,1411668838.148 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:58.546Z,1411668838.546 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:59.022Z,1411668839.022 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:59.408Z,1411668839.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:13:59.847Z,1411668839.847 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:00.331Z,1411668840.331 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:00.765Z,1411668840.765 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:01.186Z,1411668841.186 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:01.586Z,1411668841.586 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:01.993Z,1411668841.993 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:02.429Z,1411668842.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:02.867Z,1411668842.867 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:03.283Z,1411668843.283 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:03.691Z,1411668843.691 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:04.086Z,1411668844.086 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:04.490Z,1411668844.490 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:04.890Z,1411668844.890 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:05.292Z,1411668845.292 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:05.751Z,1411668845.751 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:06.175Z,1411668846.175 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:06.589Z,1411668846.589 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:06.987Z,1411668846.987 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:07.392Z,1411668847.392 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:07.795Z,1411668847.795 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:08.201Z,1411668848.201 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:08.631Z,1411668848.631 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:09.048Z,1411668849.048 [NAL9602](IMPORTANT): GPS fix at: 1411668837.00 2014-09-25T18:14:09.059Z,1411668849.059 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:09.090Z,1411668849.090 [Startup:StartupSatComms:A] Stopped 2014-09-25T18:14:09.090Z,1411668849.090 [Startup:StartupSatComms:B] Running Loop=1 2014-09-25T18:14:09.513Z,1411668849.513 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:09.540Z,1411668849.540 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2014-09-25T18:14:10.234Z,1411668850.234 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:10.633Z,1411668850.633 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:11.040Z,1411668851.040 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:11.488Z,1411668851.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:12.437Z,1411668852.437 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:13.063Z,1411668853.063 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:13.564Z,1411668853.564 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:14.005Z,1411668854.005 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:14.452Z,1411668854.452 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:14.884Z,1411668854.884 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:15.284Z,1411668855.284 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:15.712Z,1411668855.712 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:16.729Z,1411668856.729 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:17.208Z,1411668857.208 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:17.610Z,1411668857.610 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:18.011Z,1411668858.011 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:18.451Z,1411668858.451 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:19.369Z,1411668859.369 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:19.886Z,1411668859.886 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:20.321Z,1411668860.321 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:20.724Z,1411668860.724 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:21.158Z,1411668861.158 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:21.561Z,1411668861.561 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:21.981Z,1411668861.981 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:22.466Z,1411668862.466 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:22.920Z,1411668862.920 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:23.859Z,1411668863.859 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:24.332Z,1411668864.332 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:24.734Z,1411668864.734 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:25.152Z,1411668865.152 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:25.566Z,1411668865.566 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:26.536Z,1411668866.536 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:27.035Z,1411668867.035 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:27.425Z,1411668867.425 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:27.843Z,1411668867.843 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:28.268Z,1411668868.268 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:28.669Z,1411668868.669 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:29.129Z,1411668869.129 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:29.538Z,1411668869.538 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:30.015Z,1411668870.015 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:30.920Z,1411668870.920 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:31.420Z,1411668871.420 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:31.825Z,1411668871.825 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:32.224Z,1411668872.224 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:32.645Z,1411668872.645 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:33.603Z,1411668873.603 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:34.050Z,1411668874.050 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:34.475Z,1411668874.475 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:34.878Z,1411668874.878 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:35.296Z,1411668875.296 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:35.695Z,1411668875.695 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:36.103Z,1411668876.103 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:36.543Z,1411668876.543 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:36.988Z,1411668876.988 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:37.926Z,1411668877.926 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:38.379Z,1411668878.379 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:38.748Z,1411668878.748 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:39.157Z,1411668879.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:39.592Z,1411668879.592 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:40.538Z,1411668880.538 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:41.006Z,1411668881.006 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:41.431Z,1411668881.431 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:41.868Z,1411668881.868 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:42.292Z,1411668882.292 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:42.705Z,1411668882.705 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:43.123Z,1411668883.123 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:43.567Z,1411668883.567 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:44.056Z,1411668884.056 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:44.980Z,1411668884.980 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:45.440Z,1411668885.440 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:45.863Z,1411668885.863 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:46.313Z,1411668886.313 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:46.877Z,1411668886.877 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:47.835Z,1411668887.835 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:48.330Z,1411668888.330 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:48.723Z,1411668888.723 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:49.132Z,1411668889.132 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:49.568Z,1411668889.568 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:49.992Z,1411668889.992 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:50.427Z,1411668890.427 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:50.834Z,1411668890.834 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:51.279Z,1411668891.279 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:52.204Z,1411668892.204 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:52.639Z,1411668892.639 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:53.044Z,1411668893.044 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:53.470Z,1411668893.470 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:53.930Z,1411668893.930 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:54.836Z,1411668894.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:55.284Z,1411668895.284 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:55.687Z,1411668895.687 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:56.174Z,1411668896.174 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:56.611Z,1411668896.611 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:57.004Z,1411668897.004 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:57.455Z,1411668897.455 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:57.876Z,1411668897.876 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:58.291Z,1411668898.291 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:59.242Z,1411668899.242 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:14:59.772Z,1411668899.772 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:00.205Z,1411668900.205 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:00.618Z,1411668900.618 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:01.059Z,1411668901.059 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:01.967Z,1411668901.967 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:02.477Z,1411668902.477 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:02.977Z,1411668902.977 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:03.417Z,1411668903.417 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:03.838Z,1411668903.838 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:04.248Z,1411668904.248 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:04.656Z,1411668904.656 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:05.072Z,1411668905.072 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:05.480Z,1411668905.480 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:06.398Z,1411668906.398 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:06.937Z,1411668906.937 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:07.437Z,1411668907.437 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:07.874Z,1411668907.874 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:08.326Z,1411668908.326 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:09.224Z,1411668909.224 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:09.233Z,1411668909.233 [Startup:StartupSatComms:B](INFO): Timed out from 2014-09-25T18:14:09.1Z 2014-09-25T18:15:09.233Z,1411668909.233 [Startup:StartupSatComms:B] Stopped 2014-09-25T18:15:09.233Z,1411668909.233 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2014-09-25T18:15:09.233Z,1411668909.233 [Startup:StartupSatComms] Stopped 2014-09-25T18:15:09.233Z,1411668909.233 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2014-09-25T18:15:09.234Z,1411668909.234 [Startup](INFO): Completed Startup 2014-09-25T18:15:09.234Z,1411668909.234 [Startup] Stopped 2014-09-25T18:15:09.234Z,1411668909.234 [Startup](INFO): Aggregate::uninitialize Startup 2014-09-25T18:15:09.234Z,1411668909.234 [Startup:A.GoToSurface] Stopped 2014-09-25T18:15:09.234Z,1411668909.234 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-09-25T18:15:09.700Z,1411668909.700 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:09.709Z,1411668909.709 [MissionManager](IMPORTANT): Started mission Default 2014-09-25T18:15:09.709Z,1411668909.709 [Default] Running Loop=1 2014-09-25T18:15:09.709Z,1411668909.709 [Default](INFO): Aggregate::initialize Default 2014-09-25T18:15:09.709Z,1411668909.709 [Default:B.GoToSurface] Running Loop=1 2014-09-25T18:15:09.709Z,1411668909.709 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-09-25T18:15:09.709Z,1411668909.709 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2014-09-25T18:15:09.710Z,1411668909.710 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2014-09-25T18:15:09.710Z,1411668909.710 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s. 2014-09-25T18:15:09.710Z,1411668909.710 [Default:StartClock] Running Loop=1 2014-09-25T18:15:09.710Z,1411668909.710 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock 2014-09-25T18:15:09.711Z,1411668909.711 [Default:StartClock:A] Running Loop=1 2014-09-25T18:15:09.711Z,1411668909.711 [Default:CheckIn] Running Loop=1 2014-09-25T18:15:09.711Z,1411668909.711 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn 2014-09-25T18:15:09.711Z,1411668909.711 [Default:CheckIn:A.SetSpeed] Running Loop=1 2014-09-25T18:15:09.711Z,1411668909.711 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize. 2014-09-25T18:15:09.712Z,1411668909.712 [Default:CheckIn:Read_GPS] Running Loop=1 2014-09-25T18:15:09.713Z,1411668909.713 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2014-09-25T18:15:09.714Z,1411668909.714 [Default:CheckIn:A.SetSpeed] Running Loop=1 2014-09-25T18:15:09.751Z,1411668909.751 [Default:StartClock:A] Stopped 2014-09-25T18:15:09.751Z,1411668909.751 [Default:StartClock](INFO): Completed Default:StartClock 2014-09-25T18:15:09.751Z,1411668909.751 [Default:StartClock] Stopped 2014-09-25T18:15:09.751Z,1411668909.751 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock 2014-09-25T18:15:10.168Z,1411668910.168 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:10.178Z,1411668910.178 [Default:WaitAtTheSurface] Running Loop=1 2014-09-25T18:15:10.178Z,1411668910.178 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface 2014-09-25T18:15:10.178Z,1411668910.178 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1 2014-09-25T18:15:10.178Z,1411668910.178 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize. 2014-09-25T18:15:10.603Z,1411668910.603 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:10.611Z,1411668910.611 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1 2014-09-25T18:15:11.005Z,1411668911.005 [NAL9602](IMPORTANT): GPS fix at: 1411668899.00 2014-09-25T18:15:11.015Z,1411668911.015 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:11.026Z,1411668911.026 [Default:CheckIn:Read_GPS] Stopped 2014-09-25T18:15:11.026Z,1411668911.026 [Default:CheckIn:Read_Iridium] Running Loop=1 2014-09-25T18:15:11.442Z,1411668911.442 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:11.469Z,1411668911.469 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2014-09-25T18:15:12.072Z,1411668912.072 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:12.513Z,1411668912.513 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:12.933Z,1411668912.933 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:13.401Z,1411668913.401 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:14.334Z,1411668914.334 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:14.828Z,1411668914.828 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:15.279Z,1411668915.279 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:15.689Z,1411668915.689 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:16.179Z,1411668916.179 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:16.617Z,1411668916.617 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:17.085Z,1411668917.085 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:17.540Z,1411668917.540 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:18.460Z,1411668918.460 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:18.921Z,1411668918.921 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:19.299Z,1411668919.299 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:19.762Z,1411668919.762 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:20.188Z,1411668920.188 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:21.109Z,1411668921.109 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:21.590Z,1411668921.590 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:21.992Z,1411668921.992 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2014-09-25T18:15:21.992Z,1411668921.992 [DVL_micro] Data Fault, FailCount= 1 2014-09-25T18:15:21.992Z,1411668921.992 [DVL_micro](ERROR): Data Fault 2014-09-25T18:15:22.004Z,1411668922.004 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:22.023Z,1411668922.023 [CBIT](ERROR): Data Fault in component: DVL_micro 2014-09-25T18:15:22.481Z,1411668922.481 [DVL_micro](INFO): uninitialize:Powering down 2014-09-25T18:15:22.503Z,1411668922.503 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:22.932Z,1411668922.932 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:23.350Z,1411668923.350 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:23.372Z,1411668923.372 [CBIT](INFO): Clearing failed state for component DVL_micro 2014-09-25T18:15:23.372Z,1411668923.372 [DVL_micro] No Fault, FailCount= 1 2014-09-25T18:15:23.749Z,1411668923.749 [DVL_micro](INFO): Initializing 2014-09-25T18:15:23.761Z,1411668923.761 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:24.159Z,1411668924.159 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:24.567Z,1411668924.567 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:25.498Z,1411668925.498 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:26.021Z,1411668926.021 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:26.500Z,1411668926.500 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:26.914Z,1411668926.914 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:27.386Z,1411668927.386 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:28.285Z,1411668928.285 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:28.737Z,1411668928.737 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:29.186Z,1411668929.186 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:29.616Z,1411668929.616 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:30.080Z,1411668930.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:30.490Z,1411668930.490 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:30.937Z,1411668930.937 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:31.348Z,1411668931.348 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:31.822Z,1411668931.822 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:32.781Z,1411668932.781 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:33.271Z,1411668933.271 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:33.716Z,1411668933.716 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:34.146Z,1411668934.146 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:34.572Z,1411668934.572 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:35.492Z,1411668935.492 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:35.996Z,1411668935.996 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:36.492Z,1411668936.492 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:36.950Z,1411668936.950 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:37.390Z,1411668937.390 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:37.796Z,1411668937.796 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:38.205Z,1411668938.205 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:38.652Z,1411668938.652 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:39.062Z,1411668939.062 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:39.978Z,1411668939.978 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:40.462Z,1411668940.462 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:40.878Z,1411668940.878 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:41.313Z,1411668941.313 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:41.749Z,1411668941.749 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:42.706Z,1411668942.706 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:43.157Z,1411668943.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:43.587Z,1411668943.587 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:44.027Z,1411668944.027 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:44.465Z,1411668944.465 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:44.946Z,1411668944.946 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:45.366Z,1411668945.366 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:45.792Z,1411668945.792 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:46.218Z,1411668946.218 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:47.187Z,1411668947.187 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:47.668Z,1411668947.668 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:48.080Z,1411668948.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:48.532Z,1411668948.532 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:48.973Z,1411668948.973 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:49.897Z,1411668949.897 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:50.391Z,1411668950.391 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:50.786Z,1411668950.786 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:51.196Z,1411668951.196 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:51.636Z,1411668951.636 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:52.034Z,1411668952.034 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:52.438Z,1411668952.438 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:52.886Z,1411668952.886 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:53.284Z,1411668953.284 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:54.228Z,1411668954.228 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:54.676Z,1411668954.676 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:55.083Z,1411668955.083 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:55.484Z,1411668955.484 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:55.897Z,1411668955.897 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:56.840Z,1411668956.840 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:57.334Z,1411668957.334 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:57.740Z,1411668957.740 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:58.155Z,1411668958.155 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:58.629Z,1411668958.629 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:59.033Z,1411668959.033 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:59.466Z,1411668959.466 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:15:59.914Z,1411668959.914 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:00.365Z,1411668960.365 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:01.352Z,1411668961.352 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:01.790Z,1411668961.790 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:02.236Z,1411668962.236 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:02.670Z,1411668962.670 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:03.091Z,1411668963.091 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:04.033Z,1411668964.033 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:04.488Z,1411668964.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:04.903Z,1411668964.903 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:05.308Z,1411668965.308 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:05.754Z,1411668965.754 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:06.168Z,1411668966.168 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:06.612Z,1411668966.612 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:07.033Z,1411668967.033 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:07.460Z,1411668967.460 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:08.390Z,1411668968.390 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:08.858Z,1411668968.858 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:09.252Z,1411668969.252 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:09.676Z,1411668969.676 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:10.184Z,1411668970.184 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:11.123Z,1411668971.123 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:11.589Z,1411668971.589 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:11.965Z,1411668971.965 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:12.381Z,1411668972.381 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:12.802Z,1411668972.802 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:13.226Z,1411668973.226 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:13.683Z,1411668973.683 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:14.110Z,1411668974.110 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:14.513Z,1411668974.513 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:15.465Z,1411668975.465 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:15.912Z,1411668975.912 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:16.338Z,1411668976.338 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:16.780Z,1411668976.780 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:17.194Z,1411668977.194 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:18.174Z,1411668978.174 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:18.648Z,1411668978.648 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:19.554Z,1411668979.554 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:19.991Z,1411668979.991 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:20.427Z,1411668980.427 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:20.821Z,1411668980.821 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003306 2014-09-25T18:16:20.839Z,1411668980.839 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:21.264Z,1411668981.264 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:22.194Z,1411668982.194 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:23.157Z,1411668983.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:23.553Z,1411668983.553 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20140924T224954/Courier0768.lzma 2014-09-25T18:16:23.593Z,1411668983.593 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:23.993Z,1411668983.993 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:24.396Z,1411668984.396 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:24.808Z,1411668984.808 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:25.246Z,1411668985.246 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:25.653Z,1411668985.653 [DataOverHttps](INFO): Moved sent file to Logs/20140924T224954/Courier0768.lzma.bak 2014-09-25T18:16:25.653Z,1411668985.653 [DataOverHttps](INFO): SBD MOMSN=1207614 2014-09-25T18:16:25.699Z,1411668985.699 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:26.173Z,1411668986.173 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:26.578Z,1411668986.578 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:27.451Z,1411668987.451 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:27.862Z,1411668987.862 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:28.266Z,1411668988.266 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:28.661Z,1411668988.661 [NAL9602](INFO): Powering down 2014-09-25T18:16:28.673Z,1411668988.673 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:29.120Z,1411668989.120 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:29.582Z,1411668989.582 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:30.482Z,1411668990.482 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:30.899Z,1411668990.899 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:31.347Z,1411668991.347 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:31.743Z,1411668991.743 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:32.176Z,1411668992.176 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:32.606Z,1411668992.606 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:33.648Z,1411668993.648 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:34.133Z,1411668994.133 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:34.534Z,1411668994.534 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:34.952Z,1411668994.952 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:35.357Z,1411668995.357 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:35.803Z,1411668995.803 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:36.830Z,1411668996.830 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:36.862Z,1411668996.862 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:37.327Z,1411668997.327 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:37.347Z,1411668997.347 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:37.759Z,1411668997.759 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:37.783Z,1411668997.783 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:38.178Z,1411668998.178 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:38.188Z,1411668998.188 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:38.596Z,1411668998.596 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:38.607Z,1411668998.607 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:39.017Z,1411668999.017 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:39.027Z,1411668999.027 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:39.903Z,1411668999.903 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:39.957Z,1411668999.957 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:40.371Z,1411669000.371 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:40.383Z,1411669000.383 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:40.783Z,1411669000.783 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:40.805Z,1411669000.805 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:41.208Z,1411669001.208 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:41.219Z,1411669001.219 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:41.617Z,1411669001.617 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:41.628Z,1411669001.628 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:42.033Z,1411669002.033 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:42.044Z,1411669002.044 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:42.913Z,1411669002.913 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:42.927Z,1411669002.927 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:43.331Z,1411669003.331 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:43.422Z,1411669003.422 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:43.812Z,1411669003.812 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:43.825Z,1411669003.825 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:44.221Z,1411669004.221 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:44.231Z,1411669004.231 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:44.630Z,1411669004.630 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:44.680Z,1411669004.680 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:45.074Z,1411669005.074 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:45.084Z,1411669005.084 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:45.928Z,1411669005.928 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:45.971Z,1411669005.971 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:46.369Z,1411669006.369 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:46.402Z,1411669006.402 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:46.809Z,1411669006.809 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:46.851Z,1411669006.851 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:47.242Z,1411669007.242 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:47.252Z,1411669007.252 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:47.650Z,1411669007.650 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:47.696Z,1411669007.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:48.092Z,1411669008.092 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:48.102Z,1411669008.102 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:49.002Z,1411669009.002 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:49.022Z,1411669009.022 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:49.422Z,1411669009.422 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:49.454Z,1411669009.454 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:49.878Z,1411669009.878 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:49.896Z,1411669009.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:50.296Z,1411669010.296 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:50.306Z,1411669010.306 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:50.707Z,1411669010.707 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:50.735Z,1411669010.735 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:51.128Z,1411669011.128 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:51.138Z,1411669011.138 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:51.997Z,1411669011.997 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:52.007Z,1411669012.007 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:52.415Z,1411669012.415 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:52.440Z,1411669012.440 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:52.834Z,1411669012.834 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:52.867Z,1411669012.867 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:53.296Z,1411669013.296 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:53.308Z,1411669013.308 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:53.740Z,1411669013.740 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:53.761Z,1411669013.761 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:54.160Z,1411669014.160 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:54.171Z,1411669014.171 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:55.079Z,1411669015.079 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:55.091Z,1411669015.091 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:55.491Z,1411669015.491 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:55.512Z,1411669015.512 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:55.896Z,1411669015.896 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:55.935Z,1411669015.935 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:56.344Z,1411669016.344 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:56.365Z,1411669016.365 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:56.740Z,1411669016.740 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:56.763Z,1411669016.763 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:57.153Z,1411669017.153 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:57.163Z,1411669017.163 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:58.017Z,1411669018.017 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:58.028Z,1411669018.028 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:58.443Z,1411669018.443 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:58.489Z,1411669018.489 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:58.889Z,1411669018.889 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:58.940Z,1411669018.940 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:59.352Z,1411669019.352 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:59.403Z,1411669019.403 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:16:59.797Z,1411669019.797 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:16:59.822Z,1411669019.822 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:00.226Z,1411669020.226 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:00.295Z,1411669020.295 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:01.168Z,1411669021.168 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:01.178Z,1411669021.178 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:01.593Z,1411669021.593 [DataOverHttps](INFO): Sending 363 bytes from file Logs/20140925T181140/Courier0000.lzma 2014-09-25T18:17:01.598Z,1411669021.598 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:01.642Z,1411669021.642 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:02.024Z,1411669022.024 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:02.098Z,1411669022.098 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:02.485Z,1411669022.485 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:02.497Z,1411669022.497 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:02.898Z,1411669022.898 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:02.909Z,1411669022.909 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:03.310Z,1411669023.310 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:03.336Z,1411669023.336 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:03.719Z,1411669023.719 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:03.739Z,1411669023.739 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:04.130Z,1411669024.130 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:04.141Z,1411669024.141 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:04.531Z,1411669024.531 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:04.545Z,1411669024.545 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:04.912Z,1411669024.912 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:04.922Z,1411669024.922 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:05.306Z,1411669025.306 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:05.365Z,1411669025.365 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:05.732Z,1411669025.732 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:05.744Z,1411669025.744 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:06.171Z,1411669026.171 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:06.222Z,1411669026.222 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:06.641Z,1411669026.641 [DataOverHttps](INFO): Moved sent file to Logs/20140925T181140/Courier0000.lzma.bak 2014-09-25T18:17:06.642Z,1411669026.642 [DataOverHttps](INFO): SBD MOMSN=1207616 2014-09-25T18:17:06.656Z,1411669026.656 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:06.678Z,1411669026.678 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:07.086Z,1411669027.086 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:07.096Z,1411669027.096 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:07.497Z,1411669027.497 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:07.515Z,1411669027.515 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:08.380Z,1411669028.380 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:08.445Z,1411669028.445 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:08.851Z,1411669028.851 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:08.871Z,1411669028.871 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:09.265Z,1411669029.265 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:09.275Z,1411669029.275 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:09.681Z,1411669029.681 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:09.691Z,1411669029.691 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:10.137Z,1411669030.137 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:10.147Z,1411669030.147 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:10.550Z,1411669030.550 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:10.560Z,1411669030.560 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:11.496Z,1411669031.496 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:11.543Z,1411669031.543 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:11.952Z,1411669031.952 [DataOverHttps](INFO): Sending 57 bytes from file Logs/20140925T181140/Courier0004.lzma 2014-09-25T18:17:11.960Z,1411669031.960 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:11.988Z,1411669031.988 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:12.369Z,1411669032.369 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:12.387Z,1411669032.387 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:12.785Z,1411669032.785 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:12.796Z,1411669032.796 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:13.194Z,1411669033.194 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:13.242Z,1411669033.242 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:13.620Z,1411669033.620 [DataOverHttps](INFO): Moved sent file to Logs/20140925T181140/Courier0004.lzma.bak 2014-09-25T18:17:13.620Z,1411669033.620 [DataOverHttps](INFO): SBD MOMSN=1207624 2014-09-25T18:17:13.641Z,1411669033.641 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:13.686Z,1411669033.686 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:14.115Z,1411669034.115 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:14.138Z,1411669034.138 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:14.516Z,1411669034.516 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:14.557Z,1411669034.557 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:15.422Z,1411669035.422 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:15.464Z,1411669035.464 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:15.860Z,1411669035.860 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:15.872Z,1411669035.872 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:16.271Z,1411669036.271 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:16.316Z,1411669036.316 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:16.703Z,1411669036.703 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:16.745Z,1411669036.745 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:16.753Z,1411669036.753 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1 2014-09-25T18:17:16.753Z,1411669036.753 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-09-25T18:17:16.754Z,1411669036.754 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1 2014-09-25T18:17:16.756Z,1411669036.756 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped 2014-09-25T18:17:16.756Z,1411669036.756 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1 2014-09-25T18:17:17.140Z,1411669037.140 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:17.151Z,1411669037.151 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:17.160Z,1411669037.160 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 2.118464 min 2014-09-25T18:17:17.162Z,1411669037.162 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped 2014-09-25T18:17:17.162Z,1411669037.162 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted 2014-09-25T18:17:17.162Z,1411669037.162 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped 2014-09-25T18:17:17.162Z,1411669037.162 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-09-25T18:17:17.549Z,1411669037.549 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:17.560Z,1411669037.560 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:18.415Z,1411669038.415 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:18.477Z,1411669038.477 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:18.860Z,1411669038.860 [DataOverHttps](INFO): Sending 714 bytes from file Logs/20140924T224954/Express0769.lzma 2014-09-25T18:17:18.875Z,1411669038.875 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:18.887Z,1411669038.887 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:19.300Z,1411669039.300 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:19.310Z,1411669039.310 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:19.708Z,1411669039.708 [DataOverHttps](INFO): Moved sent file to Logs/20140924T224954/Express0769.lzma.bak 2014-09-25T18:17:19.708Z,1411669039.708 [DataOverHttps](INFO): SBD MOMSN=1207626 2014-09-25T18:17:19.713Z,1411669039.713 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:19.725Z,1411669039.725 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:20.166Z,1411669040.166 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:20.178Z,1411669040.178 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:20.578Z,1411669040.578 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:20.588Z,1411669040.588 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:21.445Z,1411669041.445 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:21.482Z,1411669041.482 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:21.902Z,1411669041.902 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:21.932Z,1411669041.932 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:22.325Z,1411669042.325 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:22.341Z,1411669042.341 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:22.750Z,1411669042.750 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:22.761Z,1411669042.761 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:23.174Z,1411669043.174 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:23.185Z,1411669043.185 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:23.578Z,1411669043.578 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:23.589Z,1411669043.589 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:24.453Z,1411669044.453 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:24.498Z,1411669044.498 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:24.916Z,1411669044.916 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:24.940Z,1411669044.940 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:25.339Z,1411669045.339 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:25.349Z,1411669045.349 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:25.746Z,1411669045.746 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:25.756Z,1411669045.756 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:26.162Z,1411669046.162 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:26.189Z,1411669046.189 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:26.591Z,1411669046.591 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:26.601Z,1411669046.601 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:27.498Z,1411669047.498 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:27.550Z,1411669047.550 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:27.969Z,1411669047.969 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:28.023Z,1411669048.023 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:28.409Z,1411669048.409 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:28.420Z,1411669048.420 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:28.822Z,1411669048.822 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:28.861Z,1411669048.861 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:29.244Z,1411669049.244 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:29.255Z,1411669049.255 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:29.649Z,1411669049.649 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:29.701Z,1411669049.701 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:30.562Z,1411669050.562 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:30.624Z,1411669050.624 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:31.026Z,1411669051.026 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:31.075Z,1411669051.075 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:31.462Z,1411669051.462 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:31.504Z,1411669051.504 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:31.900Z,1411669051.900 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:31.911Z,1411669051.911 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:32.304Z,1411669052.304 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:32.316Z,1411669052.316 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:32.712Z,1411669052.712 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:32.723Z,1411669052.723 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:33.595Z,1411669053.595 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:33.626Z,1411669053.626 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:34.053Z,1411669054.053 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:34.065Z,1411669054.065 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:34.467Z,1411669054.467 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:34.478Z,1411669054.478 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:34.876Z,1411669054.876 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:34.887Z,1411669054.887 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:35.281Z,1411669055.281 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:35.292Z,1411669055.292 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:35.693Z,1411669055.693 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:35.703Z,1411669055.703 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:36.561Z,1411669056.561 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:36.572Z,1411669056.572 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:36.987Z,1411669056.987 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:37.019Z,1411669057.019 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:37.438Z,1411669057.438 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:37.450Z,1411669057.450 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:37.848Z,1411669057.848 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:37.858Z,1411669057.858 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:38.249Z,1411669058.249 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:38.260Z,1411669058.260 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:38.665Z,1411669058.665 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:38.690Z,1411669058.690 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:39.576Z,1411669059.576 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:39.590Z,1411669059.590 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:39.996Z,1411669059.996 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:40.048Z,1411669060.048 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:40.452Z,1411669060.452 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:40.464Z,1411669060.464 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:40.873Z,1411669060.873 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:40.883Z,1411669060.883 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:41.282Z,1411669061.282 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:41.300Z,1411669061.300 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:41.702Z,1411669061.702 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:41.713Z,1411669061.713 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:42.627Z,1411669062.627 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:42.677Z,1411669062.677 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:43.080Z,1411669063.080 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:43.127Z,1411669063.127 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:43.521Z,1411669063.521 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:43.575Z,1411669063.575 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:43.960Z,1411669063.960 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:43.970Z,1411669063.970 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:43.988Z,1411669063.988 [CBIT](INFO): Clearing failed count for component AcousticModem_Benthos_ATM900 2014-09-25T18:17:43.988Z,1411669063.988 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 8 2014-09-25T18:17:44.371Z,1411669064.371 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:17:44.371Z,1411669064.371 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:17:44.371Z,1411669064.371 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:17:44.371Z,1411669064.371 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1 2014-09-25T18:17:44.371Z,1411669064.371 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:17:44.382Z,1411669064.382 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:44.393Z,1411669064.393 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:44.444Z,1411669064.444 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:17:44.803Z,1411669064.803 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:17:44.822Z,1411669064.822 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:44.866Z,1411669064.866 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:45.706Z,1411669065.706 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:45.716Z,1411669065.716 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:46.116Z,1411669066.116 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:46.187Z,1411669066.187 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:46.222Z,1411669066.222 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:17:46.222Z,1411669066.222 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1 2014-09-25T18:17:46.582Z,1411669066.582 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:46.631Z,1411669066.631 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:47.019Z,1411669067.019 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:47.029Z,1411669067.029 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:47.424Z,1411669067.424 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:47.435Z,1411669067.435 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:47.816Z,1411669067.816 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:17:47.816Z,1411669067.816 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:17:47.817Z,1411669067.817 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:17:47.817Z,1411669067.817 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2 2014-09-25T18:17:47.817Z,1411669067.817 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:17:47.828Z,1411669067.828 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:47.838Z,1411669067.838 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:47.857Z,1411669067.857 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:17:48.241Z,1411669068.241 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:17:48.711Z,1411669068.711 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:48.727Z,1411669068.727 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:49.135Z,1411669069.135 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:49.201Z,1411669069.201 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:49.595Z,1411669069.595 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:49.607Z,1411669069.607 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:49.649Z,1411669069.649 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:17:49.649Z,1411669069.649 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2 2014-09-25T18:17:50.026Z,1411669070.026 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:50.036Z,1411669070.036 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:50.428Z,1411669070.428 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:50.439Z,1411669070.439 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:50.832Z,1411669070.832 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:50.842Z,1411669070.842 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:51.695Z,1411669071.695 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:51.705Z,1411669071.705 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:52.103Z,1411669072.103 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:17:52.103Z,1411669072.103 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:17:52.103Z,1411669072.103 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:17:52.103Z,1411669072.103 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3 2014-09-25T18:17:52.103Z,1411669072.103 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:17:52.109Z,1411669072.109 [DataOverHttps](INFO): Sending 752 bytes from file Logs/20140925T181140/Express0001.lzma 2014-09-25T18:17:52.116Z,1411669072.116 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:52.179Z,1411669072.179 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:52.214Z,1411669072.214 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:17:52.567Z,1411669072.567 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:17:52.585Z,1411669072.585 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:52.617Z,1411669072.617 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:53.040Z,1411669073.040 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:53.052Z,1411669073.052 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:53.444Z,1411669073.444 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:53.455Z,1411669073.455 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:53.474Z,1411669073.474 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:17:53.474Z,1411669073.474 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3 2014-09-25T18:17:53.845Z,1411669073.845 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:53.855Z,1411669073.855 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:54.250Z,1411669074.250 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:54.260Z,1411669074.260 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:54.654Z,1411669074.654 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:54.665Z,1411669074.665 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:55.063Z,1411669075.063 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:55.074Z,1411669075.074 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:55.486Z,1411669075.486 [DataOverHttps](INFO): Moved sent file to Logs/20140925T181140/Express0001.lzma.bak 2014-09-25T18:17:55.486Z,1411669075.486 [DataOverHttps](INFO): SBD MOMSN=1207654 2014-09-25T18:17:55.491Z,1411669075.491 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:55.520Z,1411669075.520 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:55.920Z,1411669075.920 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:17:55.921Z,1411669075.921 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:17:55.921Z,1411669075.921 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:17:55.921Z,1411669075.921 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4 2014-09-25T18:17:55.921Z,1411669075.921 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:17:55.992Z,1411669075.992 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:56.029Z,1411669076.029 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:56.064Z,1411669076.064 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:17:56.417Z,1411669076.417 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:17:56.429Z,1411669076.429 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:56.441Z,1411669076.441 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:57.324Z,1411669077.324 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:57.353Z,1411669077.353 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:57.771Z,1411669077.771 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:57.782Z,1411669077.782 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:57.801Z,1411669077.801 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:17:57.801Z,1411669077.801 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4 2014-09-25T18:17:58.192Z,1411669078.192 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:58.202Z,1411669078.202 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:58.597Z,1411669078.597 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:58.607Z,1411669078.607 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:59.009Z,1411669079.009 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:59.087Z,1411669079.087 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:59.482Z,1411669079.482 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:17:59.482Z,1411669079.482 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:17:59.483Z,1411669079.483 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:17:59.483Z,1411669079.483 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5 2014-09-25T18:17:59.483Z,1411669079.483 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:17:59.494Z,1411669079.494 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:17:59.506Z,1411669079.506 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:17:59.565Z,1411669079.565 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:17:59.915Z,1411669079.915 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:18:00.441Z,1411669080.441 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:00.493Z,1411669080.493 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:00.917Z,1411669080.917 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20140925T181140/Express0005.lzma 2014-09-25T18:18:00.948Z,1411669080.948 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:00.967Z,1411669080.967 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:01.365Z,1411669081.365 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:01.414Z,1411669081.414 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:01.465Z,1411669081.465 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:18:01.465Z,1411669081.465 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5 2014-09-25T18:18:01.866Z,1411669081.866 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:01.903Z,1411669081.903 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:02.302Z,1411669082.302 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:02.342Z,1411669082.342 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:02.740Z,1411669082.740 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:02.793Z,1411669082.793 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:03.232Z,1411669083.232 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:18:03.232Z,1411669083.232 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:18:03.232Z,1411669083.232 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:18:03.232Z,1411669083.232 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6 2014-09-25T18:18:03.232Z,1411669083.232 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:18:03.244Z,1411669083.244 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:03.254Z,1411669083.254 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:03.334Z,1411669083.334 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:18:03.677Z,1411669083.677 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:18:03.679Z,1411669083.679 [DataOverHttps](INFO): Moved sent file to Logs/20140925T181140/Express0005.lzma.bak 2014-09-25T18:18:03.679Z,1411669083.679 [DataOverHttps](INFO): SBD MOMSN=1207669 2014-09-25T18:18:03.725Z,1411669083.725 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:03.750Z,1411669083.750 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:04.232Z,1411669084.232 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:04.242Z,1411669084.242 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:04.295Z,1411669084.295 [Default:CheckIn:Read_Iridium] Stopped 2014-09-25T18:18:04.295Z,1411669084.295 [Default:CheckIn](INFO): Completed Default:CheckIn 2014-09-25T18:18:04.295Z,1411669084.295 [Default:CheckIn] Stopped 2014-09-25T18:18:04.317Z,1411669084.317 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn 2014-09-25T18:18:04.317Z,1411669084.317 [Default:CheckIn:A.SetSpeed] Stopped 2014-09-25T18:18:04.317Z,1411669084.317 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize. 2014-09-25T18:18:04.730Z,1411669084.730 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:04.741Z,1411669084.741 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:04.783Z,1411669084.783 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:18:04.783Z,1411669084.783 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6 2014-09-25T18:18:05.446Z,1411669085.446 [CommandLine](IMPORTANT): got command get Vehicle.checkMTQueue 2014-09-25T18:18:05.447Z,1411669085.447 [CommandLine](IMPORTANT): Vehicle.checkMTQueue 0 bool 2014-09-25T18:18:09.750Z,1411669089.750 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:18:09.750Z,1411669089.750 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:18:09.750Z,1411669089.750 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:18:09.751Z,1411669089.751 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7 2014-09-25T18:18:09.751Z,1411669089.751 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:18:10.253Z,1411669090.253 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:10.289Z,1411669090.289 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:10.307Z,1411669090.307 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:18:14.762Z,1411669094.762 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:18:14.790Z,1411669094.790 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:14.861Z,1411669094.861 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:19.761Z,1411669099.761 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:19.809Z,1411669099.809 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:24.769Z,1411669104.769 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:24.819Z,1411669104.819 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:24.853Z,1411669104.853 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:18:24.853Z,1411669104.853 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7 2014-09-25T18:18:29.753Z,1411669109.753 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:18:29.753Z,1411669109.753 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:18:29.754Z,1411669109.754 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:18:29.754Z,1411669109.754 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8 2014-09-25T18:18:29.754Z,1411669109.754 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:18:29.757Z,1411669109.757 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-09-25T18:18:29.757Z,1411669109.757 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2014-09-25T18:18:29.757Z,1411669109.757 [DVL_micro] Data Fault, FailCount= 1 2014-09-25T18:18:29.757Z,1411669109.757 [DVL_micro](ERROR): Data Fault 2014-09-25T18:18:29.768Z,1411669109.768 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:29.829Z,1411669109.829 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:18:29.829Z,1411669109.829 [CBIT](CRITICAL): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:18:29.829Z,1411669109.829 [CBIT](ERROR): Data Fault in component: DVL_micro 2014-09-25T18:18:34.761Z,1411669114.761 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:18:34.839Z,1411669114.839 [DVL_micro](INFO): uninitialize:Powering down 2014-09-25T18:18:34.879Z,1411669114.879 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:39.762Z,1411669119.762 [DVL_micro](FAULT): LCB fault: Software Overcurrent. 2014-09-25T18:18:39.762Z,1411669119.762 [DVL_micro] Hardware Fault, FailCount= 1 2014-09-25T18:18:39.762Z,1411669119.762 [DVL_micro](ERROR): Hardware Fault 2014-09-25T18:18:39.796Z,1411669119.796 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:44.834Z,1411669124.834 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:44.874Z,1411669124.874 [CBIT](INFO): Clearing failed state for component DVL_micro 2014-09-25T18:18:44.874Z,1411669124.874 [DVL_micro] No Fault, FailCount= 1 2014-09-25T18:18:49.770Z,1411669129.770 [DVL_micro](INFO): Initializing 2014-09-25T18:18:49.782Z,1411669129.782 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:54.806Z,1411669134.806 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:18:59.848Z,1411669139.848 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:04.826Z,1411669144.826 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:09.849Z,1411669149.849 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:15.227Z,1411669155.227 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:19.848Z,1411669159.848 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:24.836Z,1411669164.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:29.832Z,1411669169.832 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:34.808Z,1411669174.808 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:39.784Z,1411669179.784 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:44.806Z,1411669184.806 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:49.836Z,1411669189.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:54.765Z,1411669194.765 [BPC1](DEBUG): Received data from all battery sticks 2014-09-25T18:19:54.772Z,1411669194.772 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:19:59.789Z,1411669199.789 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:04.836Z,1411669204.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:09.840Z,1411669209.840 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:15.247Z,1411669215.247 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:19.833Z,1411669219.833 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:24.806Z,1411669224.806 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:29.859Z,1411669229.859 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:34.803Z,1411669234.803 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:39.821Z,1411669239.821 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:44.838Z,1411669244.838 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:49.825Z,1411669249.825 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:54.809Z,1411669254.809 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:20:59.823Z,1411669259.823 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:04.811Z,1411669264.811 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:09.880Z,1411669269.880 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:14.804Z,1411669274.804 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:20.250Z,1411669280.250 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:24.811Z,1411669284.811 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:29.851Z,1411669289.851 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:34.822Z,1411669294.822 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:39.809Z,1411669299.809 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:44.810Z,1411669304.810 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:49.838Z,1411669309.838 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:54.780Z,1411669314.780 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:21:59.811Z,1411669319.811 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:04.849Z,1411669324.849 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:09.804Z,1411669329.804 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:14.800Z,1411669334.800 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:19.767Z,1411669339.767 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2014-09-25T18:22:19.767Z,1411669339.767 [DVL_micro] Data Fault, FailCount= 1 2014-09-25T18:22:19.767Z,1411669339.767 [DVL_micro](ERROR): Data Fault 2014-09-25T18:22:19.804Z,1411669339.804 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:19.814Z,1411669339.814 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1 2014-09-25T18:22:19.815Z,1411669339.815 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-09-25T18:22:19.815Z,1411669339.815 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1 2014-09-25T18:22:19.815Z,1411669339.815 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped 2014-09-25T18:22:19.815Z,1411669339.815 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1 2014-09-25T18:22:19.845Z,1411669339.845 [CBIT](ERROR): Data Fault in component: DVL_micro 2014-09-25T18:22:25.310Z,1411669345.310 [DVL_micro](INFO): uninitialize:Powering down 2014-09-25T18:22:25.358Z,1411669345.358 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:25.367Z,1411669345.367 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 7.168321 min 2014-09-25T18:22:25.368Z,1411669345.368 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped 2014-09-25T18:22:25.368Z,1411669345.368 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted 2014-09-25T18:22:25.368Z,1411669345.368 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped 2014-09-25T18:22:25.368Z,1411669345.368 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted 2014-09-25T18:22:29.840Z,1411669349.840 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:34.768Z,1411669354.768 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:34.816Z,1411669354.816 [CBIT](INFO): Clearing failed state for component DVL_micro 2014-09-25T18:22:34.816Z,1411669354.816 [DVL_micro] No Fault, FailCount= 1 2014-09-25T18:22:39.778Z,1411669359.778 [DVL_micro](INFO): Initializing 2014-09-25T18:22:39.794Z,1411669359.794 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:44.868Z,1411669364.868 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:49.806Z,1411669369.806 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:54.807Z,1411669374.807 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:22:59.807Z,1411669379.807 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:04.799Z,1411669384.799 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:04.808Z,1411669384.808 [Default:CheckIn] Running Loop=1 2014-09-25T18:23:04.808Z,1411669384.808 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn 2014-09-25T18:23:04.808Z,1411669384.808 [Default:CheckIn:A.SetSpeed] Running Loop=1 2014-09-25T18:23:04.808Z,1411669384.808 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize. 2014-09-25T18:23:04.809Z,1411669384.809 [Default:CheckIn:Read_GPS] Running Loop=1 2014-09-25T18:23:05.198Z,1411669385.198 [NAL9602](INFO): Powering up 2014-09-25T18:23:05.249Z,1411669385.249 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:05.652Z,1411669385.652 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:06.062Z,1411669386.062 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:06.474Z,1411669386.474 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:06.878Z,1411669386.878 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:07.307Z,1411669387.307 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:07.734Z,1411669387.734 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:08.200Z,1411669388.200 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:08.624Z,1411669388.624 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:09.041Z,1411669389.041 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:09.478Z,1411669389.478 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:09.883Z,1411669389.883 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:10.305Z,1411669390.305 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:10.730Z,1411669390.730 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:11.171Z,1411669391.171 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:11.573Z,1411669391.573 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:11.986Z,1411669391.986 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:12.391Z,1411669392.391 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:12.804Z,1411669392.804 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:13.235Z,1411669393.235 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:13.684Z,1411669393.684 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:14.159Z,1411669394.159 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:14.569Z,1411669394.569 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:15.019Z,1411669395.019 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:15.436Z,1411669395.436 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:16.369Z,1411669396.369 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:16.776Z,1411669396.776 [NAL9602](INFO): NAL9602 initialized 2014-09-25T18:23:16.786Z,1411669396.786 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:17.316Z,1411669397.316 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:17.741Z,1411669397.741 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:18.151Z,1411669398.151 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:18.605Z,1411669398.605 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:19.005Z,1411669399.005 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:19.442Z,1411669399.442 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:19.882Z,1411669399.882 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:20.359Z,1411669400.359 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:20.851Z,1411669400.851 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:21.270Z,1411669401.270 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:21.704Z,1411669401.704 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:22.157Z,1411669402.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:22.555Z,1411669402.555 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:22.963Z,1411669402.963 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:23.408Z,1411669403.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:23.830Z,1411669403.830 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:24.248Z,1411669404.248 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:24.669Z,1411669404.669 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:25.119Z,1411669405.119 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:25.572Z,1411669405.572 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:25.994Z,1411669405.994 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:26.418Z,1411669406.418 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:26.889Z,1411669406.889 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:27.296Z,1411669407.296 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:27.722Z,1411669407.722 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:28.142Z,1411669408.142 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:28.548Z,1411669408.548 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:28.955Z,1411669408.955 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:29.358Z,1411669409.358 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:29.849Z,1411669409.849 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:29.909Z,1411669409.909 [CBIT](INFO): Clearing failed count for component AcousticModem_Benthos_ATM900 2014-09-25T18:23:29.909Z,1411669409.909 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 8 2014-09-25T18:23:30.293Z,1411669410.293 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:23:30.293Z,1411669410.293 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:23:30.293Z,1411669410.293 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:23:30.293Z,1411669410.293 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1 2014-09-25T18:23:30.293Z,1411669410.293 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:23:30.381Z,1411669410.381 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:30.399Z,1411669410.399 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:23:30.769Z,1411669410.769 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:23:30.828Z,1411669410.828 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:31.260Z,1411669411.260 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:31.680Z,1411669411.680 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:31.697Z,1411669411.697 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:23:31.697Z,1411669411.697 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1 2014-09-25T18:23:32.149Z,1411669412.149 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:32.574Z,1411669412.574 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:33.123Z,1411669413.123 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:33.571Z,1411669413.571 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:33.962Z,1411669413.962 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:23:33.963Z,1411669413.963 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:23:33.963Z,1411669413.963 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:23:33.963Z,1411669413.963 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2 2014-09-25T18:23:33.963Z,1411669413.963 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:23:34.060Z,1411669414.060 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:34.094Z,1411669414.094 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:23:34.431Z,1411669414.431 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:23:34.492Z,1411669414.492 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:34.893Z,1411669414.893 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:35.303Z,1411669415.303 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:35.321Z,1411669415.321 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:23:35.321Z,1411669415.321 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2 2014-09-25T18:23:35.722Z,1411669415.722 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:36.231Z,1411669416.231 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:36.704Z,1411669416.704 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:37.109Z,1411669417.109 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:37.500Z,1411669417.500 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:23:37.500Z,1411669417.500 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:23:37.501Z,1411669417.501 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:23:37.501Z,1411669417.501 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3 2014-09-25T18:23:37.501Z,1411669417.501 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:23:37.522Z,1411669417.522 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:37.561Z,1411669417.561 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:23:37.929Z,1411669417.929 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:23:37.961Z,1411669417.961 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:38.363Z,1411669418.363 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:38.835Z,1411669418.835 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:38.877Z,1411669418.877 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:23:38.878Z,1411669418.878 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3 2014-09-25T18:23:39.306Z,1411669419.306 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:39.719Z,1411669419.719 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:40.127Z,1411669420.127 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:40.533Z,1411669420.533 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:40.944Z,1411669420.944 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:41.374Z,1411669421.374 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:23:41.374Z,1411669421.374 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:23:41.375Z,1411669421.375 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:23:41.375Z,1411669421.375 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4 2014-09-25T18:23:41.375Z,1411669421.375 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:23:41.449Z,1411669421.449 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:41.483Z,1411669421.483 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:23:41.839Z,1411669421.839 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:23:41.894Z,1411669421.894 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:42.415Z,1411669422.415 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:42.821Z,1411669422.821 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:42.855Z,1411669422.855 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:23:42.855Z,1411669422.855 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4 2014-09-25T18:23:43.263Z,1411669423.263 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:43.668Z,1411669423.668 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:44.079Z,1411669424.079 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:44.488Z,1411669424.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:44.884Z,1411669424.884 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:23:44.884Z,1411669424.884 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:23:44.884Z,1411669424.884 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:23:44.884Z,1411669424.884 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5 2014-09-25T18:23:44.884Z,1411669424.884 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:23:44.950Z,1411669424.950 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:44.969Z,1411669424.969 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:23:45.331Z,1411669425.331 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:23:45.376Z,1411669425.376 [NAL9602](IMPORTANT): GPS fix at: 1411669414.00 2014-09-25T18:23:45.386Z,1411669425.386 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:45.422Z,1411669425.422 [Default:CheckIn:Read_GPS] Stopped 2014-09-25T18:23:45.422Z,1411669425.422 [Default:CheckIn:Read_Iridium] Running Loop=1 2014-09-25T18:23:45.842Z,1411669425.842 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:46.818Z,1411669426.818 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:46.862Z,1411669426.862 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:23:46.862Z,1411669426.862 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5 2014-09-25T18:23:47.272Z,1411669427.272 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:48.174Z,1411669428.174 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:48.569Z,1411669428.569 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:23:48.569Z,1411669428.569 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:23:48.570Z,1411669428.570 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:23:48.570Z,1411669428.570 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6 2014-09-25T18:23:48.570Z,1411669428.570 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:23:48.583Z,1411669428.583 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20140925T181140/Courier0008.lzma 2014-09-25T18:23:48.745Z,1411669428.745 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:48.787Z,1411669428.787 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:23:49.146Z,1411669429.146 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:23:49.222Z,1411669429.222 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:49.589Z,1411669429.589 [DataOverHttps](INFO): Moved sent file to Logs/20140925T181140/Courier0008.lzma.bak 2014-09-25T18:23:49.589Z,1411669429.589 [DataOverHttps](INFO): SBD MOMSN=1207694 2014-09-25T18:23:49.638Z,1411669429.638 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:50.845Z,1411669430.845 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:50.890Z,1411669430.890 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:23:50.890Z,1411669430.890 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6 2014-09-25T18:23:51.287Z,1411669431.287 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:52.402Z,1411669432.402 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:52.860Z,1411669432.860 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:23:52.860Z,1411669432.860 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:23:52.860Z,1411669432.860 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:23:52.860Z,1411669432.860 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7 2014-09-25T18:23:52.860Z,1411669432.860 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:23:52.879Z,1411669432.879 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:52.897Z,1411669432.897 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:23:53.280Z,1411669433.280 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:23:53.292Z,1411669433.292 [NAL9602](INFO): Powering down 2014-09-25T18:23:53.305Z,1411669433.305 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:53.747Z,1411669433.747 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:54.148Z,1411669434.148 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:54.183Z,1411669434.183 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2014-09-25T18:23:54.183Z,1411669434.183 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7 2014-09-25T18:23:54.604Z,1411669434.604 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:55.509Z,1411669435.509 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:55.951Z,1411669435.951 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:56.325Z,1411669436.325 [AcousticModem_Benthos_ATM900](INFO): Powering up 2014-09-25T18:23:56.325Z,1411669436.325 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2014-09-25T18:23:56.326Z,1411669436.326 [AcousticModem_Benthos_ATM900](ERROR): Error opening port: cannot open serial port 2014-09-25T18:23:56.326Z,1411669436.326 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8 2014-09-25T18:23:56.326Z,1411669436.326 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2014-09-25T18:23:56.369Z,1411669436.369 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:56.407Z,1411669436.407 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:23:56.408Z,1411669436.408 [CBIT](CRITICAL): Communications Fault in component: AcousticModem_Benthos_ATM900 2014-09-25T18:23:56.754Z,1411669436.754 [AcousticModem_Benthos_ATM900](INFO): Powering down 2014-09-25T18:23:56.776Z,1411669436.776 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:57.180Z,1411669437.180 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:57.589Z,1411669437.589 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:58.478Z,1411669438.478 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:58.866Z,1411669438.866 [DataOverHttps](INFO): Sending 491 bytes from file Logs/20140925T181140/Express0009.lzma 2014-09-25T18:23:58.942Z,1411669438.942 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:59.359Z,1411669439.359 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:23:59.732Z,1411669439.732 [DataOverHttps](INFO): Moved sent file to Logs/20140925T181140/Express0009.lzma.bak 2014-09-25T18:23:59.732Z,1411669439.732 [DataOverHttps](INFO): SBD MOMSN=1207697 2014-09-25T18:23:59.752Z,1411669439.752 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:00.208Z,1411669440.208 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:00.229Z,1411669440.229 [Default:CheckIn:Read_Iridium] Stopped 2014-09-25T18:24:00.230Z,1411669440.230 [Default:CheckIn](INFO): Completed Default:CheckIn 2014-09-25T18:24:00.230Z,1411669440.230 [Default:CheckIn] Stopped 2014-09-25T18:24:00.230Z,1411669440.230 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn 2014-09-25T18:24:00.230Z,1411669440.230 [Default:CheckIn:A.SetSpeed] Stopped 2014-09-25T18:24:00.230Z,1411669440.230 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize. 2014-09-25T18:24:00.623Z,1411669440.623 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:06.327Z,1411669446.327 [BPC1](FAULT): Failed to receive battery data 2014-09-25T18:24:06.327Z,1411669446.327 [BPC1] Communications Fault, FailCount= 1 2014-09-25T18:24:06.327Z,1411669446.327 [BPC1](ERROR): Communications Fault 2014-09-25T18:24:06.358Z,1411669446.358 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:06.383Z,1411669446.383 [CBIT](ERROR): Communications Fault in component: BPC1 2014-09-25T18:24:10.689Z,1411669450.689 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:15.730Z,1411669455.730 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:20.692Z,1411669460.692 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:20.711Z,1411669460.711 [CBIT](INFO): Clearing failed state for component BPC1 2014-09-25T18:24:20.711Z,1411669460.711 [BPC1] No Fault, FailCount= 1 2014-09-25T18:24:25.781Z,1411669465.781 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:30.696Z,1411669470.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:35.714Z,1411669475.714 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:40.670Z,1411669480.670 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:45.786Z,1411669485.786 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:50.678Z,1411669490.678 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:24:55.786Z,1411669495.786 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:00.665Z,1411669500.665 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:05.744Z,1411669505.744 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:11.183Z,1411669511.183 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:15.727Z,1411669515.727 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:20.673Z,1411669520.673 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:25.791Z,1411669525.791 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:30.663Z,1411669530.663 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:35.758Z,1411669535.758 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:40.671Z,1411669540.671 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:45.736Z,1411669545.736 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:50.702Z,1411669550.702 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:25:55.730Z,1411669555.730 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:26:00.675Z,1411669560.675 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2014-09-25T18:26:05.659Z,1411669565.659 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2014-09-25T18:26:05.659Z,1411669565.659 [DVL_micro] Data Fault, FailCount= 1 2014-09-25T18:26:05.659Z,1411669565.659 [DVL_micro](ERROR): Data Fault 2014-09-25T18:26:05.667Z,1411669565.667 [BPC1](ERROR): A bank expecting battery number:1 and read number: uart read:X $S,01,7994,02,00,04,63%52 $C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72 $B11,0A,FFF3%46 $B12,0A,FFF3%45 $B13,0A,FFF2%45 $B14,0A,FFF2%42 $B15,0A,FFF3%42 $B16,0A,FFF3%41 $B17,0A,FFF3%40 $B18,0A,0000%3A $S,01,7C57,02,00,04,63%27 $C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72 $B11,02,000A,01,026C,03,0001,08,0B6E,09,4046,0A,FFF3,0B,FFF2%41 $B11,0C,0001,0D,0063,0E,0060,0F,1724,10,1775,11,6ACE,12,632D%40 $B11,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4C $B11,1A,0031,1B,4357,1C,00A7%42 $B12,02,000A,01,026C,03,0001,08,0B6D,09,403F,0A,FFF4,0B,FFF3%32 $B12,0C,0001,0D,0063,0E,005E,0F,16A5,10,170E,11,7139,12,6883%3A $B12,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4F $B12,1A,0031,1B,4357,1C,009E%4B $B13,02,000A,01,026C,03,0001,08,0B6B,09,404C,0A,FFF2,0B,FFF2%30 $B13,0C,0001,0D,0063,0E,0061,0F,174F,10,17AA,11,63E5,12,63E5%37 $B13,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4E $B13,1A,0031,1B,4357,1C,0089%37 $B14,02,000A,01,026C,03,0001,08,0B6E,09,404B,0A,FFF4,0B,FFF3%36 $B14,0C,0001,0D,0063,0E,005E,0F,1698,10,16FF,11,70F8,12,6847%40 $B14,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%49 $B14,1A,0031,1B,4357,1C,00A6%46 $B15,02,000A,01,026C,03,0001,08,0B73,09,4042,0A,FFF5,0B,FFF4%36 $B15,0C,0001,0D,0063,0E,005E,0F,16C0,10,1717,11,7C17,12,71C0%47 $B15,13,FF