2015-02-13T08:25:14.338Z,1423815914.338 [Supervisor](DEBUG): Initializing supervisor. 2015-02-13T08:25:14.340Z,1423815914.340 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0 2015-02-13T08:25:14.341Z,1423815914.341 [SyncHandler](INFO): Protected caller Thread ID is 777 2015-02-13T08:25:14.341Z,1423815914.341 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2015-02-13T08:25:14.350Z,1423815914.350 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0 2015-02-13T08:25:14.351Z,1423815914.351 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 778 2015-02-13T08:25:14.354Z,1423815914.354 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2015-02-13T08:25:14.365Z,1423815914.365 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2015-02-13T08:25:14.398Z,1423815914.398 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0 2015-02-13T08:25:14.399Z,1423815914.399 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 779 2015-02-13T08:25:14.400Z,1423815914.400 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2015-02-13T08:25:14.411Z,1423815914.411 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0 2015-02-13T08:25:14.411Z,1423815914.411 [logger ThreadHandler](INFO): Protected caller Thread ID is 780 2015-02-13T08:25:14.413Z,1423815914.413 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2015-02-13T08:25:14.414Z,1423815914.414 [Supervisor](INFO): Looking for Config files in directory: Config/ 2015-02-13T08:25:14.429Z,1423815914.429 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2015-02-13T08:25:15.090Z,1423815915.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2015-02-13T08:25:15.090Z,1423815915.090 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2015-02-13T08:25:15.326Z,1423815915.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2015-02-13T08:25:15.327Z,1423815915.327 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2015-02-13T08:25:15.438Z,1423815915.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2015-02-13T08:25:15.439Z,1423815915.439 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2015-02-13T08:25:15.565Z,1423815915.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2015-02-13T08:25:15.566Z,1423815915.566 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2015-02-13T08:25:15.655Z,1423815915.655 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2015-02-13T08:25:15.827Z,1423815915.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2015-02-13T08:25:15.828Z,1423815915.828 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2015-02-13T08:25:15.922Z,1423815915.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2015-02-13T08:25:15.924Z,1423815915.924 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2015-02-13T08:25:16.172Z,1423815916.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2015-02-13T08:25:16.173Z,1423815916.173 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2015-02-13T08:25:16.535Z,1423815916.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2015-02-13T08:25:16.535Z,1423815916.535 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2015-02-13T08:25:16.847Z,1423815916.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2015-02-13T08:25:16.848Z,1423815916.848 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2015-02-13T08:25:17.360Z,1423815917.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2015-02-13T08:25:17.361Z,1423815917.361 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2015-02-13T08:25:17.605Z,1423815917.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2015-02-13T08:25:17.605Z,1423815917.605 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2015-02-13T08:25:21.381Z,1423815921.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2015-02-13T08:25:21.381Z,1423815921.381 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2015-02-13T08:25:23.764Z,1423815923.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2015-02-13T08:25:23.765Z,1423815923.765 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2015-02-13T08:25:24.416Z,1423815924.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2015-02-13T08:25:24.418Z,1423815924.418 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2015-02-13T08:25:24.423Z,1423815924.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2015-02-13T08:25:24.669Z,1423815924.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2015-02-13T08:25:24.670Z,1423815924.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2015-02-13T08:25:24.788Z,1423815924.788 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg 2015-02-13T08:25:24.887Z,1423815924.887 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2015-02-13T08:25:24.986Z,1423815924.986 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2015-02-13T08:25:25.095Z,1423815925.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2015-02-13T08:25:25.240Z,1423815925.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2015-02-13T08:25:25.414Z,1423815925.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2015-02-13T08:25:25.517Z,1423815925.517 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2015-02-13T08:25:25.610Z,1423815925.610 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2015-02-13T08:25:25.712Z,1423815925.712 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2015-02-13T08:25:25.811Z,1423815925.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2015-02-13T08:25:25.994Z,1423815925.994 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2015-02-13T08:25:26.001Z,1423815926.001 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2015-02-13T08:25:26.078Z,1423815926.078 [VerticalControl](DEBUG): Construct VerticalControl. 2015-02-13T08:25:26.200Z,1423815926.200 [VerticalControl] Loaded 2015-02-13T08:25:26.200Z,1423815926.200 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2015-02-13T08:25:26.201Z,1423815926.201 [HorizontalControl](DEBUG): Construct HorizontalControl. 2015-02-13T08:25:26.276Z,1423815926.276 [HorizontalControl] Loaded 2015-02-13T08:25:26.276Z,1423815926.276 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2015-02-13T08:25:26.276Z,1423815926.276 [SpeedControl](DEBUG): Construct SpeedControl. 2015-02-13T08:25:26.283Z,1423815926.283 [SpeedControl] Loaded 2015-02-13T08:25:26.283Z,1423815926.283 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2015-02-13T08:25:26.284Z,1423815926.284 [LoopControl](DEBUG): Construct LoopControl. 2015-02-13T08:25:26.284Z,1423815926.284 [LoopControl] Loaded 2015-02-13T08:25:26.284Z,1423815926.284 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2015-02-13T08:25:26.285Z,1423815926.285 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2015-02-13T08:25:26.286Z,1423815926.286 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2015-02-13T08:25:26.417Z,1423815926.417 [SBIT](DEBUG): Construct Startup Built In Test. 2015-02-13T08:25:26.428Z,1423815926.428 [SBIT] Loaded 2015-02-13T08:25:26.428Z,1423815926.428 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2015-02-13T08:25:26.429Z,1423815926.429 [IBIT](DEBUG): Construct Initiated Built In Test. 2015-02-13T08:25:26.459Z,1423815926.459 [IBIT] Loaded 2015-02-13T08:25:26.459Z,1423815926.459 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2015-02-13T08:25:26.461Z,1423815926.461 [CBIT](DEBUG): Construct CBIT Built In Test. 2015-02-13T08:25:26.597Z,1423815926.597 [CBIT] Loaded 2015-02-13T08:25:26.597Z,1423815926.597 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2015-02-13T08:25:26.598Z,1423815926.598 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2015-02-13T08:25:26.598Z,1423815926.598 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2015-02-13T08:25:26.629Z,1423815926.629 [DepthRateCalculator] Loaded 2015-02-13T08:25:26.629Z,1423815926.629 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2015-02-13T08:25:26.635Z,1423815926.635 [PitchRateCalculator] Loaded 2015-02-13T08:25:26.636Z,1423815926.636 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2015-02-13T08:25:26.647Z,1423815926.647 [SpeedCalculator] Loaded 2015-02-13T08:25:26.648Z,1423815926.648 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2015-02-13T08:25:26.664Z,1423815926.664 [TempGradientCalculator] Loaded 2015-02-13T08:25:26.665Z,1423815926.665 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2015-02-13T08:25:26.670Z,1423815926.670 [YawRateCalculator] Loaded 2015-02-13T08:25:26.670Z,1423815926.670 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2015-02-13T08:25:26.671Z,1423815926.671 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2015-02-13T08:25:26.672Z,1423815926.672 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2015-02-13T08:25:27.811Z,1423815927.811 [HFRCMReconstructedInterpolator] Loaded 2015-02-13T08:25:27.811Z,1423815927.811 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread. 2015-02-13T08:25:27.812Z,1423815927.812 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2015-02-13T08:25:27.812Z,1423815927.812 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2015-02-13T08:25:28.101Z,1423815928.101 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2015-02-13T08:25:28.101Z,1423815928.101 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2015-02-13T08:25:28.212Z,1423815928.212 [DeadReckonUsingMultipleVelocitySources] Loaded 2015-02-13T08:25:28.212Z,1423815928.212 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2015-02-13T08:25:28.260Z,1423815928.260 [DeadReckonUsingSpeedCalculator] Loaded 2015-02-13T08:25:28.260Z,1423815928.260 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2015-02-13T08:25:28.278Z,1423815928.278 [NavChart] Loaded 2015-02-13T08:25:28.278Z,1423815928.278 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2015-02-13T08:25:28.283Z,1423815928.283 [UniversalFixResidualReporter] Loaded 2015-02-13T08:25:28.283Z,1423815928.283 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2015-02-13T08:25:28.283Z,1423815928.283 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2015-02-13T08:25:28.284Z,1423815928.284 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2015-02-13T08:25:28.294Z,1423815928.294 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2015-02-13T08:25:28.294Z,1423815928.294 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2015-02-13T08:25:28.421Z,1423815928.421 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2015-02-13T08:25:28.422Z,1423815928.422 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2015-02-13T08:25:29.080Z,1423815929.080 [DataOverHttps] Loaded 2015-02-13T08:25:29.080Z,1423815929.080 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2015-02-13T08:25:29.161Z,1423815929.161 [Depth_Keller] Loaded 2015-02-13T08:25:29.161Z,1423815929.161 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2015-02-13T08:25:29.441Z,1423815929.441 [DVL_micro] Loaded 2015-02-13T08:25:29.442Z,1423815929.442 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2015-02-13T08:25:29.540Z,1423815929.540 [NAL9602] Loaded 2015-02-13T08:25:29.540Z,1423815929.540 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2015-02-13T08:25:29.583Z,1423815929.583 [Onboard] Loaded 2015-02-13T08:25:29.583Z,1423815929.583 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2015-02-13T08:25:29.591Z,1423815929.591 [Radio_Surface] Loaded 2015-02-13T08:25:29.591Z,1423815929.591 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2015-02-13T08:25:29.592Z,1423815929.592 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408074E0 2015-02-13T08:25:29.593Z,1423815929.593 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 863 2015-02-13T08:25:29.642Z,1423815929.642 [PNI_TCM] Loaded 2015-02-13T08:25:29.642Z,1423815929.642 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2015-02-13T08:25:31.622Z,1423815931.622 [BPC1] Loaded 2015-02-13T08:25:31.623Z,1423815931.623 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2015-02-13T08:25:31.623Z,1423815931.623 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2015-02-13T08:25:31.624Z,1423815931.624 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2015-02-13T08:25:41.709Z,1423815941.709 [BuoyancyServo] Loaded 2015-02-13T08:25:41.709Z,1423815941.709 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2015-02-13T08:25:41.722Z,1423815941.722 [ElevatorServo] Loaded 2015-02-13T08:25:41.722Z,1423815941.722 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2015-02-13T08:25:41.735Z,1423815941.735 [MassServo] Loaded 2015-02-13T08:25:41.735Z,1423815941.735 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2015-02-13T08:25:41.747Z,1423815941.747 [RudderServo] Loaded 2015-02-13T08:25:41.748Z,1423815941.748 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2015-02-13T08:25:41.759Z,1423815941.759 [ThrusterServo] Loaded 2015-02-13T08:25:41.760Z,1423815941.760 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2015-02-13T08:25:41.760Z,1423815941.760 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2015-02-13T08:25:41.761Z,1423815941.761 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2015-02-13T08:25:42.045Z,1423815942.045 [ExternalSim] Loaded 2015-02-13T08:25:42.046Z,1423815942.046 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread. 2015-02-13T08:25:42.046Z,1423815942.046 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2015-02-13T08:25:42.047Z,1423815942.047 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2015-02-13T08:25:42.088Z,1423815942.088 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2015-02-13T08:25:42.090Z,1423815942.090 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2015-02-13T08:25:42.091Z,1423815942.091 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2015-02-13T08:25:42.098Z,1423815942.098 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2015-02-13T08:25:42.099Z,1423815942.099 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408B84E0 2015-02-13T08:25:42.099Z,1423815942.099 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 864 2015-02-13T08:25:42.104Z,1423815942.104 [Supervisor](INFO): Main Thread ID is 776 2015-02-13T08:25:42.104Z,1423815942.104 [Supervisor](DEBUG): Running supervisor. 2015-02-13T08:25:42.105Z,1423815942.105 [CommandLine ThreadHandler](INFO): Handler Thread ID is 865 2015-02-13T08:25:42.108Z,1423815942.108 [controlThread ThreadHandler](INFO): Handler Thread ID is 866 2015-02-13T08:25:42.108Z,1423815942.108 [controlThread](DEBUG): Initializing ControlThread 2015-02-13T08:25:42.109Z,1423815942.109 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2015-02-13T08:25:42.111Z,1423815942.111 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2015-02-13T08:25:42.112Z,1423815942.112 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2015-02-13T08:25:42.112Z,1423815942.112 [LoopControl](DEBUG): Initialize LoopControlComponent. 2015-02-13T08:25:42.112Z,1423815942.112 [SBIT](INFO): Initialize SBIT Component. 2015-02-13T08:25:42.113Z,1423815942.113 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12012 2015-02-13T08:25:42.113Z,1423815942.113 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2015-02-13T08:25:42.114Z,1423815942.114 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 2015-02-13T08:25:42.114Z,1423815942.114 [IBIT](INFO): Initialize IBIT Component. 2015-02-13T08:25:42.115Z,1423815942.115 [CBIT](DEBUG): Initialize CBIT Component. 2015-02-13T08:25:42.115Z,1423815942.115 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2015-02-13T08:25:42.115Z,1423815942.115 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2015-02-13T08:25:42.116Z,1423815942.116 [logger ThreadHandler](INFO): Handler Thread ID is 867 2015-02-13T08:25:42.142Z,1423815942.142 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2015-02-13T08:25:42.142Z,1423815942.142 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2015-02-13T08:25:42.143Z,1423815942.143 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2015-02-13T08:25:42.143Z,1423815942.143 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2015-02-13T08:25:42.143Z,1423815942.143 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2015-02-13T08:25:42.145Z,1423815942.145 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s. 2015-02-13T08:25:42.146Z,1423815942.146 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2015-02-13T08:25:42.147Z,1423815942.147 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2015-02-13T08:25:42.147Z,1423815942.147 [NavChart](DEBUG): Initialize NavChart Navigation. 2015-02-13T08:25:42.148Z,1423815942.148 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2015-02-13T08:25:42.152Z,1423815942.152 [ExternalSim](INFO): ExternalSim initializing... 2015-02-13T08:25:42.162Z,1423815942.162 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 868 2015-02-13T08:25:42.169Z,1423815942.169 [Radio_Surface](INFO): Powering up 2015-02-13T08:25:42.198Z,1423815942.198 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 869 2015-02-13T08:25:42.210Z,1423815942.210 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2015-02-13T08:25:42.210Z,1423815942.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2015-02-13T08:25:42.211Z,1423815942.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2015-02-13T08:25:42.211Z,1423815942.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2015-02-13T08:25:42.211Z,1423815942.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2015-02-13T08:25:42.211Z,1423815942.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2015-02-13T08:25:42.212Z,1423815942.212 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2015-02-13T08:25:42.212Z,1423815942.212 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2015-02-13T08:25:42.212Z,1423815942.212 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2015-02-13T08:25:42.465Z,1423815942.465 [ExternalSim](DEBUG): beaconLat = 36.80340 2015-02-13T08:25:42.465Z,1423815942.465 [ExternalSim](DEBUG): beaconLon = -121.82230 2015-02-13T08:25:42.466Z,1423815942.466 [ExternalSim](DEBUG): beaconDepth = 25.00 2015-02-13T08:25:42.697Z,1423815942.697 [ExternalSim](DEBUG): Simulator initialized 2015-02-13T08:25:42.843Z,1423815942.843 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2015-02-13T08:25:42.872Z,1423815942.872 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2015-02-13T08:25:42.897Z,1423815942.897 [MissionManager](DEBUG): 2015-02-13T08:25:42.898Z,1423815942.898 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2015-02-13T08:25:42.974Z,1423815942.974 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h 2015-02-13T08:25:42.986Z,1423815942.986 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h 2015-02-13T08:25:42.990Z,1423815942.990 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2015-02-13T08:25:43.035Z,1423815943.035 [Default:CheckIn:A.SetSpeed](DEBUG): Construct. 2015-02-13T08:25:43.042Z,1423815943.042 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2015-02-13T08:25:43.070Z,1423815943.070 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct. 2015-02-13T08:25:43.085Z,1423815943.085 [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 2015-02-13T08:25:43.104Z,1423815943.104 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,DataOverHttps,Depth_Keller,DVL_micro,NAL9602,Onboard,PNI_TCM,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2015-02-13T08:25:43.364Z,1423815943.364 [DVL_micro](INFO): Initializing 2015-02-13T08:25:43.385Z,1423815943.385 [NAL9602](INFO): Powering up NAL9602 2015-02-13T08:25:43.656Z,1423815943.656 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:25:43.663Z,1423815943.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:25:43.832Z,1423815943.832 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2015-02-13T08:25:43.840Z,1423815943.840 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2015-02-13T08:25:43.859Z,1423815943.859 [ElevatorServo](DEBUG): Initializing EZServoServo. 2015-02-13T08:25:43.865Z,1423815943.865 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2015-02-13T08:25:43.879Z,1423815943.879 [MassServo](DEBUG): Initializing EZServoServo. 2015-02-13T08:25:43.885Z,1423815943.885 [MassServo](DEBUG): Initializing MassServo. 2015-02-13T08:25:43.893Z,1423815943.893 [RudderServo](DEBUG): Initializing EZServoServo. 2015-02-13T08:25:43.901Z,1423815943.901 [RudderServo](DEBUG): Initializing RudderServo. 2015-02-13T08:25:43.911Z,1423815943.911 [ThrusterServo](DEBUG): Initializing EZServoServo. 2015-02-13T08:25:43.917Z,1423815943.917 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2015-02-13T08:25:43.969Z,1423815943.969 [CommandLine](FAULT): Scheduling is paused 2015-02-13T08:25:44.236Z,1423815944.236 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:25:44.245Z,1423815944.245 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:25:55.612Z,1423815955.612 [BPC1](ERROR): B bank expecting battery number:1 and read number: uart read:X $S,01,270F,02,00,04,5E%57 $C1,01,7F,02,00,03,7F,04,00,05,00,06,00,07,00%72 $B11,02,000A,01,026C,03,0001,08,0B8D,09,4009,0A,FFF3,0B,FFF8%4F $B11,0C,0001,0D,0062,0E,005B,0F,1602,10,170E,11,6593,12,A50F%4B $B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%41 $B11,1A,0031,1B,4357,1C,00AD%31 $B12,02,000A,01,026C,03,0001,08,0B86,09,3DD5,0A,0000,0B,0002%3C $B12,0C,0001,0D,0051,0E,004C,0F,1233,10,16B8,11,FFFF,12,FFFF%4B $B12,13,8796,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%42 $B12,1A,0031,1B,4357,1C,00AB%34 $B13,02,000A,01,026C,03,0001,08,0B81,09,3DFC,0A,0000,0B,0002%4E $B13,0C,0001,0D,005B,0E,004C,0F,1253,10,169D,11,FFFF,12,FFFF%38 $B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%43 $B13,1A,0031,1B,4357,1C,00B1%45 $B14,02,000A,01,026C,03,0001,08,0B87,09,3F8E,0A,0000,0B,0001%36 $B14,0C,0001,0D,005B,0E,0055,0F,1472,10,1685,11,FFFF,12,FFFF%3D $B14,13,7C74,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%33 $B14,1A,0031,1B,4357,1C,00A0%40 $B15,02,000A,01,026C,03,0001,08,0B89,09,402B,0A,0000,0B,0001%45 $B15,0C,0001,0D,0063,0E,005D,0F,1669,10,1716,11,FFFF,12,FFFF%3C $B15,13,288C,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%37 $B15,1A,0031,1B,4357,1C,008B%4A $B16,02,000A,01,026C,03,0001,08,0B7D,09,4023,0A,0000,0B,0001%45 $B16,0C,0001,0D,0062,0E,005D,0F,1667,10,1735,11,FFFF,12,FFFF%31 $B16,13,3048,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%4A $B16,1A,0031,1B,4339,1C,007F%4A $B17,02,000A,01,026C,03,0001,08,0B7A,09,3989,0A,0000,0B,0001%4F $B17,0C,0001,0D,0062,0E,005B,0F,15DC,10,16E1,11,FFFF,12,FFFF%40 $B17,13,3D2C,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4C $B17,1A,0031,1B,4357,1C,00B4%44 $S,01,270F,02,00,04,5E%57 $C1,01,7F,02,00,03,7F,04,00,05,00,06,00,07,00%72 $B11,0A,FFF3%46 $B12,0A,0000%30 $B13,0A,0000%31 $B14,0A,0000%36 $B15,0A,0000%37 $B16,0A,0000%34 $B17,0A,0000%35 2015-02-13T08:25:55.612Z,1423815955.612 [BPC1](ERROR): Failed to parse bank B battery data 2015-02-13T08:25:55.612Z,1423815955.612 [BPC1] Data Fault, FailCount= 1 2015-02-13T08:25:55.613Z,1423815955.613 [BPC1](ERROR): Data Fault 2015-02-13T08:25:55.630Z,1423815955.630 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:25:55.631Z,1423815955.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:25:55.950Z,1423815955.950 [CBIT](ERROR): Data Fault in component: BPC1 2015-02-13T08:25:56.204Z,1423815956.204 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:25:56.206Z,1423815956.206 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:25:56.473Z,1423815956.473 [NAL9602](INFO): NAL9602 initialized 2015-02-13T08:25:56.652Z,1423815956.652 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:25:56.653Z,1423815956.653 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:25:56.866Z,1423815956.866 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003506 2015-02-13T08:25:56.944Z,1423815956.944 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:25:56.945Z,1423815956.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:25:56.966Z,1423815956.966 [CBIT](INFO): Clearing failed state for component BPC1 2015-02-13T08:25:56.966Z,1423815956.966 [BPC1] No Fault, FailCount= 1 2015-02-13T08:25:57.393Z,1423815957.393 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:25:57.737Z,1423815957.737 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:25:57.767Z,1423815957.767 [SBIT](IMPORTANT): Beginning Startup BIT 2015-02-13T08:25:57.770Z,1423815957.770 [CBIT](IMPORTANT): Beginning GF scan 2015-02-13T08:25:58.198Z,1423815958.198 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:25:58.638Z,1423815958.638 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:25:59.336Z,1423815959.336 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:25:59.705Z,1423815959.705 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:00.031Z,1423815960.031 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:00.554Z,1423815960.554 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:00.976Z,1423815960.976 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:01.339Z,1423815961.339 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:01.763Z,1423815961.763 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:02.157Z,1423815962.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:02.706Z,1423815962.706 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:03.080Z,1423815963.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:03.510Z,1423815963.510 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:04.033Z,1423815964.033 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:04.499Z,1423815964.499 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:04.995Z,1423815964.995 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:05.499Z,1423815965.499 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:05.874Z,1423815965.874 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:06.241Z,1423815966.241 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:06.678Z,1423815966.678 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:07.017Z,1423815967.017 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:07.611Z,1423815967.611 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:08.120Z,1423815968.120 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:08.642Z,1423815968.642 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:08.931Z,1423815968.931 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:09.396Z,1423815969.396 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:09.836Z,1423815969.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:10.137Z,1423815970.137 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:10.561Z,1423815970.561 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:11.001Z,1423815971.001 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:11.149Z,1423815971.149 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.667913 2015-02-13T08:26:11.379Z,1423815971.379 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:11.843Z,1423815971.843 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:12.293Z,1423815972.293 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:12.624Z,1423815972.624 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:13.016Z,1423815973.016 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:13.347Z,1423815973.347 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:14.207Z,1423815974.207 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:14.749Z,1423815974.749 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:15.351Z,1423815975.351 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:15.819Z,1423815975.819 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:16.131Z,1423815976.131 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:16.657Z,1423815976.657 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:17.011Z,1423815977.011 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:17.369Z,1423815977.369 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:17.784Z,1423815977.784 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:18.235Z,1423815978.235 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:18.708Z,1423815978.708 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:19.511Z,1423815979.511 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:20.143Z,1423815980.143 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:20.533Z,1423815980.533 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:21.010Z,1423815981.010 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:21.451Z,1423815981.451 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:21.828Z,1423815981.828 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:22.263Z,1423815982.263 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:22.664Z,1423815982.664 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:23.057Z,1423815983.057 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:23.451Z,1423815983.451 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:23.894Z,1423815983.894 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:24.335Z,1423815984.335 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:24.528Z,1423815984.528 [CBIT](IMPORTANT): No ground fault detected 2015-02-13T08:26:24.716Z,1423815984.716 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:25.107Z,1423815985.107 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:25.557Z,1423815985.557 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:26.000Z,1423815986.000 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:26.454Z,1423815986.454 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:26.923Z,1423815986.923 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:27.439Z,1423815987.439 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:27.845Z,1423815987.845 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:28.397Z,1423815988.397 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:28.714Z,1423815988.714 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:29.116Z,1423815989.116 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:29.567Z,1423815989.567 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:30.012Z,1423815990.012 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:30.305Z,1423815990.305 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:30.789Z,1423815990.789 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:31.139Z,1423815991.139 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:31.546Z,1423815991.546 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:31.954Z,1423815991.954 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:32.279Z,1423815992.279 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:32.728Z,1423815992.728 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:33.140Z,1423815993.140 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:33.534Z,1423815993.534 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:33.894Z,1423815993.894 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:34.349Z,1423815994.349 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:34.672Z,1423815994.672 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:35.144Z,1423815995.144 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:35.532Z,1423815995.532 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:35.877Z,1423815995.877 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:36.290Z,1423815996.290 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:36.686Z,1423815996.686 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:37.119Z,1423815997.119 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:37.499Z,1423815997.499 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:37.872Z,1423815997.872 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:38.294Z,1423815998.294 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:38.725Z,1423815998.725 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:39.094Z,1423815999.094 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:39.461Z,1423815999.461 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:39.907Z,1423815999.907 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:40.267Z,1423816000.267 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:40.859Z,1423816000.859 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:41.301Z,1423816001.301 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:41.698Z,1423816001.698 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:42.059Z,1423816002.059 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:42.496Z,1423816002.496 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:42.870Z,1423816002.870 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:43.272Z,1423816003.272 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:43.658Z,1423816003.658 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:51.340Z,1423816011.340 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:51.674Z,1423816011.674 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:51.844Z,1423816011.844 [SBIT](CRITICAL): SBIT FAILED 2015-02-13T08:26:52.192Z,1423816012.192 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:52.228Z,1423816012.228 [MissionManager](IMPORTANT): Started mission Startup 2015-02-13T08:26:52.228Z,1423816012.228 [Startup] Running Loop=1 2015-02-13T08:26:52.228Z,1423816012.228 [Startup](INFO): Aggregate::initialize Startup 2015-02-13T08:26:52.229Z,1423816012.229 [Startup:A.GoToSurface] Running Loop=1 2015-02-13T08:26:52.229Z,1423816012.229 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2015-02-13T08:26:52.229Z,1423816012.229 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2015-02-13T08:26:52.230Z,1423816012.230 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2015-02-13T08:26:52.230Z,1423816012.230 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s. 2015-02-13T08:26:52.287Z,1423816012.287 [Startup:StartupSatComms] Running Loop=1 2015-02-13T08:26:52.287Z,1423816012.287 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2015-02-13T08:26:52.287Z,1423816012.287 [Startup:StartupSatComms:A] Running Loop=1 2015-02-13T08:26:52.611Z,1423816012.611 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:52.616Z,1423816012.616 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2015-02-13T08:26:52.897Z,1423816012.897 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:53.332Z,1423816013.332 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:53.760Z,1423816013.760 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:54.148Z,1423816014.148 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:54.530Z,1423816014.530 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:54.864Z,1423816014.864 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:55.319Z,1423816015.319 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:55.748Z,1423816015.748 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:56.323Z,1423816016.323 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:56.548Z,1423816016.548 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:57.092Z,1423816017.092 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:57.825Z,1423816017.825 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:58.392Z,1423816018.392 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:58.776Z,1423816018.776 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:58.987Z,1423816018.987 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:59.414Z,1423816019.414 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:26:59.826Z,1423816019.826 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:00.191Z,1423816020.191 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:00.613Z,1423816020.613 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:01.056Z,1423816021.056 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:01.392Z,1423816021.392 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:01.836Z,1423816021.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:02.257Z,1423816022.257 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:02.604Z,1423816022.604 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:03.071Z,1423816023.071 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:03.412Z,1423816023.412 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:03.813Z,1423816023.813 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:04.221Z,1423816024.221 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:04.681Z,1423816024.681 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:05.006Z,1423816025.006 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:05.407Z,1423816025.407 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:05.889Z,1423816025.889 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:06.240Z,1423816026.240 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:06.661Z,1423816026.661 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:07.029Z,1423816027.029 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:07.402Z,1423816027.402 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:08.059Z,1423816028.059 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:08.308Z,1423816028.308 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:08.712Z,1423816028.712 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:09.140Z,1423816029.140 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:09.477Z,1423816029.477 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:09.896Z,1423816029.896 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:10.243Z,1423816030.243 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:10.643Z,1423816030.643 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:11.053Z,1423816031.053 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:11.456Z,1423816031.456 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:11.852Z,1423816031.852 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:12.257Z,1423816032.257 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:12.665Z,1423816032.665 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:13.142Z,1423816033.142 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:13.466Z,1423816033.466 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:13.870Z,1423816033.870 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:14.249Z,1423816034.249 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:14.825Z,1423816034.825 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:15.110Z,1423816035.110 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:15.574Z,1423816035.574 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:15.887Z,1423816035.887 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:16.288Z,1423816036.288 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:16.656Z,1423816036.656 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:17.061Z,1423816037.061 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:17.454Z,1423816037.454 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:17.864Z,1423816037.864 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:18.267Z,1423816038.267 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:18.660Z,1423816038.660 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:19.032Z,1423816039.032 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:19.484Z,1423816039.484 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:19.895Z,1423816039.895 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:20.318Z,1423816040.318 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:20.687Z,1423816040.687 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:21.080Z,1423816041.080 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:21.517Z,1423816041.517 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:21.903Z,1423816041.903 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:22.238Z,1423816042.238 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:22.685Z,1423816042.685 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:23.038Z,1423816043.038 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:23.523Z,1423816043.523 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:23.881Z,1423816043.881 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:24.271Z,1423816044.271 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:24.669Z,1423816044.669 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:25.033Z,1423816045.033 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:25.454Z,1423816045.454 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:25.855Z,1423816045.855 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:26.327Z,1423816046.327 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:26.629Z,1423816046.629 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:27.033Z,1423816047.033 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:27.457Z,1423816047.457 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:27.846Z,1423816047.846 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:28.231Z,1423816048.231 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:28.631Z,1423816048.631 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:29.028Z,1423816049.028 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:29.581Z,1423816049.581 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:29.841Z,1423816049.841 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:30.234Z,1423816050.234 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:30.675Z,1423816050.675 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:31.056Z,1423816051.056 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:31.459Z,1423816051.459 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:31.870Z,1423816051.870 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:32.258Z,1423816052.258 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:32.729Z,1423816052.729 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:33.071Z,1423816053.071 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:33.478Z,1423816053.478 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:33.871Z,1423816053.871 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:34.265Z,1423816054.265 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:34.701Z,1423816054.701 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:35.065Z,1423816055.065 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:35.414Z,1423816055.414 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:35.895Z,1423816055.895 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:36.305Z,1423816056.305 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:36.681Z,1423816056.681 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:37.063Z,1423816057.063 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:37.514Z,1423816057.514 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:37.867Z,1423816057.867 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:38.311Z,1423816058.311 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:38.685Z,1423816058.685 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:39.035Z,1423816059.035 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:39.447Z,1423816059.447 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:39.892Z,1423816059.892 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:40.311Z,1423816060.311 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:40.649Z,1423816060.649 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:41.051Z,1423816061.051 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:41.487Z,1423816061.487 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:41.876Z,1423816061.876 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:42.255Z,1423816062.255 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:42.654Z,1423816062.654 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:43.062Z,1423816063.062 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:43.470Z,1423816063.470 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:43.838Z,1423816063.838 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:48.013Z,1423816068.013 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:48.183Z,1423816068.183 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:48.688Z,1423816068.688 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:48.980Z,1423816068.980 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:49.428Z,1423816069.428 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:49.952Z,1423816069.952 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:50.296Z,1423816070.296 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:50.569Z,1423816070.569 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:50.993Z,1423816070.993 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:51.342Z,1423816071.342 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:51.739Z,1423816071.739 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:52.143Z,1423816072.143 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:52.548Z,1423816072.548 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:52.552Z,1423816072.552 [Startup:StartupSatComms:A](INFO): Timed out from 2015-02-13T08:26:52.3Z 2015-02-13T08:27:52.552Z,1423816072.552 [Startup:StartupSatComms:A] Stopped 2015-02-13T08:27:52.552Z,1423816072.552 [Startup:StartupSatComms:B] Running Loop=1 2015-02-13T08:27:53.027Z,1423816073.027 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:53.031Z,1423816073.031 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2015-02-13T08:27:53.795Z,1423816073.795 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:53.946Z,1423816073.946 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:57.151Z,1423816077.151 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:57.273Z,1423816077.273 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20150213T003027/Courier0212.lzma 2015-02-13T08:27:57.325Z,1423816077.325 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:57.709Z,1423816077.709 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:58.056Z,1423816078.056 [DataOverHttps](INFO): Moved sent file to Logs/20150213T003027/Courier0212.lzma.bak 2015-02-13T08:27:58.056Z,1423816078.056 [DataOverHttps](INFO): SBD MOMSN=1529456 2015-02-13T08:27:58.144Z,1423816078.144 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:58.818Z,1423816078.818 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:27:58.978Z,1423816078.978 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:02.324Z,1423816082.324 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:02.501Z,1423816082.501 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:02.892Z,1423816082.892 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:03.277Z,1423816083.277 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:03.726Z,1423816083.726 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:04.053Z,1423816084.053 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:07.415Z,1423816087.415 [BPC1](ERROR): Failed to receive battery data 2015-02-13T08:28:07.440Z,1423816087.440 [BPC1] Communications Fault, FailCount= 1 2015-02-13T08:28:07.440Z,1423816087.440 [BPC1](ERROR): Communications Fault 2015-02-13T08:28:07.446Z,1423816087.446 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:07.458Z,1423816087.458 [CBIT](ERROR): Communications Fault in component: BPC1 2015-02-13T08:28:07.608Z,1423816087.608 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:08.016Z,1423816088.016 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:08.408Z,1423816088.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:08.428Z,1423816088.428 [CBIT](INFO): Clearing failed state for component BPC1 2015-02-13T08:28:08.429Z,1423816088.429 [BPC1] No Fault, FailCount= 1 2015-02-13T08:28:08.854Z,1423816088.854 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:09.217Z,1423816089.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:12.404Z,1423816092.404 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:12.510Z,1423816092.510 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20150213T082514/Courier0000.lzma 2015-02-13T08:28:12.560Z,1423816092.560 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:12.960Z,1423816092.960 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:13.308Z,1423816093.308 [DataOverHttps](INFO): Moved sent file to Logs/20150213T082514/Courier0000.lzma.bak 2015-02-13T08:28:13.308Z,1423816093.308 [DataOverHttps](INFO): SBD MOMSN=1529458 2015-02-13T08:28:13.380Z,1423816093.380 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:25.107Z,1423816105.107 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:25.481Z,1423816105.481 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:28.499Z,1423816108.499 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:28.629Z,1423816108.629 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:29.081Z,1423816109.081 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:29.489Z,1423816109.489 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:29.878Z,1423816109.878 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:30.271Z,1423816110.271 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:33.487Z,1423816113.487 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:33.593Z,1423816113.593 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20150213T003027/Express0213.lzma 2015-02-13T08:28:33.657Z,1423816113.657 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:34.044Z,1423816114.044 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:34.391Z,1423816114.391 [DataOverHttps](INFO): Moved sent file to Logs/20150213T003027/Express0213.lzma.bak 2015-02-13T08:28:34.391Z,1423816114.391 [DataOverHttps](INFO): SBD MOMSN=1529463 2015-02-13T08:28:34.487Z,1423816114.487 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:35.357Z,1423816115.357 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:35.512Z,1423816115.512 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:38.723Z,1423816118.723 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:38.894Z,1423816118.894 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:39.302Z,1423816119.302 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:39.687Z,1423816119.687 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:40.114Z,1423816120.114 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:40.482Z,1423816120.482 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:43.710Z,1423816123.710 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:43.712Z,1423816123.712 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2015-02-13T08:28:43.712Z,1423816123.712 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2015-02-13T08:28:43.712Z,1423816123.712 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2015-02-13T08:28:43.714Z,1423816123.714 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2015-02-13T08:28:43.714Z,1423816123.714 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2015-02-13T08:28:43.714Z,1423816123.714 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2015-02-13T08:28:43.725Z,1423816123.725 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2015-02-13T08:28:43.726Z,1423816123.726 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2015-02-13T08:28:43.873Z,1423816123.873 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:43.891Z,1423816123.891 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2015-02-13T08:28:43.891Z,1423816123.891 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2015-02-13T08:28:43.892Z,1423816123.892 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2015-02-13T08:28:43.892Z,1423816123.892 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2015-02-13T08:28:44.305Z,1423816124.305 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:44.306Z,1423816124.306 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2015-02-13T08:28:44.306Z,1423816124.306 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2015-02-13T08:28:44.653Z,1423816124.653 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:44.654Z,1423816124.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:28:45.094Z,1423816125.094 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:45.095Z,1423816125.095 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:28:45.534Z,1423816125.534 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:45.536Z,1423816125.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:28:50.565Z,1423816130.565 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:50.566Z,1423816130.566 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:28:50.701Z,1423816130.701 [DataOverHttps](INFO): Sending 546 bytes from file Logs/20150213T082514/Express0001.lzma 2015-02-13T08:28:50.742Z,1423816130.742 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:50.743Z,1423816130.743 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:28:51.168Z,1423816131.168 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:51.169Z,1423816131.169 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles. 2015-02-13T08:28:51.497Z,1423816131.497 [DataOverHttps](INFO): Moved sent file to Logs/20150213T082514/Express0001.lzma.bak 2015-02-13T08:28:51.497Z,1423816131.497 [DataOverHttps](INFO): SBD MOMSN=1529465 2015-02-13T08:28:51.552Z,1423816131.552 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:52.477Z,1423816132.477 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:52.500Z,1423816132.500 [Startup:StartupSatComms:B] Stopped 2015-02-13T08:28:52.501Z,1423816132.501 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2015-02-13T08:28:52.501Z,1423816132.501 [Startup:StartupSatComms] Stopped 2015-02-13T08:28:52.501Z,1423816132.501 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2015-02-13T08:28:52.506Z,1423816132.506 [Startup](INFO): Completed Startup 2015-02-13T08:28:52.506Z,1423816132.506 [Startup] Stopped 2015-02-13T08:28:52.506Z,1423816132.506 [Startup](INFO): Aggregate::uninitialize Startup 2015-02-13T08:28:52.506Z,1423816132.506 [Startup:A.GoToSurface] Stopped 2015-02-13T08:28:52.507Z,1423816132.507 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2015-02-13T08:28:52.691Z,1423816132.691 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:52.695Z,1423816132.695 [MissionManager](IMPORTANT): Started mission Default 2015-02-13T08:28:52.695Z,1423816132.695 [Default] Running Loop=1 2015-02-13T08:28:52.695Z,1423816132.695 [Default](INFO): Aggregate::initialize Default 2015-02-13T08:28:52.696Z,1423816132.696 [Default:B.GoToSurface] Running Loop=1 2015-02-13T08:28:52.696Z,1423816132.696 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2015-02-13T08:28:52.696Z,1423816132.696 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2015-02-13T08:28:52.696Z,1423816132.696 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2015-02-13T08:28:52.697Z,1423816132.697 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s. 2015-02-13T08:28:52.697Z,1423816132.697 [Default:StartClock] Running Loop=1 2015-02-13T08:28:52.697Z,1423816132.697 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock 2015-02-13T08:28:52.697Z,1423816132.697 [Default:StartClock:A] Running Loop=1 2015-02-13T08:28:52.705Z,1423816132.705 [Default:StartClock:A] Stopped 2015-02-13T08:28:52.705Z,1423816132.705 [Default:StartClock](INFO): Completed Default:StartClock 2015-02-13T08:28:52.705Z,1423816132.705 [Default:StartClock] Stopped 2015-02-13T08:28:52.705Z,1423816132.705 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock 2015-02-13T08:28:56.231Z,1423816136.231 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:56.235Z,1423816136.235 [Default:WaitAtTheSurface] Running Loop=1 2015-02-13T08:28:56.236Z,1423816136.236 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface 2015-02-13T08:28:56.236Z,1423816136.236 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1 2015-02-13T08:28:56.236Z,1423816136.236 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize. 2015-02-13T08:28:56.384Z,1423816136.384 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:28:56.387Z,1423816136.387 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1 2015-02-13T08:29:01.435Z,1423816141.435 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:29:06.396Z,1423816146.396 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:29:11.435Z,1423816151.435 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:29:16.405Z,1423816156.405 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:29:21.435Z,1423816161.435 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:29:26.446Z,1423816166.446 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:29:31.394Z,1423816171.394 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:29:36.378Z,1423816176.378 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:29:41.483Z,1423816181.483 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:29:46.454Z,1423816186.454 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:29:51.429Z,1423816191.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:29:56.412Z,1423816196.412 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:01.426Z,1423816201.426 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:09.225Z,1423816209.225 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:11.417Z,1423816211.417 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:16.452Z,1423816216.452 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:21.429Z,1423816221.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:26.452Z,1423816226.452 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:31.436Z,1423816231.436 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:36.427Z,1423816236.427 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:41.436Z,1423816241.436 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:46.472Z,1423816246.472 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:46.491Z,1423816246.491 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1 2015-02-13T08:30:46.492Z,1423816246.492 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted 2015-02-13T08:30:46.492Z,1423816246.492 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1 2015-02-13T08:30:46.494Z,1423816246.494 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped 2015-02-13T08:30:46.494Z,1423816246.494 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1 2015-02-13T08:30:51.407Z,1423816251.407 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:30:51.424Z,1423816251.424 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 1.895550 min 2015-02-13T08:30:51.426Z,1423816251.426 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped 2015-02-13T08:30:51.426Z,1423816251.426 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted 2015-02-13T08:30:51.426Z,1423816251.426 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped 2015-02-13T08:30:51.426Z,1423816251.426 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted 2015-02-13T08:30:56.423Z,1423816256.423 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:31:01.366Z,1423816261.366 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2015-02-13T08:31:01.423Z,1423816261.423 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:31:06.420Z,1423816266.420 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:31:14.406Z,1423816274.406 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:31:16.366Z,1423816276.366 [NAL9602](IMPORTANT): GPS fix at: 1423816276.36 2015-02-13T08:31:16.410Z,1423816276.410 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:31:21.469Z,1423816281.469 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:31:26.446Z,1423816286.446 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast 2015-02-13T08:31:31.410Z,1423816291.410 [HFRCMReconstructedInterpolator](DEBUG): no valid