2014-10-27T22:12:19.340Z,1414447939.340 [Supervisor](DEBUG): Initializing supervisor.
2014-10-27T22:12:19.343Z,1414447939.343 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-10-27T22:12:19.343Z,1414447939.343 [SyncHandler](INFO): Protected caller Thread ID is 9203
2014-10-27T22:12:19.343Z,1414447939.343 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-10-27T22:12:19.344Z,1414447939.344 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-10-27T22:12:19.345Z,1414447939.345 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9204
2014-10-27T22:12:19.348Z,1414447939.348 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-10-27T22:12:19.359Z,1414447939.359 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-10-27T22:12:19.360Z,1414447939.360 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-10-27T22:12:19.361Z,1414447939.361 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9205
2014-10-27T22:12:19.362Z,1414447939.362 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-10-27T22:12:19.363Z,1414447939.363 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-10-27T22:12:19.363Z,1414447939.363 [logger ThreadHandler](INFO): Protected caller Thread ID is 9206
2014-10-27T22:12:19.365Z,1414447939.365 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-10-27T22:12:19.366Z,1414447939.366 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-10-27T22:12:19.367Z,1414447939.367 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-10-27T22:12:19.693Z,1414447939.693 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-10-27T22:12:19.693Z,1414447939.693 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-10-27T22:12:19.855Z,1414447939.855 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-10-27T22:12:19.856Z,1414447939.856 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-10-27T22:12:19.963Z,1414447939.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-10-27T22:12:19.964Z,1414447939.964 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-10-27T22:12:20.086Z,1414447940.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-10-27T22:12:20.087Z,1414447940.087 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-10-27T22:12:20.173Z,1414447940.173 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-10-27T22:12:20.315Z,1414447940.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-10-27T22:12:20.316Z,1414447940.316 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-10-27T22:12:20.400Z,1414447940.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-10-27T22:12:20.401Z,1414447940.401 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-10-27T22:12:20.603Z,1414447940.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-10-27T22:12:20.604Z,1414447940.604 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-10-27T22:12:20.955Z,1414447940.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-10-27T22:12:20.956Z,1414447940.956 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-10-27T22:12:21.251Z,1414447941.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-10-27T22:12:21.252Z,1414447941.252 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-10-27T22:12:21.742Z,1414447941.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-10-27T22:12:21.742Z,1414447941.742 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-10-27T22:12:21.935Z,1414447941.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-10-27T22:12:21.936Z,1414447941.936 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-10-27T22:12:22.044Z,1414447942.044 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-10-27T22:12:22.044Z,1414447942.044 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-10-27T22:12:22.477Z,1414447942.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-10-27T22:12:22.477Z,1414447942.477 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-10-27T22:12:22.587Z,1414447942.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-10-27T22:12:22.589Z,1414447942.589 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2014-10-27T22:12:22.590Z,1414447942.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2014-10-27T22:12:22.859Z,1414447942.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2014-10-27T22:12:22.860Z,1414447942.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2014-10-27T22:12:23.013Z,1414447943.013 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2014-10-27T22:12:23.158Z,1414447943.158 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2014-10-27T22:12:23.254Z,1414447943.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2014-10-27T22:12:23.350Z,1414447943.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2014-10-27T22:12:23.489Z,1414447943.489 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2014-10-27T22:12:23.671Z,1414447943.671 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2014-10-27T22:12:23.771Z,1414447943.771 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2014-10-27T22:12:23.856Z,1414447943.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2014-10-27T22:12:23.955Z,1414447943.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2014-10-27T22:12:24.051Z,1414447944.051 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2014-10-27T22:12:24.224Z,1414447944.224 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-10-27T22:12:24.237Z,1414447944.237 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-10-27T22:12:24.290Z,1414447944.290 [VerticalControl](DEBUG): Construct VerticalControl.
2014-10-27T22:12:24.410Z,1414447944.410 [VerticalControl] Loaded
2014-10-27T22:12:24.411Z,1414447944.411 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-10-27T22:12:24.411Z,1414447944.411 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-10-27T22:12:24.484Z,1414447944.484 [HorizontalControl] Loaded
2014-10-27T22:12:24.484Z,1414447944.484 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-10-27T22:12:24.485Z,1414447944.485 [SpeedControl](DEBUG): Construct SpeedControl.
2014-10-27T22:12:24.491Z,1414447944.491 [SpeedControl] Loaded
2014-10-27T22:12:24.491Z,1414447944.491 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-10-27T22:12:24.492Z,1414447944.492 [LoopControl](DEBUG): Construct LoopControl.
2014-10-27T22:12:24.492Z,1414447944.492 [LoopControl] Loaded
2014-10-27T22:12:24.492Z,1414447944.492 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-10-27T22:12:24.493Z,1414447944.493 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-10-27T22:12:24.493Z,1414447944.493 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-10-27T22:12:24.630Z,1414447944.630 [SBIT](DEBUG): Construct Startup Built In Test.
2014-10-27T22:12:24.641Z,1414447944.641 [SBIT] Loaded
2014-10-27T22:12:24.642Z,1414447944.642 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-10-27T22:12:24.643Z,1414447944.643 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-10-27T22:12:24.672Z,1414447944.672 [IBIT] Loaded
2014-10-27T22:12:24.672Z,1414447944.672 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-10-27T22:12:24.674Z,1414447944.674 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-10-27T22:12:24.796Z,1414447944.796 [CBIT] Loaded
2014-10-27T22:12:24.796Z,1414447944.796 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-10-27T22:12:24.796Z,1414447944.796 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-10-27T22:12:24.797Z,1414447944.797 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-10-27T22:12:24.816Z,1414447944.816 [DepthRateCalculator] Loaded
2014-10-27T22:12:24.816Z,1414447944.816 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-10-27T22:12:24.821Z,1414447944.821 [PitchRateCalculator] Loaded
2014-10-27T22:12:24.822Z,1414447944.822 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-10-27T22:12:24.833Z,1414447944.833 [SpeedCalculator] Loaded
2014-10-27T22:12:24.833Z,1414447944.833 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-10-27T22:12:24.850Z,1414447944.850 [TempGradientCalculator] Loaded
2014-10-27T22:12:24.851Z,1414447944.851 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-10-27T22:12:24.856Z,1414447944.856 [YawRateCalculator] Loaded
2014-10-27T22:12:24.856Z,1414447944.856 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-10-27T22:12:24.857Z,1414447944.857 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-10-27T22:12:24.857Z,1414447944.857 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-10-27T22:12:26.041Z,1414447946.041 [HFRCMReconstructedInterpolator] Loaded
2014-10-27T22:12:26.041Z,1414447946.041 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-10-27T22:12:26.042Z,1414447946.042 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-10-27T22:12:26.042Z,1414447946.042 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-10-27T22:12:26.312Z,1414447946.312 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-10-27T22:12:26.313Z,1414447946.313 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-10-27T22:12:26.392Z,1414447946.392 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-10-27T22:12:26.392Z,1414447946.392 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-10-27T22:12:26.438Z,1414447946.438 [DeadReckonUsingSpeedCalculator] Loaded
2014-10-27T22:12:26.439Z,1414447946.439 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2014-10-27T22:12:26.484Z,1414447946.484 [DeadReckonWithRespectToWater] Loaded
2014-10-27T22:12:26.484Z,1414447946.484 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-10-27T22:12:26.531Z,1414447946.531 [DeadReckonWithRespectToSeafloor] Loaded
2014-10-27T22:12:26.531Z,1414447946.531 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-10-27T22:12:26.582Z,1414447946.582 [DeadReckonUsingDVLWaterTrack] Loaded
2014-10-27T22:12:26.582Z,1414447946.582 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-10-27T22:12:26.599Z,1414447946.599 [NavChart] Loaded
2014-10-27T22:12:26.599Z,1414447946.599 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-10-27T22:12:26.603Z,1414447946.603 [UniversalFixResidualReporter] Loaded
2014-10-27T22:12:26.604Z,1414447946.604 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-10-27T22:12:26.604Z,1414447946.604 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-10-27T22:12:26.605Z,1414447946.605 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-10-27T22:12:26.611Z,1414447946.611 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-10-27T22:12:26.612Z,1414447946.612 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-10-27T22:12:31.548Z,1414447951.548 [Aanderaa_O2] Loaded
2014-10-27T22:12:31.549Z,1414447951.549 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-10-27T22:12:31.602Z,1414447951.602 [CTD_NeilBrown] Loaded
2014-10-27T22:12:31.603Z,1414447951.603 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-10-27T22:12:31.604Z,1414447951.604 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405DD4E0
2014-10-27T22:12:31.604Z,1414447951.604 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 9289
2014-10-27T22:12:31.611Z,1414447951.611 [g3ESP] Loaded
2014-10-27T22:12:31.611Z,1414447951.611 [ComponentRegistry](DEBUG): SyncComponent "g3ESP" handled in the control thread.
2014-10-27T22:12:31.629Z,1414447951.629 [rhodamine] Loaded
2014-10-27T22:12:31.629Z,1414447951.629 [ComponentRegistry](DEBUG): SyncComponent "rhodamine" handled in the control thread.
2014-10-27T22:12:31.665Z,1414447951.665 [WetLabsBB2FL] Loaded
2014-10-27T22:12:31.665Z,1414447951.665 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-10-27T22:12:31.667Z,1414447951.667 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4060D4E0
2014-10-27T22:12:31.667Z,1414447951.667 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9290
2014-10-27T22:12:31.667Z,1414447951.667 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-10-27T22:12:31.668Z,1414447951.668 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-10-27T22:12:48.738Z,1414447968.738 [AHRS_sp3003D] Loaded
2014-10-27T22:12:48.738Z,1414447968.738 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-10-27T22:12:48.784Z,1414447968.784 [AcousticModem_Benthos_ATM900] Loaded
2014-10-27T22:12:48.785Z,1414447968.785 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2014-10-27T22:12:48.799Z,1414447968.799 [Depth_Keller] Loaded
2014-10-27T22:12:48.799Z,1414447968.799 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-10-27T22:12:48.804Z,1414447968.804 [DropWeight] Loaded
2014-10-27T22:12:48.804Z,1414447968.804 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-10-27T22:12:48.950Z,1414447968.950 [DVL_micro] Loaded
2014-10-27T22:12:48.950Z,1414447968.950 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-10-27T22:12:49.047Z,1414447969.047 [NAL9602] Loaded
2014-10-27T22:12:49.048Z,1414447969.048 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-10-27T22:12:49.091Z,1414447969.091 [Onboard] Loaded
2014-10-27T22:12:49.091Z,1414447969.091 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-10-27T22:12:51.026Z,1414447971.026 [BPC1] Loaded
2014-10-27T22:12:51.026Z,1414447971.026 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2014-10-27T22:12:51.027Z,1414447971.027 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-10-27T22:12:51.028Z,1414447971.028 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-10-27T22:12:51.157Z,1414447971.157 [BuoyancyServo] Loaded
2014-10-27T22:12:51.157Z,1414447971.157 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-10-27T22:12:51.170Z,1414447971.170 [ElevatorServo] Loaded
2014-10-27T22:12:51.170Z,1414447971.170 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-10-27T22:12:51.182Z,1414447971.182 [MassServo] Loaded
2014-10-27T22:12:51.182Z,1414447971.182 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-10-27T22:12:51.194Z,1414447971.194 [RudderServo] Loaded
2014-10-27T22:12:51.194Z,1414447971.194 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-10-27T22:12:51.206Z,1414447971.206 [ThrusterServo] Loaded
2014-10-27T22:12:51.206Z,1414447971.206 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-10-27T22:12:51.207Z,1414447971.207 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-10-27T22:12:51.208Z,1414447971.208 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-10-27T22:12:51.402Z,1414447971.402 [InternalSim] Loaded
2014-10-27T22:12:51.403Z,1414447971.403 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-10-27T22:12:51.403Z,1414447971.403 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-10-27T22:12:51.404Z,1414447971.404 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-10-27T22:12:51.445Z,1414447971.445 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-10-27T22:12:51.448Z,1414447971.448 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-10-27T22:12:51.449Z,1414447971.449 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-10-27T22:12:51.455Z,1414447971.455 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-10-27T22:12:51.456Z,1414447971.456 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408DA4E0
2014-10-27T22:12:51.457Z,1414447971.457 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9291
2014-10-27T22:12:51.461Z,1414447971.461 [Supervisor](INFO): Main Thread ID is 9202
2014-10-27T22:12:51.461Z,1414447971.461 [Supervisor](DEBUG): Running supervisor.
2014-10-27T22:12:51.462Z,1414447971.462 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9292
2014-10-27T22:12:51.466Z,1414447971.466 [controlThread ThreadHandler](INFO): Handler Thread ID is 9293
2014-10-27T22:12:51.467Z,1414447971.467 [controlThread](DEBUG): Initializing ControlThread
2014-10-27T22:12:51.468Z,1414447971.468 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-10-27T22:12:51.469Z,1414447971.469 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-10-27T22:12:51.470Z,1414447971.470 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-10-27T22:12:51.471Z,1414447971.471 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-10-27T22:12:51.471Z,1414447971.471 [SBIT](INFO): Initialize SBIT Component.
2014-10-27T22:12:51.471Z,1414447971.471 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11797
2014-10-27T22:12:51.472Z,1414447971.472 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-10-27T22:12:51.472Z,1414447971.472 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-10-27T22:12:51.473Z,1414447971.473 [IBIT](INFO): Initialize IBIT Component.
2014-10-27T22:12:51.473Z,1414447971.473 [CBIT](DEBUG): Initialize CBIT Component.
2014-10-27T22:12:51.474Z,1414447971.474 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-10-27T22:12:51.475Z,1414447971.475 [logger ThreadHandler](INFO): Handler Thread ID is 9294
2014-10-27T22:12:51.500Z,1414447971.500 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-10-27T22:12:51.500Z,1414447971.500 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-10-27T22:12:51.501Z,1414447971.501 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-10-27T22:12:51.501Z,1414447971.501 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-10-27T22:12:51.502Z,1414447971.502 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-10-27T22:12:51.503Z,1414447971.503 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-10-27T22:12:51.504Z,1414447971.504 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-10-27T22:12:51.505Z,1414447971.505 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2014-10-27T22:12:51.505Z,1414447971.505 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-10-27T22:12:51.506Z,1414447971.506 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-10-27T22:12:51.506Z,1414447971.506 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-10-27T22:12:51.506Z,1414447971.506 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-10-27T22:12:51.507Z,1414447971.507 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2014-10-27T22:12:51.516Z,1414447971.516 [InternalSim](DEBUG):  InternalSim initializing...
2014-10-27T22:12:51.524Z,1414447971.524 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 9295
2014-10-27T22:12:51.525Z,1414447971.525 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-10-27T22:12:51.532Z,1414447971.532 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-10-27T22:12:51.538Z,1414447971.538 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9297
2014-10-27T22:12:51.540Z,1414447971.540 [WetLabsBB2FL](INFO): Powering down
2014-10-27T22:12:51.580Z,1414447971.580 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9298
2014-10-27T22:12:51.586Z,1414447971.586 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-10-27T22:12:51.587Z,1414447971.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-10-27T22:12:51.587Z,1414447971.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-10-27T22:12:51.587Z,1414447971.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-10-27T22:12:51.587Z,1414447971.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-10-27T22:12:51.588Z,1414447971.588 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-10-27T22:12:51.588Z,1414447971.588 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-10-27T22:12:51.588Z,1414447971.588 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-10-27T22:12:51.589Z,1414447971.589 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-10-27T22:12:51.690Z,1414447971.690 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-10-27T22:12:51.714Z,1414447971.714 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-10-27T22:12:51.739Z,1414447971.739 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2014-10-27T22:12:51.752Z,1414447971.752 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-10-27T22:12:51.856Z,1414447971.856 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-10-27T22:12:51.860Z,1414447971.860 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-10-27T22:12:51.864Z,1414447971.864 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-10-27T22:12:51.929Z,1414447971.929 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-10-27T22:12:51.954Z,1414447971.954 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-10-27T22:12:51.987Z,1414447971.987 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-10-27T22:12:52.001Z,1414447972.001 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

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

    <Aggregate Id="StartClock">

        <Sequence/>

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

    </Aggregate>

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

    <Aggregate Id="CheckIn">

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

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

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

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

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

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="UpdateAndReportMinutesSinceMissionStarted">

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

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

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

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2014-10-27T22:12:52.014Z,1414447972.014 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,Aanderaa_O2,g3ESP,rhodamine,AHRS_sp3003D,AcousticModem_Benthos_ATM900,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-10-27T22:12:52.196Z,1414447972.196 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-10-27T22:12:52.227Z,1414447972.227 [AcousticModem_Benthos_ATM900](INFO): Powering up
2014-10-27T22:12:52.228Z,1414447972.228 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2014-10-27T22:12:52.392Z,1414447972.392 [DVL_micro](INFO): Initializing
2014-10-27T22:12:52.658Z,1414447972.658 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:12:52.670Z,1414447972.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:52.694Z,1414447972.694 [DeadReckonUsingSpeedCalculator](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:52.702Z,1414447972.702 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:52.718Z,1414447972.718 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:52.730Z,1414447972.730 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:52.838Z,1414447972.838 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-10-27T22:12:52.846Z,1414447972.846 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-10-27T22:12:52.868Z,1414447972.868 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-10-27T22:12:52.874Z,1414447972.874 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-10-27T22:12:52.895Z,1414447972.895 [MassServo](DEBUG): Initializing EZServoServo.
2014-10-27T22:12:52.902Z,1414447972.902 [MassServo](DEBUG): Initializing MassServo.
2014-10-27T22:12:52.908Z,1414447972.908 [RudderServo](DEBUG): Initializing EZServoServo.
2014-10-27T22:12:52.914Z,1414447972.914 [RudderServo](DEBUG): Initializing RudderServo.
2014-10-27T22:12:52.920Z,1414447972.920 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-10-27T22:12:52.926Z,1414447972.926 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-10-27T22:12:53.125Z,1414447973.125 [g3ESP](FAULT): LCB fault:  Software Overcurrent.
2014-10-27T22:12:53.126Z,1414447973.126 [g3ESP] Hardware Fault, FailCount= 1
2014-10-27T22:12:53.126Z,1414447973.126 [g3ESP](ERROR): Hardware Fault
2014-10-27T22:12:53.270Z,1414447973.270 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:12:53.271Z,1414447973.271 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:53.272Z,1414447973.272 [DeadReckonUsingSpeedCalculator](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:53.273Z,1414447973.273 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:53.274Z,1414447973.274 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:53.276Z,1414447973.276 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:53.303Z,1414447973.303 [CBIT](ERROR): Hardware Fault in component: g3ESP
2014-10-27T22:12:53.567Z,1414447973.567 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:12:53.574Z,1414447973.574 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:53.579Z,1414447973.579 [DeadReckonUsingSpeedCalculator](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:53.580Z,1414447973.580 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:53.581Z,1414447973.581 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:53.582Z,1414447973.582 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-10-27T22:12:53.889Z,1414447973.889 [CBIT](INFO): Clearing failed state for component g3ESP
2014-10-27T22:12:53.889Z,1414447973.889 [g3ESP] No Fault, FailCount= 1
2014-10-27T22:12:53.912Z,1414447973.912 [g3ESP](INFO): Powering up
2014-10-27T22:12:54.230Z,1414447974.230 [NAL9602](INFO): Powering up NAL9602
2014-10-27T22:12:54.488Z,1414447974.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:12:54.000Z,1414447975.000 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:12:55.658Z,1414447975.658 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:12:55.963Z,1414447975.963 [Aanderaa_O2](INFO): Powering down
2014-10-27T22:12:56.605Z,1414447976.605 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:12:57.038Z,1414447977.038 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:12:57.688Z,1414447977.688 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:12:58.650Z,1414447978.650 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:12:59.086Z,1414447979.086 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:12:59.768Z,1414447979.768 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:00.502Z,1414447980.502 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:00.929Z,1414447980.929 [AcousticModem_Benthos_ATM900](DEBUG): 
2014-10-27T22:13:01.128Z,1414447981.128 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:01.555Z,1414447981.555 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:01.612Z,1414447981.612 [CBIT](CRITICAL): Environmental Failure. Press:14.451184 PSI. Humidity:55%. Temp:20 C. ABORTING MISSION
2014-10-27T22:13:01.959Z,1414447981.959 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series
2014-10-27T22:13:01.959Z,1414447981.959 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2014-10-27T22:13:01.960Z,1414447981.960 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.8.9
2014-10-27T22:13:01.999Z,1414447981.999 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:02.063Z,1414447982.063 [CommandLine](FAULT): Scheduling is paused
2014-10-27T22:13:02.427Z,1414447982.427 [AcousticModem_Benthos_ATM900](DEBUG): Oct 27 2014 22:11:08 
2014-10-27T22:13:02.492Z,1414447982.492 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:02.965Z,1414447982.965 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:03.364Z,1414447983.364 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2014-10-27T22:13:03.415Z,1414447983.415 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:03.821Z,1414447983.821 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2014-10-27T22:13:03.822Z,1414447983.822 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2014-10-27T22:13:03.822Z,1414447983.822 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2014-10-27T22:13:03.919Z,1414447983.919 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:04.334Z,1414447984.334 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2014-10-27T22:13:04.429Z,1414447984.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:04.843Z,1414447984.843 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2014-10-27T22:13:04.844Z,1414447984.844 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2014-10-27T22:13:04.884Z,1414447984.884 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:05.292Z,1414447985.292 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2014-10-27T22:13:05.300Z,1414447985.300 [NAL9602](INFO): NAL9602 initialized
2014-10-27T22:13:05.337Z,1414447985.337 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:05.871Z,1414447985.871 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:06.378Z,1414447986.378 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:06.905Z,1414447986.905 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:07.614Z,1414447987.614 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:07.667Z,1414447987.667 [SBIT](IMPORTANT): Beginning Startup BIT
2014-10-27T22:13:07.669Z,1414447987.669 [CBIT](IMPORTANT): Beginning GF scan
2014-10-27T22:13:08.313Z,1414447988.313 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:08.966Z,1414447988.966 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:09.484Z,1414447989.484 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:09.943Z,1414447989.943 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:10.484Z,1414447990.484 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:11.021Z,1414447991.021 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:11.500Z,1414447991.500 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:12.095Z,1414447992.095 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:12.559Z,1414447992.559 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:13.140Z,1414447993.140 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:13.649Z,1414447993.649 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:14.242Z,1414447994.242 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:14.767Z,1414447994.767 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:15.307Z,1414447995.307 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:15.839Z,1414447995.839 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:16.396Z,1414447996.396 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:16.891Z,1414447996.891 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:17.455Z,1414447997.455 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:17.956Z,1414447997.956 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:18.495Z,1414447998.495 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:18.622Z,1414447998.622 [CBIT](CRITICAL): Environmental Failure. Press:14.451184 PSI. Humidity:57%. Temp:21 C. ABORTING MISSION
2014-10-27T22:13:19.024Z,1414447999.024 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:19.560Z,1414447999.560 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:20.063Z,1414448000.063 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:20.616Z,1414448000.616 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:20.760Z,1414448000.760 [SBIT](FAULT): Mass: EXPECTED:0.006000 ACTUAL:0.005785
2014-10-27T22:13:20.760Z,1414448000.760 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.670113
2014-10-27T22:13:21.185Z,1414448001.185 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:21.798Z,1414448001.798 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:22.319Z,1414448002.319 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:22.802Z,1414448002.802 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:23.265Z,1414448003.265 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:23.843Z,1414448003.843 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:24.334Z,1414448004.334 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:24.831Z,1414448004.831 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:25.327Z,1414448005.327 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:25.802Z,1414448005.802 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:26.259Z,1414448006.259 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:26.723Z,1414448006.723 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:27.246Z,1414448007.246 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:27.762Z,1414448007.762 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:28.280Z,1414448008.280 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:28.936Z,1414448008.936 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:29.423Z,1414448009.423 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:29.944Z,1414448009.944 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:30.544Z,1414448010.544 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:31.028Z,1414448011.028 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:31.585Z,1414448011.585 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:32.133Z,1414448012.133 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:32.674Z,1414448012.674 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:33.210Z,1414448013.210 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:33.784Z,1414448013.784 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:34.438Z,1414448014.438 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:34.556Z,1414448014.556 [CBIT](IMPORTANT): No ground fault detected
2014-10-27T22:13:34.942Z,1414448014.942 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:35.419Z,1414448015.419 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:35.529Z,1414448015.529 [CBIT](CRITICAL): Environmental Failure. Press:14.982194 PSI. Humidity:55%. Temp:21 C. ABORTING MISSION
2014-10-27T22:13:35.975Z,1414448015.975 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:36.460Z,1414448016.460 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:36.974Z,1414448016.974 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:37.489Z,1414448017.489 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:37.988Z,1414448017.988 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:38.488Z,1414448018.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:38.960Z,1414448018.960 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:39.437Z,1414448019.437 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:39.906Z,1414448019.906 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:40.375Z,1414448020.375 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:40.895Z,1414448020.895 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:41.364Z,1414448021.364 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:41.849Z,1414448021.849 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:42.353Z,1414448022.353 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:42.854Z,1414448022.854 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:43.331Z,1414448023.331 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:43.788Z,1414448023.788 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:44.305Z,1414448024.305 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:44.766Z,1414448024.766 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:45.250Z,1414448025.250 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:45.762Z,1414448025.762 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:46.256Z,1414448026.256 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:46.737Z,1414448026.737 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:47.217Z,1414448027.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:47.742Z,1414448027.742 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:48.211Z,1414448028.211 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:48.696Z,1414448028.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:49.164Z,1414448029.164 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:49.661Z,1414448029.661 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:50.102Z,1414448030.102 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:50.675Z,1414448030.675 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:51.625Z,1414448031.625 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:52.170Z,1414448032.170 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:52.758Z,1414448032.758 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:53.261Z,1414448033.261 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:53.780Z,1414448033.780 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:54.002Z,1414448034.002 [g3ESP](FAULT): g3ESP initialization failed.
2014-10-27T22:13:54.002Z,1414448034.002 [g3ESP] Communications Fault, FailCount= 2
2014-10-27T22:13:54.002Z,1414448034.002 [g3ESP](ERROR): Communications Fault
2014-10-27T22:13:54.358Z,1414448034.358 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:54.434Z,1414448034.434 [CBIT](ERROR): Communications Fault in component: g3ESP
2014-10-27T22:13:55.017Z,1414448035.017 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:55.478Z,1414448035.478 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:56.035Z,1414448036.035 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:56.079Z,1414448036.079 [CBIT](INFO): Clearing failed state for component g3ESP
2014-10-27T22:13:56.079Z,1414448036.079 [g3ESP] No Fault, FailCount= 2
2014-10-27T22:13:56.132Z,1414448036.132 [g3ESP](INFO): Powering up
2014-10-27T22:13:56.488Z,1414448036.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:56.921Z,1414448036.921 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:57.362Z,1414448037.362 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:57.794Z,1414448037.794 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:58.245Z,1414448038.245 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:58.682Z,1414448038.682 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:59.079Z,1414448039.079 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:59.478Z,1414448039.478 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:13:59.881Z,1414448039.881 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:00.294Z,1414448040.294 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:00.702Z,1414448040.702 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:01.101Z,1414448041.101 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:01.560Z,1414448041.560 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:01.949Z,1414448041.949 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:01.961Z,1414448041.961 [SBIT](CRITICAL): SBIT FAILED
2014-10-27T22:14:02.355Z,1414448042.355 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:02.360Z,1414448042.360 [MissionManager](IMPORTANT): Started mission Startup
2014-10-27T22:14:02.360Z,1414448042.360 [Startup] Running Loop=1
2014-10-27T22:14:02.361Z,1414448042.361 [Startup](INFO): Aggregate::initialize Startup
2014-10-27T22:14:02.361Z,1414448042.361 [Startup:A.GoToSurface] Running Loop=1
2014-10-27T22:14:02.361Z,1414448042.361 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-10-27T22:14:02.361Z,1414448042.361 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-10-27T22:14:02.362Z,1414448042.362 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-10-27T22:14:02.362Z,1414448042.362 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-10-27T22:14:02.368Z,1414448042.368 [Startup:StartupSatComms] Running Loop=1
2014-10-27T22:14:02.369Z,1414448042.369 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-10-27T22:14:02.369Z,1414448042.369 [Startup:StartupSatComms:A] Running Loop=1
2014-10-27T22:14:02.810Z,1414448042.810 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:02.816Z,1414448042.816 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-10-27T22:14:03.226Z,1414448043.226 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:03.627Z,1414448043.627 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:04.054Z,1414448044.054 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:04.831Z,1414448044.831 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:05.271Z,1414448045.271 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:05.715Z,1414448045.715 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:06.162Z,1414448046.162 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:06.588Z,1414448046.588 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:07.024Z,1414448047.024 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:07.478Z,1414448047.478 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:07.925Z,1414448047.925 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:08.379Z,1414448048.379 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:08.837Z,1414448048.837 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:09.271Z,1414448049.271 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:09.716Z,1414448049.716 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:10.118Z,1414448050.118 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:10.523Z,1414448050.523 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:10.950Z,1414448050.950 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:11.384Z,1414448051.384 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:11.808Z,1414448051.808 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:12.256Z,1414448052.256 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:12.691Z,1414448052.691 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:13.120Z,1414448053.120 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:13.531Z,1414448053.531 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:13.958Z,1414448053.958 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:14.400Z,1414448054.400 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:14.836Z,1414448054.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:15.280Z,1414448055.280 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:15.714Z,1414448055.714 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:16.144Z,1414448056.144 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:16.552Z,1414448056.552 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:17.012Z,1414448057.012 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:17.440Z,1414448057.440 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:17.873Z,1414448057.873 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:18.318Z,1414448058.318 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:18.767Z,1414448058.767 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:19.202Z,1414448059.202 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:19.638Z,1414448059.638 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:20.061Z,1414448060.061 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:20.470Z,1414448060.470 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:20.962Z,1414448060.962 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:21.398Z,1414448061.398 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:21.912Z,1414448061.912 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:22.298Z,1414448062.298 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:22.733Z,1414448062.733 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:23.180Z,1414448063.180 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:23.559Z,1414448063.559 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:23.975Z,1414448063.975 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:24.378Z,1414448064.378 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:24.780Z,1414448064.780 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:25.158Z,1414448065.158 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:25.562Z,1414448065.562 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:25.960Z,1414448065.960 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:26.376Z,1414448066.376 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:26.803Z,1414448066.803 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:27.216Z,1414448067.216 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:27.625Z,1414448067.625 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:28.006Z,1414448068.006 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:28.415Z,1414448068.415 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:28.811Z,1414448068.811 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:29.211Z,1414448069.211 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:29.634Z,1414448069.634 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:30.061Z,1414448070.061 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:30.436Z,1414448070.436 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:30.833Z,1414448070.833 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:31.223Z,1414448071.223 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:31.624Z,1414448071.624 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:32.025Z,1414448072.025 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:32.441Z,1414448072.441 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:32.892Z,1414448072.892 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:33.322Z,1414448073.322 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:33.727Z,1414448073.727 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:34.117Z,1414448074.117 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:34.517Z,1414448074.517 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:34.976Z,1414448074.976 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:35.356Z,1414448075.356 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:35.805Z,1414448075.805 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:36.237Z,1414448076.237 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:36.660Z,1414448076.660 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:36.674Z,1414448076.674 [CBIT](CRITICAL): Environmental Failure. Press:14.451184 PSI. Humidity:57%. Temp:21 C. ABORTING MISSION
2014-10-27T22:14:37.094Z,1414448077.094 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:37.465Z,1414448077.465 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:37.862Z,1414448077.862 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:38.258Z,1414448078.258 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:38.697Z,1414448078.697 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:39.073Z,1414448079.073 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:39.534Z,1414448079.534 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:39.923Z,1414448079.923 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:40.317Z,1414448080.317 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:40.733Z,1414448080.733 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:41.137Z,1414448081.137 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:41.532Z,1414448081.532 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:41.916Z,1414448081.916 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:42.316Z,1414448082.316 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:42.734Z,1414448082.734 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:43.159Z,1414448083.159 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:43.559Z,1414448083.559 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:43.981Z,1414448083.981 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:44.390Z,1414448084.390 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:44.783Z,1414448084.783 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:45.177Z,1414448085.177 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:45.570Z,1414448085.570 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:45.988Z,1414448085.988 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:46.408Z,1414448086.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:46.826Z,1414448086.826 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:47.235Z,1414448087.235 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:47.643Z,1414448087.643 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:48.014Z,1414448088.014 [BPC1](ERROR): B bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,61%20
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,0A,FFFC%36
$B12,0A,0000%30
$B13,0A,FFFA%36
$B14,0A,FFFB%32
$B15,0A,0000%37
$B16,0A,0000%34
$B17,0A,0000%35
$B18,0A,0000%3A
$S,01,270F,02,00,04,61%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,0B8F,09,3F8E,0A,FFFC,0B,FFFC%43
$B11,0C,0001,0D,0062,0E,005A,0F,15C9,10,1767,11,FFFF,12,FFFF%4C
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%42
$B11,1A,0031,1B,4357,1C,00A7%42
$B12,02,000A,01,026C,03,0001,08,0B8E,09,3DF0,0A,0000,0B,0000%4A
$B12,0C,0001,0D,0064,0E,005C,0F,162C,10,170F,11,FFFF,12,FFFF%34
$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,0B8F,09,3F91,0A,FFFA,0B,FFFA%34
$B13,0C,0001,0D,005D,0E,005B,0F,15E1,10,179A,11,DACA,12,DACA%4F
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%40
$B13,1A,0031,1B,4357,1C,0089%37
$B14,02,000A,01,026C,03,0001,08,0B93,09,3F93,0A,FFFB,0B,FFFC%44
$B14,0C,0001,0D,005E,0E,0058,0F,1550,10,16E8,11,FFC0,12,FFFF%35
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%47
$B14,1A,0031,1B,4357,1C,00A6%46
$B15,02,000A,01,026C,03,0001,08,0B92,09,3DE5,0A,0000,0B,0000%3D
$B15,0C,0001,0D,0060,0E,005C,0F,162A,10,171F,11,FFFF,12,FFFF%34
$B15,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%48
$B15,1A,0031,1B,4357,1C,00A9%48
$B16,02,000A,01,026C,03,0001,08,0B89,09,3DE5,0A,0000,0B,0000%34
$B16,0C,0001,0D,0063,0E,0059,0F,1586,10,1689,11,FFFF,12,FFFF%47
$B16,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4B
$B16,1A,0031,1B,4357,1C,00A8%4A
$B17,02,000A,01,026C,03,0001,08,0B87,09,3DE2,0A,0000,0B,0000%3C
$B17,0C,0001,0D,0064,0E,005A,0F,15C1,10,16E0,11,FFFF,12,FFFF%31
$B17,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4A
$B17,1A,0031,1B,4357,1C,0096%3D
$B18,02,000A,01,026C,03,0001,08,0B8B,09,3FE1,0A,0000,0B,0000%47
$B18,0C,0001,0D,0063,0E,0061,0F,1773,10,17BF,11,FFFF,12,FFFF%4E
$B18,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%45
$B18,1A,0031,1B,4357,1C,009B%46

2014-10-27T22:14:48.014Z,1414448088.014 [BPC1](FAULT): Failed to parse bank B battery data
2014-10-27T22:14:48.014Z,1414448088.014 [BPC1] Data Fault, FailCount= 1
2014-10-27T22:14:48.014Z,1414448088.014 [BPC1](ERROR): Data Fault
2014-10-27T22:14:48.020Z,1414448088.020 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:48.035Z,1414448088.035 [CBIT](ERROR): Data Fault in component: BPC1
2014-10-27T22:14:48.468Z,1414448088.468 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:48.837Z,1414448088.837 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:49.249Z,1414448089.249 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:49.284Z,1414448089.284 [CBIT](INFO): Clearing failed state for component BPC1
2014-10-27T22:14:49.284Z,1414448089.284 [BPC1] No Fault, FailCount= 1
2014-10-27T22:14:49.711Z,1414448089.711 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:50.133Z,1414448090.133 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:50.536Z,1414448090.536 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:50.948Z,1414448090.948 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:51.339Z,1414448091.339 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:51.735Z,1414448091.735 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:52.132Z,1414448092.132 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:52.556Z,1414448092.556 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:52.986Z,1414448092.986 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:53.387Z,1414448093.387 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:53.766Z,1414448093.766 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:54.167Z,1414448094.167 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:54.585Z,1414448094.585 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:54.987Z,1414448094.987 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:55.014Z,1414448095.014 [CommandLine](IMPORTANT): got command show stack
2014-10-27T22:14:55.014Z,1414448095.014 [CommandLine](IMPORTANT): Behavior Stack:

2014-10-27T22:14:55.014Z,1414448095.014 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2014-10-27T22:14:55.014Z,1414448095.014 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:A
2014-10-27T22:14:55.445Z,1414448095.445 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:55.871Z,1414448095.871 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:56.299Z,1414448096.299 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:56.344Z,1414448096.344 [g3ESP](FAULT): g3ESP initialization failed.
2014-10-27T22:14:56.344Z,1414448096.344 [g3ESP] Communications Fault, FailCount= 3
2014-10-27T22:14:56.344Z,1414448096.344 [g3ESP](ERROR): Communications Fault
2014-10-27T22:14:56.709Z,1414448096.709 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:56.725Z,1414448096.725 [CBIT](ERROR): Communications Fault in component: g3ESP
2014-10-27T22:14:56.725Z,1414448096.725 [CBIT](FAULT): Communications Fault in component: g3ESP
2014-10-27T22:14:57.232Z,1414448097.232 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:57.620Z,1414448097.620 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:58.017Z,1414448098.017 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:58.414Z,1414448098.414 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:58.881Z,1414448098.881 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:59.293Z,1414448099.293 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:14:59.693Z,1414448099.693 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:00.065Z,1414448100.065 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:00.471Z,1414448100.471 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:00.876Z,1414448100.876 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:01.274Z,1414448101.274 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:01.693Z,1414448101.693 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:02.148Z,1414448102.148 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:02.583Z,1414448102.583 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:02.594Z,1414448102.594 [Startup:StartupSatComms:A](INFO): Timed out from 2014-10-27T22:14:02.4Z
2014-10-27T22:15:02.594Z,1414448102.594 [Startup:StartupSatComms:A] Stopped
2014-10-27T22:15:02.594Z,1414448102.594 [Startup:StartupSatComms:B] Running Loop=1
2014-10-27T22:15:02.984Z,1414448102.984 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:02.990Z,1414448102.990 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-10-27T22:15:03.641Z,1414448103.641 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:04.031Z,1414448104.031 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:04.482Z,1414448104.482 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:05.393Z,1414448105.393 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:05.952Z,1414448105.952 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:06.340Z,1414448106.340 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:06.768Z,1414448106.768 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:07.165Z,1414448107.165 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:07.574Z,1414448107.574 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:07.988Z,1414448107.988 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:08.380Z,1414448108.380 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:08.823Z,1414448108.823 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:09.240Z,1414448109.240 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:09.648Z,1414448109.648 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:10.535Z,1414448110.535 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:11.072Z,1414448111.072 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:11.442Z,1414448111.442 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:11.853Z,1414448111.853 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:12.298Z,1414448112.298 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:12.706Z,1414448112.706 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:13.081Z,1414448113.081 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:13.485Z,1414448113.485 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:13.892Z,1414448113.892 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:14.328Z,1414448114.328 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:15.250Z,1414448115.250 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:15.935Z,1414448115.935 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:16.353Z,1414448116.353 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:16.800Z,1414448116.800 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:16.836Z,1414448116.836 [CBIT](CRITICAL): Environmental Failure. Press:14.982194 PSI. Humidity:55%. Temp:21 C. ABORTING MISSION
2014-10-27T22:15:17.242Z,1414448117.242 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:17.725Z,1414448117.725 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:18.122Z,1414448118.122 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:18.361Z,1414448118.361 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2014-10-27T22:15:18.522Z,1414448118.522 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:18.527Z,1414448118.527 [Startup:StartupSatComms:B] Stopped
2014-10-27T22:15:18.528Z,1414448118.528 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2014-10-27T22:15:18.528Z,1414448118.528 [Startup:StartupSatComms] Stopped
2014-10-27T22:15:18.528Z,1414448118.528 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-10-27T22:15:18.529Z,1414448118.529 [Startup](INFO): Completed Startup
2014-10-27T22:15:18.529Z,1414448118.529 [Startup] Stopped
2014-10-27T22:15:18.529Z,1414448118.529 [Startup](INFO): Aggregate::uninitialize Startup
2014-10-27T22:15:18.529Z,1414448118.529 [Startup:A.GoToSurface] Stopped
2014-10-27T22:15:18.529Z,1414448118.529 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-10-27T22:15:19.037Z,1414448119.037 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:19.044Z,1414448119.044 [MissionManager](IMPORTANT): Started mission Default
2014-10-27T22:15:19.044Z,1414448119.044 [Default] Running Loop=1
2014-10-27T22:15:19.044Z,1414448119.044 [Default](INFO): Aggregate::initialize Default
2014-10-27T22:15:19.044Z,1414448119.044 [Default:B.GoToSurface] Running Loop=1
2014-10-27T22:15:19.044Z,1414448119.044 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-10-27T22:15:19.044Z,1414448119.044 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-10-27T22:15:19.045Z,1414448119.045 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-10-27T22:15:19.045Z,1414448119.045 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-10-27T22:15:19.045Z,1414448119.045 [Default:StartClock] Running Loop=1
2014-10-27T22:15:19.046Z,1414448119.046 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock
2014-10-27T22:15:19.046Z,1414448119.046 [Default:StartClock:A] Running Loop=1
2014-10-27T22:15:19.054Z,1414448119.054 [Default:StartClock:A] Stopped
2014-10-27T22:15:19.054Z,1414448119.054 [Default:StartClock](INFO): Completed Default:StartClock
2014-10-27T22:15:19.054Z,1414448119.054 [Default:StartClock] Stopped
2014-10-27T22:15:19.054Z,1414448119.054 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock
2014-10-27T22:15:19.460Z,1414448119.460 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:19.466Z,1414448119.466 [Default:WaitAtTheSurface] Running Loop=1
2014-10-27T22:15:19.466Z,1414448119.466 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2014-10-27T22:15:19.466Z,1414448119.466 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2014-10-27T22:15:19.466Z,1414448119.466 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2014-10-27T22:15:19.901Z,1414448119.901 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:19.907Z,1414448119.907 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2014-10-27T22:15:24.966Z,1414448124.966 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:29.913Z,1414448129.913 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:29.929Z,1414448129.929 [CBIT](CRITICAL): Environmental Failure. Press:13.920176 PSI. Humidity:56%. Temp:20 C. ABORTING MISSION
2014-10-27T22:15:34.924Z,1414448134.924 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:39.965Z,1414448139.965 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:44.958Z,1414448144.958 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:49.933Z,1414448149.933 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:54.940Z,1414448154.940 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:15:59.921Z,1414448159.921 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:04.886Z,1414448164.886 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-10-27T22:16:04.886Z,1414448164.886 [DVL_micro] Data Fault, FailCount= 1
2014-10-27T22:16:04.886Z,1414448164.886 [DVL_micro](ERROR): Data Fault
2014-10-27T22:16:04.942Z,1414448164.942 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:04.978Z,1414448164.978 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-10-27T22:16:09.882Z,1414448169.882 [AcousticModem_Benthos_ATM900](DEBUG): sending wake-up to local modem
2014-10-27T22:16:09.957Z,1414448169.957 [DVL_micro](INFO): uninitialize:Powering down
2014-10-27T22:16:09.987Z,1414448169.987 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:10.223Z,1414448170.223 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/sampleG3ESP.xml
2014-10-27T22:16:10.224Z,1414448170.224 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sampleG3ESP.xml
2014-10-27T22:16:10.241Z,1414448170.241 [MissionManager](INFO): DefineArg sampleG3ESP.MassDefault = 0.012000 n/a
2014-10-27T22:16:10.243Z,1414448170.243 [sampleG3ESP:A.Pitch](DEBUG): Construct.
2014-10-27T22:16:10.270Z,1414448170.270 [sampleG3ESP:TestDrive:C.Wait](DEBUG): Construct Wait.
2014-10-27T22:16:10.273Z,1414448170.273 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Science="Tethys/Science"
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Science http://aosn.mbari.org/tethys/Xml/Science.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="sampleG3ESP">

    <DefineArg Name="MassDefault"><Control:VerticalControl.massDefault/></DefineArg>

    <Timeout Duration="P90M"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Arg Name="MassDefault"/></Setting>
    </Guidance:Pitch>

    <Aggregate Id="TestDrive">

        <Sequence/>

        <ReadData Strategy="AllDevices">
            <Science:g3ESP.samplingActive/>
        </ReadData>

        <Aggregate Id="Data">

            <When>
                <True/>
            </When>

            <SendData Service="Express">
                <Science:g3ESP.samplingActive/><Units:bool/>
            </SendData>

        </Aggregate>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:hour/><Value>1</Value></Setting>
        </Guidance:Wait>

    </Aggregate>

</Mission>


2014-10-27T22:16:10.275Z,1414448170.275 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/sampleG3ESP.xml
2014-10-27T22:16:14.882Z,1414448174.882 [AcousticModem_Benthos_ATM900](DEBUG): confirming wake-up of local modem
2014-10-27T22:16:14.931Z,1414448174.931 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:14.937Z,1414448174.937 [Default] Stopped
2014-10-27T22:16:14.937Z,1414448174.937 [Default](INFO): Aggregate::uninitialize Default
2014-10-27T22:16:14.937Z,1414448174.937 [Default:B.GoToSurface] Stopped
2014-10-27T22:16:14.938Z,1414448174.938 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-10-27T22:16:14.938Z,1414448174.938 [Default:WaitAtTheSurface] Stopped
2014-10-27T22:16:14.938Z,1414448174.938 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface
2014-10-27T22:16:14.938Z,1414448174.938 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Stopped
2014-10-27T22:16:14.938Z,1414448174.938 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Uninitialize.
2014-10-27T22:16:14.938Z,1414448174.938 [MissionManager](IMPORTANT): Started mission sampleG3ESP
2014-10-27T22:16:14.938Z,1414448174.938 [sampleG3ESP] Running Loop=1
2014-10-27T22:16:14.938Z,1414448174.938 [sampleG3ESP](INFO): Aggregate::initialize sampleG3ESP
2014-10-27T22:16:14.938Z,1414448174.938 [sampleG3ESP:A.Pitch] Running Loop=1
2014-10-27T22:16:14.938Z,1414448174.938 [sampleG3ESP:A.Pitch](DEBUG): Initialize.
2014-10-27T22:16:14.939Z,1414448174.939 [sampleG3ESP:TestDrive] Running Loop=1
2014-10-27T22:16:14.939Z,1414448174.939 [sampleG3ESP:TestDrive](INFO): Aggregate::initialize sampleG3ESP:TestDrive
2014-10-27T22:16:14.939Z,1414448174.939 [sampleG3ESP:TestDrive:A] Running Loop=1
2014-10-27T22:16:14.939Z,1414448174.939 [sampleG3ESP:TestDrive:C.Wait] Running Loop=1
2014-10-27T22:16:14.939Z,1414448174.939 [sampleG3ESP:TestDrive:C.Wait](DEBUG): Initialize Wait Component.
2014-10-27T22:16:14.945Z,1414448174.945 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:14.945Z,1414448174.945 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:14.945Z,1414448174.945 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:14.948Z,1414448174.948 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:14.948Z,1414448174.948 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:14.949Z,1414448174.949 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:14.949Z,1414448174.949 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:14.949Z,1414448174.949 [sampleG3ESP:TestDrive:A] Running Loop=1
2014-10-27T22:16:14.950Z,1414448174.950 [sampleG3ESP:TestDrive:A](DEBUG): Initialize ReadDataComponent to sense g3ESP.samplingActive
2014-10-27T22:16:14.951Z,1414448174.951 [sampleG3ESP:A.Pitch] Running Loop=1
2014-10-27T22:16:15.455Z,1414448175.455 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:15.461Z,1414448175.461 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:15.461Z,1414448175.461 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:15.461Z,1414448175.461 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:15.462Z,1414448175.462 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:15.462Z,1414448175.462 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:15.462Z,1414448175.462 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:15.462Z,1414448175.462 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:15.471Z,1414448175.471 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-10-27T22:16:15.471Z,1414448175.471 [DVL_micro] No Fault, FailCount= 1
2014-10-27T22:16:15.894Z,1414448175.894 [DVL_micro](INFO): Initializing
2014-10-27T22:16:15.932Z,1414448175.932 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:15.958Z,1414448175.958 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:15.958Z,1414448175.958 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:15.959Z,1414448175.959 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:15.959Z,1414448175.959 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:15.959Z,1414448175.959 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:15.959Z,1414448175.959 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:15.959Z,1414448175.959 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:16.429Z,1414448176.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:16.435Z,1414448176.435 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:16.435Z,1414448176.435 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:16.435Z,1414448176.435 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:16.436Z,1414448176.436 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:16.436Z,1414448176.436 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:16.436Z,1414448176.436 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:16.436Z,1414448176.436 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:16.906Z,1414448176.906 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:16.917Z,1414448176.917 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:16.917Z,1414448176.917 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:16.917Z,1414448176.917 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:16.917Z,1414448176.917 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:16.918Z,1414448176.918 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:16.918Z,1414448176.918 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:16.918Z,1414448176.918 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:17.342Z,1414448177.342 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:17.348Z,1414448177.348 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:17.349Z,1414448177.349 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:17.349Z,1414448177.349 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:17.349Z,1414448177.349 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:17.349Z,1414448177.349 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:17.349Z,1414448177.349 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:17.349Z,1414448177.349 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:17.812Z,1414448177.812 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:17.818Z,1414448177.818 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:17.818Z,1414448177.818 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:17.818Z,1414448177.818 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:17.819Z,1414448177.819 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:17.819Z,1414448177.819 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:17.819Z,1414448177.819 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:17.819Z,1414448177.819 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:18.226Z,1414448178.226 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:18.232Z,1414448178.232 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:18.232Z,1414448178.232 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:18.232Z,1414448178.232 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:18.232Z,1414448178.232 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:18.232Z,1414448178.232 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:18.233Z,1414448178.233 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:18.233Z,1414448178.233 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:18.667Z,1414448178.667 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:18.673Z,1414448178.673 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:18.673Z,1414448178.673 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:18.673Z,1414448178.673 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:18.674Z,1414448178.674 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:18.674Z,1414448178.674 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:18.674Z,1414448178.674 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:18.674Z,1414448178.674 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:19.095Z,1414448179.095 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:19.101Z,1414448179.101 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:19.101Z,1414448179.101 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:19.101Z,1414448179.101 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:19.102Z,1414448179.102 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:19.102Z,1414448179.102 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:19.102Z,1414448179.102 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:19.102Z,1414448179.102 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:19.526Z,1414448179.526 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:19.533Z,1414448179.533 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:19.533Z,1414448179.533 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:19.533Z,1414448179.533 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:19.534Z,1414448179.534 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:19.534Z,1414448179.534 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:19.534Z,1414448179.534 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:19.534Z,1414448179.534 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:19.955Z,1414448179.955 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:19.961Z,1414448179.961 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:19.961Z,1414448179.961 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:19.961Z,1414448179.961 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:19.962Z,1414448179.962 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:19.962Z,1414448179.962 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:19.962Z,1414448179.962 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:19.962Z,1414448179.962 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:20.398Z,1414448180.398 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:20.404Z,1414448180.404 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:20.404Z,1414448180.404 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:20.404Z,1414448180.404 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:20.405Z,1414448180.405 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:20.405Z,1414448180.405 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:20.405Z,1414448180.405 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:20.405Z,1414448180.405 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:20.832Z,1414448180.832 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:20.861Z,1414448180.861 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:20.861Z,1414448180.861 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:20.861Z,1414448180.861 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:20.862Z,1414448180.862 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:20.862Z,1414448180.862 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:20.862Z,1414448180.862 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:20.862Z,1414448180.862 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:21.265Z,1414448181.265 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:21.271Z,1414448181.271 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:21.271Z,1414448181.271 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:21.271Z,1414448181.271 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:21.272Z,1414448181.272 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:21.272Z,1414448181.272 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:21.272Z,1414448181.272 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:21.272Z,1414448181.272 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:21.628Z,1414448181.628 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:21.633Z,1414448181.633 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:21.634Z,1414448181.634 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:21.634Z,1414448181.634 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:21.634Z,1414448181.634 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:21.635Z,1414448181.635 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:21.635Z,1414448181.635 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:21.635Z,1414448181.635 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:22.087Z,1414448182.087 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:22.097Z,1414448182.097 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:22.098Z,1414448182.098 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:22.098Z,1414448182.098 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:22.098Z,1414448182.098 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:22.098Z,1414448182.098 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:22.098Z,1414448182.098 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:22.099Z,1414448182.099 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:22.485Z,1414448182.485 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:22.491Z,1414448182.491 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:22.491Z,1414448182.491 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:22.491Z,1414448182.491 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:22.492Z,1414448182.492 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:22.492Z,1414448182.492 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:22.492Z,1414448182.492 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:22.492Z,1414448182.492 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:22.911Z,1414448182.911 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:22.917Z,1414448182.917 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:22.917Z,1414448182.917 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:22.917Z,1414448182.917 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:22.918Z,1414448182.918 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:22.918Z,1414448182.918 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:22.918Z,1414448182.918 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:22.918Z,1414448182.918 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:23.365Z,1414448183.365 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:23.371Z,1414448183.371 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:23.371Z,1414448183.371 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:23.371Z,1414448183.371 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:23.372Z,1414448183.372 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:23.372Z,1414448183.372 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:23.372Z,1414448183.372 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:23.372Z,1414448183.372 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:23.809Z,1414448183.809 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:23.816Z,1414448183.816 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:23.816Z,1414448183.816 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:23.816Z,1414448183.816 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:23.817Z,1414448183.817 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:23.817Z,1414448183.817 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:23.817Z,1414448183.817 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:23.817Z,1414448183.817 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:24.220Z,1414448184.220 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:24.225Z,1414448184.225 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:24.226Z,1414448184.226 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:24.226Z,1414448184.226 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:24.226Z,1414448184.226 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:24.226Z,1414448184.226 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:24.227Z,1414448184.227 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:24.227Z,1414448184.227 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:24.644Z,1414448184.644 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:24.650Z,1414448184.650 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:24.650Z,1414448184.650 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:24.650Z,1414448184.650 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:24.651Z,1414448184.651 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:24.651Z,1414448184.651 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:24.651Z,1414448184.651 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:24.651Z,1414448184.651 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:25.054Z,1414448185.054 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:25.061Z,1414448185.061 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:25.061Z,1414448185.061 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:25.061Z,1414448185.061 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:25.062Z,1414448185.062 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:25.062Z,1414448185.062 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:25.062Z,1414448185.062 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:25.062Z,1414448185.062 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:25.455Z,1414448185.455 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:25.461Z,1414448185.461 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:25.461Z,1414448185.461 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:25.462Z,1414448185.462 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:25.462Z,1414448185.462 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:25.462Z,1414448185.462 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:25.462Z,1414448185.462 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:25.462Z,1414448185.462 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:25.471Z,1414448185.471 [CBIT](CRITICAL): Environmental Failure. Press:14.451184 PSI. Humidity:56%. Temp:21 C. ABORTING MISSION
2014-10-27T22:16:25.904Z,1414448185.904 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:25.910Z,1414448185.910 [sampleG3ESP:TestDrive:Data] Running Loop=1
2014-10-27T22:16:25.911Z,1414448185.911 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::initialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:25.911Z,1414448185.911 [sampleG3ESP:TestDrive:Data:A] Running Loop=1
2014-10-27T22:16:25.911Z,1414448185.911 [sampleG3ESP:TestDrive:Data:A] Stopped
2014-10-27T22:16:25.911Z,1414448185.911 [sampleG3ESP:TestDrive:Data](INFO): Completed sampleG3ESP:TestDrive:Data
2014-10-27T22:16:25.911Z,1414448185.911 [sampleG3ESP:TestDrive:Data] Stopped
2014-10-27T22:16:25.912Z,1414448185.912 [sampleG3ESP:TestDrive:Data](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive:Data
2014-10-27T22:16:25.912Z,1414448185.912 [sampleG3ESP] Stopped
2014-10-27T22:16:25.912Z,1414448185.912 [sampleG3ESP](INFO): Aggregate::uninitialize sampleG3ESP
2014-10-27T22:16:25.912Z,1414448185.912 [sampleG3ESP:A.Pitch] Stopped
2014-10-27T22:16:25.913Z,1414448185.913 [sampleG3ESP:TestDrive] Stopped
2014-10-27T22:16:25.913Z,1414448185.913 [sampleG3ESP:TestDrive](INFO): Aggregate::uninitialize sampleG3ESP:TestDrive
2014-10-27T22:16:25.913Z,1414448185.913 [sampleG3ESP:TestDrive:A] Stopped
2014-10-27T22:16:25.913Z,1414448185.913 [sampleG3ESP:TestDrive:C.Wait] Stopped
2014-10-27T22:16:25.913Z,1414448185.913 [sampleG3ESP:TestDrive:C.Wait](DEBUG): Uninitialize Wait Component.
2014-10-27T22:16:26.313Z,1414448186.313 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:26.319Z,1414448186.319 [MissionManager](IMPORTANT): Started mission Default
2014-10-27T22:16:26.319Z,1414448186.319 [Default] Running Loop=1
2014-10-27T22:16:26.319Z,1414448186.319 [Default](INFO): Aggregate::initialize Default
2014-10-27T22:16:26.319Z,1414448186.319 [Default:B.GoToSurface] Running Loop=1
2014-10-27T22:16:26.319Z,1414448186.319 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-10-27T22:16:26.320Z,1414448186.320 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-10-27T22:16:26.320Z,1414448186.320 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-10-27T22:16:26.320Z,1414448186.320 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-10-27T22:16:26.320Z,1414448186.320 [Default:StartClock] Running Loop=1
2014-10-27T22:16:26.321Z,1414448186.321 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock
2014-10-27T22:16:26.321Z,1414448186.321 [Default:StartClock:A] Running Loop=1
2014-10-27T22:16:26.322Z,1414448186.322 [Default:StartClock:A] Stopped
2014-10-27T22:16:26.322Z,1414448186.322 [Default:StartClock](INFO): Completed Default:StartClock
2014-10-27T22:16:26.323Z,1414448186.323 [Default:StartClock] Stopped
2014-10-27T22:16:26.323Z,1414448186.323 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock
2014-10-27T22:16:26.741Z,1414448186.741 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:26.762Z,1414448186.762 [Default:WaitAtTheSurface] Running Loop=1
2014-10-27T22:16:26.763Z,1414448186.763 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2014-10-27T22:16:26.763Z,1414448186.763 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2014-10-27T22:16:26.763Z,1414448186.763 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2014-10-27T22:16:27.128Z,1414448187.128 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:32.287Z,1414448192.287 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:37.194Z,1414448197.194 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:42.278Z,1414448202.278 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:47.220Z,1414448207.220 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:52.267Z,1414448212.267 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:16:57.370Z,1414448217.370 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:02.248Z,1414448222.248 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:07.213Z,1414448227.213 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:12.297Z,1414448232.297 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:12.329Z,1414448232.329 [CBIT](CRITICAL): Environmental Failure. Press:14.982194 PSI. Humidity:56%. Temp:21 C. ABORTING MISSION
2014-10-27T22:17:17.212Z,1414448237.212 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:22.252Z,1414448242.252 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:27.215Z,1414448247.215 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:32.256Z,1414448252.256 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:37.260Z,1414448257.260 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:42.251Z,1414448262.251 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:47.211Z,1414448267.211 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:52.283Z,1414448272.283 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:52.290Z,1414448272.290 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2014-10-27T22:17:52.290Z,1414448272.290 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2014-10-27T22:17:52.290Z,1414448272.290 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2014-10-27T22:17:52.297Z,1414448272.297 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2014-10-27T22:17:52.297Z,1414448272.297 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2014-10-27T22:17:57.217Z,1414448277.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:17:57.224Z,1414448277.224 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 1.430841 min 
2014-10-27T22:17:57.226Z,1414448277.226 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2014-10-27T22:17:57.226Z,1414448277.226 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2014-10-27T22:17:57.227Z,1414448277.227 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2014-10-27T22:17:57.227Z,1414448277.227 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2014-10-27T22:18:02.279Z,1414448282.279 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:18:07.218Z,1414448287.218 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:18:12.250Z,1414448292.250 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:18:17.226Z,1414448297.226 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-10-27T22:18:18.136Z,1414448298.136 [CommandLine](IMPORTANT): got command restart application
2014-10-27T22:18:19.152Z,1414448299.152 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T22:18:19.153Z,1414448299.153 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.209Z,1414448299.209 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-10-27T22:18:19.210Z,1414448299.210 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.210Z,1414448299.210 [CommandLine](INFO): Join timeout helper Thread ID is 9327
2014-10-27T22:18:19.211Z,1414448299.211 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-10-27T22:18:19.211Z,1414448299.211 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.211Z,1414448299.211 [NavChartDb](INFO): Join timeout helper Thread ID is 9328
2014-10-27T22:18:19.254Z,1414448299.254 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T22:18:19.254Z,1414448299.254 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.269Z,1414448299.269 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-10-27T22:18:19.270Z,1414448299.270 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.270Z,1414448299.270 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9329
2014-10-27T22:18:19.546Z,1414448299.546 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T22:18:19.546Z,1414448299.546 [WetLabsBB2FL](INFO): Powering down
2014-10-27T22:18:19.547Z,1414448299.547 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.550Z,1414448299.550 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-10-27T22:18:19.550Z,1414448299.550 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.551Z,1414448299.551 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9330
2014-10-27T22:18:19.682Z,1414448299.682 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T22:18:19.683Z,1414448299.683 [CTD_NeilBrown](INFO): Powering down
2014-10-27T22:18:19.683Z,1414448299.683 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.690Z,1414448299.690 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-10-27T22:18:19.690Z,1414448299.690 [logger ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.691Z,1414448299.691 [logger](INFO): Join timeout helper Thread ID is 9331
2014-10-27T22:18:19.706Z,1414448299.706 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T22:18:19.707Z,1414448299.707 [logger ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.725Z,1414448299.725 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-10-27T22:18:19.725Z,1414448299.725 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.725Z,1414448299.726 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-10-27T22:18:19.726Z,1414448299.726 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:19.726Z,1414448299.726 [controlThread](INFO): Join timeout helper Thread ID is 9332
2014-10-27T22:18:21.774Z,1414448301.774 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-10-27T22:18:21.774Z,1414448301.774 [controlThread](DEBUG): Uninitializing ControlThread
2014-10-27T22:18:21.774Z,1414448301.774 [Aanderaa_O2](INFO): Powering down
2014-10-27T22:18:21.776Z,1414448301.776 [AHRS_sp3003D](INFO): Powering down
2014-10-27T22:18:21.862Z,1414448301.862 [AcousticModem_Benthos_ATM900](INFO): Powering down
2014-10-27T22:18:22.054Z,1414448302.054 [DVL_micro](INFO): uninitialize:Powering down
2014-10-27T22:18:22.055Z,1414448302.055 [NAL9602](INFO): Powering down
2014-10-27T22:18:22.057Z,1414448302.057 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-10-27T22:18:22.059Z,1414448302.059 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-10-27T22:18:22.059Z,1414448302.059 [Default] Stopped
2014-10-27T22:18:22.059Z,1414448302.059 [Default](INFO): Aggregate::uninitialize Default
2014-10-27T22:18:22.060Z,1414448302.060 [Default:B.GoToSurface] Stopped
2014-10-27T22:18:22.060Z,1414448302.060 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-10-27T22:18:22.060Z,1414448302.060 [Default:WaitAtTheSurface] Stopped
2014-10-27T22:18:22.060Z,1414448302.060 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface
2014-10-27T22:18:22.060Z,1414448302.060 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Stopped
2014-10-27T22:18:22.060Z,1414448302.060 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Uninitialize.
2014-10-27T22:18:22.063Z,1414448302.063 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-10-27T22:18:22.064Z,1414448302.064 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-10-27T22:18:22.064Z,1414448302.064 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-10-27T22:18:22.064Z,1414448302.064 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-10-27T22:18:22.065Z,1414448302.065 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-10-27T22:18:22.065Z,1414448302.065 [BuoyancyServo](INFO): Powering down
2014-10-27T22:18:22.078Z,1414448302.078 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-10-27T22:18:22.078Z,1414448302.078 [ElevatorServo](INFO): Powering down
2014-10-27T22:18:22.079Z,1414448302.079 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-10-27T22:18:22.079Z,1414448302.079 [MassServo](INFO): Powering down
2014-10-27T22:18:22.080Z,1414448302.080 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-10-27T22:18:22.080Z,1414448302.080 [RudderServo](INFO): Powering down
2014-10-27T22:18:22.081Z,1414448302.081 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-10-27T22:18:22.081Z,1414448302.081 [ThrusterServo](INFO): Powering down
2014-10-27T22:18:22.082Z,1414448302.082 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-10-27T22:18:22.082Z,1414448302.082 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-10-27T22:18:22.082Z,1414448302.082 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-10-27T22:18:22.115Z,1414448302.115 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:22.175Z,1414448302.175 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:22.179Z,1414448302.179 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:22.273Z,1414448302.273 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-10-27T22:18:22.319Z,1414448302.319 [logger ThreadHandler](INFO): Thread cancelled.