2014-09-24T01:05:49.233Z,1411520749.233 [Supervisor](DEBUG): Initializing supervisor.
2014-09-24T01:05:49.236Z,1411520749.236 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-09-24T01:05:49.236Z,1411520749.236 [SyncHandler](INFO): Protected caller Thread ID is 2006
2014-09-24T01:05:49.237Z,1411520749.237 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-09-24T01:05:49.238Z,1411520749.238 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-09-24T01:05:49.238Z,1411520749.238 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2007
2014-09-24T01:05:49.241Z,1411520749.241 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-09-24T01:05:49.253Z,1411520749.253 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-09-24T01:05:49.254Z,1411520749.254 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-09-24T01:05:49.254Z,1411520749.254 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2008
2014-09-24T01:05:49.255Z,1411520749.255 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-09-24T01:05:49.256Z,1411520749.256 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-09-24T01:05:49.257Z,1411520749.257 [logger ThreadHandler](INFO): Protected caller Thread ID is 2009
2014-09-24T01:05:49.259Z,1411520749.259 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-09-24T01:05:49.259Z,1411520749.259 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-09-24T01:05:49.260Z,1411520749.260 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-09-24T01:05:49.588Z,1411520749.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-09-24T01:05:49.589Z,1411520749.589 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-09-24T01:05:49.745Z,1411520749.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-09-24T01:05:49.746Z,1411520749.746 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-09-24T01:05:49.853Z,1411520749.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-09-24T01:05:49.854Z,1411520749.854 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-09-24T01:05:49.978Z,1411520749.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-09-24T01:05:49.978Z,1411520749.978 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-09-24T01:05:50.064Z,1411520750.064 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-09-24T01:05:50.208Z,1411520750.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-09-24T01:05:50.208Z,1411520750.208 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-09-24T01:05:50.294Z,1411520750.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-09-24T01:05:50.295Z,1411520750.295 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-09-24T01:05:50.493Z,1411520750.493 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-09-24T01:05:50.494Z,1411520750.494 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-09-24T01:05:50.827Z,1411520750.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-09-24T01:05:50.828Z,1411520750.828 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-09-24T01:05:51.127Z,1411520751.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-09-24T01:05:51.128Z,1411520751.128 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-09-24T01:05:51.617Z,1411520751.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-09-24T01:05:51.618Z,1411520751.618 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-09-24T01:05:51.818Z,1411520751.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-09-24T01:05:51.819Z,1411520751.819 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-09-24T01:05:51.922Z,1411520751.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-09-24T01:05:51.923Z,1411520751.923 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-09-24T01:05:52.332Z,1411520752.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-09-24T01:05:52.333Z,1411520752.333 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-09-24T01:05:52.444Z,1411520752.444 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-09-24T01:05:52.446Z,1411520752.446 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2014-09-24T01:05:52.447Z,1411520752.447 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2014-09-24T01:05:52.685Z,1411520752.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2014-09-24T01:05:52.686Z,1411520752.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2014-09-24T01:05:52.801Z,1411520752.801 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2014-09-24T01:05:52.898Z,1411520752.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2014-09-24T01:05:52.994Z,1411520752.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2014-09-24T01:05:53.091Z,1411520753.091 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2014-09-24T01:05:53.229Z,1411520753.229 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2014-09-24T01:05:53.416Z,1411520753.416 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2014-09-24T01:05:53.516Z,1411520753.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2014-09-24T01:05:53.603Z,1411520753.603 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2014-09-24T01:05:53.703Z,1411520753.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2014-09-24T01:05:53.799Z,1411520753.799 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2014-09-24T01:05:53.972Z,1411520753.972 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-09-24T01:05:53.976Z,1411520753.976 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-09-24T01:05:54.026Z,1411520754.026 [VerticalControl](DEBUG): Construct VerticalControl.
2014-09-24T01:05:54.146Z,1411520754.146 [VerticalControl] Loaded
2014-09-24T01:05:54.146Z,1411520754.146 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-09-24T01:05:54.147Z,1411520754.147 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-09-24T01:05:54.218Z,1411520754.218 [HorizontalControl] Loaded
2014-09-24T01:05:54.218Z,1411520754.218 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-09-24T01:05:54.219Z,1411520754.219 [SpeedControl](DEBUG): Construct SpeedControl.
2014-09-24T01:05:54.225Z,1411520754.225 [SpeedControl] Loaded
2014-09-24T01:05:54.225Z,1411520754.225 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-09-24T01:05:54.226Z,1411520754.226 [LoopControl](DEBUG): Construct LoopControl.
2014-09-24T01:05:54.227Z,1411520754.227 [LoopControl] Loaded
2014-09-24T01:05:54.227Z,1411520754.227 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-09-24T01:05:54.227Z,1411520754.227 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-09-24T01:05:54.228Z,1411520754.228 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-09-24T01:05:54.334Z,1411520754.334 [SBIT](DEBUG): Construct Startup Built In Test.
2014-09-24T01:05:54.345Z,1411520754.345 [SBIT] Loaded
2014-09-24T01:05:54.346Z,1411520754.346 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-09-24T01:05:54.347Z,1411520754.347 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-09-24T01:05:54.376Z,1411520754.376 [IBIT] Loaded
2014-09-24T01:05:54.376Z,1411520754.376 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-09-24T01:05:54.379Z,1411520754.379 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-09-24T01:05:54.499Z,1411520754.499 [CBIT] Loaded
2014-09-24T01:05:54.500Z,1411520754.500 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-09-24T01:05:54.500Z,1411520754.500 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-09-24T01:05:54.501Z,1411520754.501 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-09-24T01:05:54.520Z,1411520754.520 [DepthRateCalculator] Loaded
2014-09-24T01:05:54.520Z,1411520754.520 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-09-24T01:05:54.526Z,1411520754.526 [PitchRateCalculator] Loaded
2014-09-24T01:05:54.526Z,1411520754.526 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-09-24T01:05:54.537Z,1411520754.537 [SpeedCalculator] Loaded
2014-09-24T01:05:54.538Z,1411520754.538 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-09-24T01:05:54.554Z,1411520754.554 [TempGradientCalculator] Loaded
2014-09-24T01:05:54.554Z,1411520754.554 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-09-24T01:05:54.560Z,1411520754.560 [YawRateCalculator] Loaded
2014-09-24T01:05:54.560Z,1411520754.560 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-09-24T01:05:54.560Z,1411520754.560 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-09-24T01:05:54.561Z,1411520754.561 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-09-24T01:05:55.792Z,1411520755.792 [HFRCMReconstructedInterpolator] Loaded
2014-09-24T01:05:55.792Z,1411520755.792 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-09-24T01:05:55.793Z,1411520755.793 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-09-24T01:05:55.793Z,1411520755.793 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-09-24T01:05:56.036Z,1411520756.036 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-09-24T01:05:56.037Z,1411520756.037 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-09-24T01:05:56.113Z,1411520756.113 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-09-24T01:05:56.114Z,1411520756.114 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-09-24T01:05:56.158Z,1411520756.158 [DeadReckonWithRespectToWater] Loaded
2014-09-24T01:05:56.158Z,1411520756.158 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-09-24T01:05:56.204Z,1411520756.204 [DeadReckonWithRespectToSeafloor] Loaded
2014-09-24T01:05:56.204Z,1411520756.204 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-09-24T01:05:56.254Z,1411520756.254 [DeadReckonUsingDVLWaterTrack] Loaded
2014-09-24T01:05:56.254Z,1411520756.254 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-09-24T01:05:56.272Z,1411520756.272 [NavChart] Loaded
2014-09-24T01:05:56.272Z,1411520756.272 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-09-24T01:05:56.276Z,1411520756.276 [UniversalFixResidualReporter] Loaded
2014-09-24T01:05:56.276Z,1411520756.276 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-09-24T01:05:56.277Z,1411520756.277 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-09-24T01:05:56.278Z,1411520756.278 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-09-24T01:05:56.283Z,1411520756.283 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-09-24T01:05:56.284Z,1411520756.284 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-09-24T01:05:56.453Z,1411520756.453 [Aanderaa_O2] Loaded
2014-09-24T01:05:56.453Z,1411520756.453 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-09-24T01:05:56.490Z,1411520756.490 [CTD_NeilBrown] Loaded
2014-09-24T01:05:56.490Z,1411520756.490 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-09-24T01:05:56.491Z,1411520756.491 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405D94E0
2014-09-24T01:05:56.492Z,1411520756.492 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2091
2014-09-24T01:05:56.509Z,1411520756.509 [rhodamine] Loaded
2014-09-24T01:05:56.509Z,1411520756.509 [ComponentRegistry](DEBUG): SyncComponent "rhodamine" handled in the control thread.
2014-09-24T01:05:56.544Z,1411520756.544 [WetLabsBB2FL] Loaded
2014-09-24T01:05:56.545Z,1411520756.545 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-09-24T01:05:56.546Z,1411520756.546 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406094E0
2014-09-24T01:05:56.546Z,1411520756.546 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2092
2014-09-24T01:05:56.547Z,1411520756.547 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-09-24T01:05:56.547Z,1411520756.547 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-09-24T01:05:56.865Z,1411520756.865 [AHRS_sp3003D] Loaded
2014-09-24T01:05:56.865Z,1411520756.865 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-09-24T01:05:56.938Z,1411520756.938 [DataOverHttps] Loaded
2014-09-24T01:05:56.938Z,1411520756.938 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2014-09-24T01:05:56.952Z,1411520756.952 [Depth_Keller] Loaded
2014-09-24T01:05:56.952Z,1411520756.952 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-09-24T01:05:56.958Z,1411520756.958 [DropWeight] Loaded
2014-09-24T01:05:56.958Z,1411520756.958 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-09-24T01:05:57.099Z,1411520757.099 [DVL_micro] Loaded
2014-09-24T01:05:57.099Z,1411520757.099 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-09-24T01:05:57.194Z,1411520757.194 [NAL9602] Loaded
2014-09-24T01:05:57.194Z,1411520757.194 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-09-24T01:05:57.237Z,1411520757.237 [Onboard] Loaded
2014-09-24T01:05:57.237Z,1411520757.237 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-09-24T01:05:57.244Z,1411520757.244 [Radio_Surface] Loaded
2014-09-24T01:05:57.244Z,1411520757.244 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2014-09-24T01:05:57.245Z,1411520757.245 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408534E0
2014-09-24T01:05:57.246Z,1411520757.246 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2093
2014-09-24T01:05:59.127Z,1411520759.127 [BPC1] Loaded
2014-09-24T01:05:59.128Z,1411520759.128 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2014-09-24T01:05:59.128Z,1411520759.128 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-09-24T01:05:59.129Z,1411520759.129 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-09-24T01:05:59.228Z,1411520759.228 [BuoyancyServo] Loaded
2014-09-24T01:05:59.229Z,1411520759.229 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-09-24T01:05:59.241Z,1411520759.241 [ElevatorServo] Loaded
2014-09-24T01:05:59.242Z,1411520759.242 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-09-24T01:05:59.254Z,1411520759.254 [MassServo] Loaded
2014-09-24T01:05:59.254Z,1411520759.254 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-09-24T01:05:59.266Z,1411520759.266 [RudderServo] Loaded
2014-09-24T01:05:59.267Z,1411520759.267 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-09-24T01:05:59.279Z,1411520759.279 [ThrusterServo] Loaded
2014-09-24T01:05:59.279Z,1411520759.279 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-09-24T01:05:59.279Z,1411520759.279 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-09-24T01:05:59.280Z,1411520759.280 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-09-24T01:05:59.446Z,1411520759.446 [InternalSim] Loaded
2014-09-24T01:05:59.446Z,1411520759.446 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-09-24T01:05:59.447Z,1411520759.447 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-09-24T01:05:59.447Z,1411520759.447 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-09-24T01:05:59.471Z,1411520759.471 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-09-24T01:05:59.473Z,1411520759.473 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-09-24T01:05:59.474Z,1411520759.474 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-09-24T01:05:59.481Z,1411520759.481 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-09-24T01:05:59.482Z,1411520759.482 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409044E0
2014-09-24T01:05:59.482Z,1411520759.482 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2094
2014-09-24T01:05:59.487Z,1411520759.487 [Supervisor](INFO): Main Thread ID is 764
2014-09-24T01:05:59.487Z,1411520759.487 [Supervisor](DEBUG): Running supervisor.
2014-09-24T01:05:59.488Z,1411520759.488 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2095
2014-09-24T01:05:59.491Z,1411520759.491 [controlThread ThreadHandler](INFO): Handler Thread ID is 2096
2014-09-24T01:05:59.491Z,1411520759.491 [controlThread](DEBUG): Initializing ControlThread
2014-09-24T01:05:59.492Z,1411520759.492 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-09-24T01:05:59.494Z,1411520759.494 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-09-24T01:05:59.494Z,1411520759.494 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-09-24T01:05:59.495Z,1411520759.495 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-09-24T01:05:59.495Z,1411520759.495 [SBIT](INFO): Initialize SBIT Component.
2014-09-24T01:05:59.496Z,1411520759.496 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11701
2014-09-24T01:05:59.496Z,1411520759.496 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2014-09-24T01:05:59.496Z,1411520759.496 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-09-24T01:05:59.497Z,1411520759.497 [IBIT](INFO): Initialize IBIT Component.
2014-09-24T01:05:59.498Z,1411520759.498 [CBIT](DEBUG): Initialize CBIT Component.
2014-09-24T01:05:59.498Z,1411520759.498 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-09-24T01:05:59.499Z,1411520759.499 [logger ThreadHandler](INFO): Handler Thread ID is 2097
2014-09-24T01:05:59.519Z,1411520759.519 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2098
2014-09-24T01:05:59.519Z,1411520759.519 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-09-24T01:05:59.523Z,1411520759.523 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-24T01:05:59.523Z,1411520759.523 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-09-24T01:05:59.523Z,1411520759.523 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-09-24T01:05:59.523Z,1411520759.523 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-09-24T01:05:59.524Z,1411520759.524 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-09-24T01:05:59.525Z,1411520759.525 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-09-24T01:05:59.527Z,1411520759.527 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-24T01:05:59.527Z,1411520759.527 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-09-24T01:05:59.528Z,1411520759.528 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-09-24T01:05:59.528Z,1411520759.528 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-09-24T01:05:59.528Z,1411520759.528 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-09-24T01:05:59.529Z,1411520759.529 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-24T01:05:59.535Z,1411520759.535 [InternalSim](DEBUG):  InternalSim initializing...
2014-09-24T01:05:59.539Z,1411520759.539 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-09-24T01:05:59.548Z,1411520759.548 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2100
2014-09-24T01:05:59.549Z,1411520759.549 [WetLabsBB2FL](INFO): Powering down
2014-09-24T01:05:59.583Z,1411520759.583 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2101
2014-09-24T01:05:59.589Z,1411520759.589 [Radio_Surface](INFO): Powering up
2014-09-24T01:05:59.599Z,1411520759.599 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2102
2014-09-24T01:05:59.602Z,1411520759.602 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-09-24T01:05:59.603Z,1411520759.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-09-24T01:05:59.603Z,1411520759.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-09-24T01:05:59.604Z,1411520759.604 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-09-24T01:05:59.604Z,1411520759.604 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-09-24T01:05:59.604Z,1411520759.604 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-09-24T01:05:59.604Z,1411520759.604 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-09-24T01:05:59.605Z,1411520759.605 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-09-24T01:05:59.605Z,1411520759.605 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-09-24T01:05:59.697Z,1411520759.697 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-09-24T01:05:59.722Z,1411520759.722 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-24T01:05:59.759Z,1411520759.759 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2014-09-24T01:05:59.760Z,1411520759.760 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-09-24T01:05:59.855Z,1411520759.855 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-09-24T01:05:59.858Z,1411520759.858 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-09-24T01:05:59.887Z,1411520759.887 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-24T01:05:59.912Z,1411520759.912 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-09-24T01:05:59.924Z,1411520759.924 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-09-24T01:05:59.960Z,1411520759.960 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-09-24T01:05:59.974Z,1411520759.974 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

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

    <Aggregate Id="StartClock">

        <Sequence/>

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

    </Aggregate>

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

    <Aggregate Id="CheckIn">

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

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

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

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

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

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="UpdateAndReportMinutesSinceMissionStarted">

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

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

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

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2014-09-24T01:05:59.980Z,1411520759.980 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,Aanderaa_O2,rhodamine,AHRS_sp3003D,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-09-24T01:06:00.112Z,1411520760.112 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-09-24T01:06:00.287Z,1411520760.287 [DVL_micro](INFO): Initializing
2014-09-24T01:06:00.540Z,1411520760.540 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:00.552Z,1411520760.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:00.573Z,1411520760.573 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:00.592Z,1411520760.592 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:00.599Z,1411520760.599 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:00.725Z,1411520760.725 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-09-24T01:06:00.732Z,1411520760.732 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-09-24T01:06:00.751Z,1411520760.751 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-09-24T01:06:00.756Z,1411520760.756 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-09-24T01:06:00.778Z,1411520760.778 [MassServo](DEBUG): Initializing EZServoServo.
2014-09-24T01:06:00.784Z,1411520760.784 [MassServo](DEBUG): Initializing MassServo.
2014-09-24T01:06:00.794Z,1411520760.794 [RudderServo](DEBUG): Initializing EZServoServo.
2014-09-24T01:06:00.801Z,1411520760.801 [RudderServo](DEBUG): Initializing RudderServo.
2014-09-24T01:06:00.822Z,1411520760.822 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-09-24T01:06:00.833Z,1411520760.833 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-09-24T01:06:01.108Z,1411520761.108 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:01.108Z,1411520761.108 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:01.121Z,1411520761.121 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:01.122Z,1411520761.122 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:01.123Z,1411520761.123 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:01.663Z,1411520761.663 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:01.663Z,1411520761.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:01.664Z,1411520761.664 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:01.665Z,1411520761.665 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:01.674Z,1411520761.674 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-24T01:06:02.317Z,1411520762.317 [NAL9602](INFO): Powering up NAL9602
2014-09-24T01:06:02.346Z,1411520762.346 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:02.995Z,1411520762.995 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:03.483Z,1411520763.483 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:03.793Z,1411520763.793 [Aanderaa_O2](INFO): Powering down
2014-09-24T01:06:04.316Z,1411520764.316 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:04.783Z,1411520764.783 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:05.390Z,1411520765.390 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:06.304Z,1411520766.304 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:06.781Z,1411520766.781 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:07.310Z,1411520767.310 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:08.027Z,1411520768.027 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:08.535Z,1411520768.535 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:08.969Z,1411520768.969 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:09.406Z,1411520769.406 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:09.842Z,1411520769.842 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:10.489Z,1411520770.489 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:11.144Z,1411520771.144 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:11.641Z,1411520771.641 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:12.089Z,1411520772.089 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:12.643Z,1411520772.643 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:13.155Z,1411520773.155 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:13.583Z,1411520773.583 [NAL9602](INFO): NAL9602 initialized
2014-09-24T01:06:13.837Z,1411520773.837 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:14.257Z,1411520774.257 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:14.750Z,1411520774.750 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:15.253Z,1411520775.253 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:15.316Z,1411520775.316 [SBIT](IMPORTANT): Beginning Startup BIT
2014-09-24T01:06:15.319Z,1411520775.319 [CBIT](IMPORTANT): Beginning GF scan
2014-09-24T01:06:15.755Z,1411520775.755 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:16.344Z,1411520776.344 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:16.842Z,1411520776.842 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:17.291Z,1411520777.291 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:17.854Z,1411520777.854 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:18.367Z,1411520778.367 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:18.861Z,1411520778.861 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:19.376Z,1411520779.376 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:19.961Z,1411520779.961 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:20.433Z,1411520780.433 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:21.006Z,1411520781.006 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:21.510Z,1411520781.510 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:22.008Z,1411520782.008 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:22.481Z,1411520782.481 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:22.993Z,1411520782.993 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:23.527Z,1411520783.527 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:24.015Z,1411520784.015 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:24.539Z,1411520784.539 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:25.037Z,1411520785.037 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:25.525Z,1411520785.525 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:26.062Z,1411520786.062 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:26.559Z,1411520786.559 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:27.056Z,1411520787.056 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:27.549Z,1411520787.549 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:28.157Z,1411520788.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:28.703Z,1411520788.703 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:29.196Z,1411520789.196 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:29.733Z,1411520789.733 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:30.281Z,1411520790.281 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:30.782Z,1411520790.782 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:31.341Z,1411520791.341 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:31.855Z,1411520791.855 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:32.344Z,1411520792.344 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:32.837Z,1411520792.837 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:33.318Z,1411520793.318 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:33.823Z,1411520793.823 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:34.291Z,1411520794.291 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:34.836Z,1411520794.836 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:35.355Z,1411520795.355 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:35.902Z,1411520795.902 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:36.398Z,1411520796.398 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:36.902Z,1411520796.902 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:37.376Z,1411520797.376 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:37.872Z,1411520797.872 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:38.428Z,1411520798.428 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:38.911Z,1411520798.911 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:39.415Z,1411520799.415 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:39.925Z,1411520799.925 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:40.452Z,1411520800.452 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:40.961Z,1411520800.961 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:41.549Z,1411520801.549 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:41.661Z,1411520801.661 [CBIT](IMPORTANT): No ground fault detected
2014-09-24T01:06:42.051Z,1411520802.051 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:42.555Z,1411520802.555 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:43.081Z,1411520803.081 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:43.574Z,1411520803.574 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:44.147Z,1411520804.147 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:44.682Z,1411520804.682 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:45.172Z,1411520805.172 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:45.665Z,1411520805.665 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:46.142Z,1411520806.142 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:46.670Z,1411520806.670 [BPC1](FAULT): Failed to receive battery data
2014-09-24T01:06:46.670Z,1411520806.670 [BPC1] Communications Fault, FailCount= 1
2014-09-24T01:06:46.671Z,1411520806.671 [BPC1](ERROR): Communications Fault
2014-09-24T01:06:46.680Z,1411520806.680 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:46.763Z,1411520806.763 [CBIT](ERROR): Communications Fault in component: BPC1
2014-09-24T01:06:47.128Z,1411520807.128 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:47.597Z,1411520807.597 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:48.100Z,1411520808.100 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:48.160Z,1411520808.160 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-24T01:06:48.160Z,1411520808.160 [BPC1] No Fault, FailCount= 1
2014-09-24T01:06:48.634Z,1411520808.634 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:49.087Z,1411520809.087 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:49.549Z,1411520809.549 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:49.976Z,1411520809.976 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:50.429Z,1411520810.429 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:50.886Z,1411520810.886 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:51.351Z,1411520811.351 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:51.835Z,1411520811.835 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:52.275Z,1411520812.275 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:52.731Z,1411520812.731 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:53.184Z,1411520813.184 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:53.617Z,1411520813.617 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:54.038Z,1411520814.038 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:54.501Z,1411520814.501 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:54.952Z,1411520814.952 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:55.391Z,1411520815.391 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:55.781Z,1411520815.781 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:56.191Z,1411520816.191 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:56.594Z,1411520816.594 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:56.996Z,1411520816.996 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:57.415Z,1411520817.415 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:58.003Z,1411520818.003 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:58.466Z,1411520818.466 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:58.984Z,1411520818.984 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:59.441Z,1411520819.441 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:06:59.895Z,1411520819.895 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:00.346Z,1411520820.346 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:00.897Z,1411520820.897 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:01.564Z,1411520821.564 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:01.990Z,1411520821.990 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:02.038Z,1411520822.038 [MassServo](FAULT): Failed to complete homing within timeout
2014-09-24T01:07:02.038Z,1411520822.038 [MassServo] Hardware Fault, FailCount= 1
2014-09-24T01:07:02.038Z,1411520822.038 [MassServo](ERROR): Hardware Fault
2014-09-24T01:07:02.042Z,1411520822.042 [CBIT](ERROR): Hardware Fault in component: MassServo
2014-09-24T01:07:02.395Z,1411520822.395 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:02.407Z,1411520822.407 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-09-24T01:07:02.407Z,1411520822.407 [MassServo](INFO): Powering down
2014-09-24T01:07:02.804Z,1411520822.804 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:03.202Z,1411520823.202 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:03.335Z,1411520823.335 [CBIT](INFO): Clearing failed state for component MassServo
2014-09-24T01:07:03.335Z,1411520823.335 [MassServo] No Fault, FailCount= 1
2014-09-24T01:07:03.744Z,1411520823.744 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:03.754Z,1411520823.754 [MassServo](DEBUG): Initializing EZServoServo.
2014-09-24T01:07:03.755Z,1411520823.755 [MassServo](DEBUG): Initializing MassServo.
2014-09-24T01:07:04.109Z,1411520824.109 [NAL9602](IMPORTANT): GPS fix at: 1411520822.00
2014-09-24T01:07:04.119Z,1411520824.119 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:04.619Z,1411520824.619 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:05.058Z,1411520825.058 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:05.475Z,1411520825.475 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:05.886Z,1411520825.886 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:06.305Z,1411520826.305 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:06.759Z,1411520826.759 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:07.186Z,1411520827.186 [BPC1](FAULT): Failed to receive battery data
2014-09-24T01:07:07.186Z,1411520827.186 [BPC1] Communications Fault, FailCount= 2
2014-09-24T01:07:07.186Z,1411520827.186 [BPC1](ERROR): Communications Fault
2014-09-24T01:07:07.222Z,1411520827.222 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:07.287Z,1411520827.287 [CBIT](ERROR): Communications Fault in component: BPC1
2014-09-24T01:07:07.776Z,1411520827.776 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:08.227Z,1411520828.227 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:08.707Z,1411520828.707 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:08.765Z,1411520828.765 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-24T01:07:08.765Z,1411520828.765 [BPC1] No Fault, FailCount= 2
2014-09-24T01:07:09.238Z,1411520829.238 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:09.714Z,1411520829.714 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:09.789Z,1411520829.789 [SBIT](IMPORTANT): SBIT PASSED
2014-09-24T01:07:10.195Z,1411520830.195 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:10.226Z,1411520830.226 [MissionManager](IMPORTANT): Started mission Startup
2014-09-24T01:07:10.226Z,1411520830.226 [Startup] Running Loop=1
2014-09-24T01:07:10.226Z,1411520830.226 [Startup](INFO): Aggregate::initialize Startup
2014-09-24T01:07:10.226Z,1411520830.226 [Startup:A.GoToSurface] Running Loop=1
2014-09-24T01:07:10.226Z,1411520830.226 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-09-24T01:07:10.227Z,1411520830.227 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-09-24T01:07:10.227Z,1411520830.227 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-09-24T01:07:10.227Z,1411520830.227 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-09-24T01:07:10.234Z,1411520830.234 [Startup:StartupSatComms] Running Loop=1
2014-09-24T01:07:10.234Z,1411520830.234 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-09-24T01:07:10.234Z,1411520830.234 [Startup:StartupSatComms:A] Running Loop=1
2014-09-24T01:07:10.685Z,1411520830.685 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:10.693Z,1411520830.693 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-09-24T01:07:11.144Z,1411520831.144 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:11.597Z,1411520831.597 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:12.034Z,1411520832.034 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:12.479Z,1411520832.479 [NAL9602](IMPORTANT): GPS fix at: 1411520830.00
2014-09-24T01:07:12.489Z,1411520832.489 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:12.500Z,1411520832.500 [Startup:StartupSatComms:A] Stopped
2014-09-24T01:07:12.500Z,1411520832.500 [Startup:StartupSatComms:B] Running Loop=1
2014-09-24T01:07:12.947Z,1411520832.947 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:12.964Z,1411520832.964 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-09-24T01:07:13.684Z,1411520833.684 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:14.157Z,1411520834.157 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:14.657Z,1411520834.657 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:15.086Z,1411520835.086 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:15.504Z,1411520835.504 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:15.913Z,1411520835.913 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:16.361Z,1411520836.361 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:16.788Z,1411520836.788 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:17.254Z,1411520837.254 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:17.739Z,1411520837.739 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:18.176Z,1411520838.176 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:18.588Z,1411520838.588 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:19.022Z,1411520839.022 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:19.439Z,1411520839.439 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:19.876Z,1411520839.876 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:20.328Z,1411520840.328 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:21.319Z,1411520841.319 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:22.007Z,1411520842.007 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:24.460Z,1411520844.460 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:25.015Z,1411520845.015 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:25.500Z,1411520845.500 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:25.955Z,1411520845.955 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:26.402Z,1411520846.402 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:26.822Z,1411520846.822 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:27.259Z,1411520847.259 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:27.693Z,1411520847.693 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:28.224Z,1411520848.224 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:28.652Z,1411520848.652 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:29.084Z,1411520849.084 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:29.508Z,1411520849.508 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:29.931Z,1411520849.931 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:30.355Z,1411520850.355 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:30.779Z,1411520850.779 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:31.232Z,1411520851.232 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:31.668Z,1411520851.668 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:32.083Z,1411520852.083 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:32.502Z,1411520852.502 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:32.931Z,1411520852.931 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:33.372Z,1411520853.372 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:33.781Z,1411520853.781 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:34.210Z,1411520854.210 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:34.660Z,1411520854.660 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:35.089Z,1411520855.089 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:35.504Z,1411520855.504 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:35.905Z,1411520855.905 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:36.368Z,1411520856.368 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:36.798Z,1411520856.798 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:37.215Z,1411520857.215 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=1220, MT Status=1, MTMSN=135
2014-09-24T01:07:37.267Z,1411520857.267 [NAL9602](INFO): Sent 25 bytes from file Logs/20140923T222612/Courier0072.lzma
2014-09-24T01:07:37.267Z,1411520857.267 [NAL9602](INFO): Packets left to send: 0
2014-09-24T01:07:45.710Z,1411520865.710 [NAL9602](INFO): Stored copy of sent data in Logs/20140923T222612/Courier0072.lzma.parts/0000.sbd
2014-09-24T01:07:45.711Z,1411520865.711 [NAL9602](INFO): Completed sending Logs/20140923T222612/Courier0072.lzma
2014-09-24T01:07:45.730Z,1411520865.730 [BPC1](FAULT): Failed to receive battery data
2014-09-24T01:07:45.730Z,1411520865.730 [BPC1] Communications Fault, FailCount= 1
2014-09-24T01:07:45.730Z,1411520865.730 [BPC1](ERROR): Communications Fault
2014-09-24T01:07:45.736Z,1411520865.736 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:45.826Z,1411520865.826 [CBIT](ERROR): Communications Fault in component: BPC1
2014-09-24T01:07:46.253Z,1411520866.253 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-09-24T01:07:46.254Z,1411520866.254 [DVL_micro] Communications Fault, FailCount= 1
2014-09-24T01:07:46.254Z,1411520866.254 [DVL_micro](ERROR): Communications Fault
2014-09-24T01:07:46.346Z,1411520866.346 [NAL9602](INFO): Received command:restart app
2014-09-24T01:07:46.368Z,1411520866.368 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:46.394Z,1411520866.394 [CommandLine](IMPORTANT): got command restart application
2014-09-24T01:07:46.416Z,1411520866.416 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-09-24T01:07:46.846Z,1411520866.846 [DVL_micro](INFO): uninitialize:Powering down
2014-09-24T01:07:46.868Z,1411520866.868 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:47.261Z,1411520867.261 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:47.305Z,1411520867.305 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-24T01:07:47.305Z,1411520867.305 [BPC1] No Fault, FailCount= 1
2014-09-24T01:07:47.402Z,1411520867.402 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-24T01:07:47.402Z,1411520867.402 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:47.530Z,1411520867.530 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-09-24T01:07:47.530Z,1411520867.530 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:47.531Z,1411520867.531 [CommandLine](INFO): Join timeout helper Thread ID is 2116
2014-09-24T01:07:47.550Z,1411520867.550 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-09-24T01:07:47.551Z,1411520867.551 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:47.551Z,1411520867.551 [NavChartDb](INFO): Join timeout helper Thread ID is 2117
2014-09-24T01:07:47.699Z,1411520867.699 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-24T01:07:47.699Z,1411520867.699 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:47.703Z,1411520867.703 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2014-09-24T01:07:47.703Z,1411520867.703 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:47.703Z,1411520867.703 [Radio_Surface](INFO): Join timeout helper Thread ID is 2118
2014-09-24T01:07:47.763Z,1411520867.763 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:47.810Z,1411520867.810 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-24T01:07:47.810Z,1411520867.810 [DVL_micro] No Fault, FailCount= 1
2014-09-24T01:07:47.915Z,1411520867.915 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-24T01:07:47.916Z,1411520867.916 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:47.923Z,1411520867.923 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-09-24T01:07:47.923Z,1411520867.923 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:47.923Z,1411520867.923 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2119
2014-09-24T01:07:48.131Z,1411520868.131 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-24T01:07:48.132Z,1411520868.132 [WetLabsBB2FL](INFO): Powering down
2014-09-24T01:07:48.132Z,1411520868.132 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:48.143Z,1411520868.143 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-09-24T01:07:48.143Z,1411520868.143 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:48.144Z,1411520868.144 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 2120
2014-09-24T01:07:48.175Z,1411520868.175 [DVL_micro](INFO): Initializing
2014-09-24T01:07:48.227Z,1411520868.227 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:48.256Z,1411520868.256 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-24T01:07:48.256Z,1411520868.256 [CTD_NeilBrown](INFO): Powering down
2014-09-24T01:07:48.257Z,1411520868.257 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:48.268Z,1411520868.268 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-09-24T01:07:48.269Z,1411520868.269 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:48.269Z,1411520868.269 [logger](INFO): Join timeout helper Thread ID is 2122
2014-09-24T01:07:48.288Z,1411520868.288 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-24T01:07:48.288Z,1411520868.288 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:48.308Z,1411520868.308 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-09-24T01:07:48.308Z,1411520868.308 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:48.309Z,1411520868.309 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-09-24T01:07:48.309Z,1411520868.309 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:48.309Z,1411520868.309 [controlThread](INFO): Join timeout helper Thread ID is 2123
2014-09-24T01:07:48.646Z,1411520868.646 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-24T01:07:48.693Z,1411520868.693 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-24T01:07:48.693Z,1411520868.693 [controlThread](DEBUG): Uninitializing ControlThread
2014-09-24T01:07:48.693Z,1411520868.693 [Aanderaa_O2](INFO): Powering down
2014-09-24T01:07:48.694Z,1411520868.694 [AHRS_sp3003D](INFO): Powering down
2014-09-24T01:07:48.853Z,1411520868.853 [DVL_micro](INFO): uninitialize:Powering down
2014-09-24T01:07:48.854Z,1411520868.854 [NAL9602](INFO): Powering down
2014-09-24T01:07:48.855Z,1411520868.855 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-24T01:07:48.857Z,1411520868.857 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-09-24T01:07:48.860Z,1411520868.860 [Startup] Stopped
2014-09-24T01:07:48.860Z,1411520868.860 [Startup](INFO): Aggregate::uninitialize Startup
2014-09-24T01:07:48.860Z,1411520868.860 [Startup:A.GoToSurface] Stopped
2014-09-24T01:07:48.860Z,1411520868.860 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-09-24T01:07:48.861Z,1411520868.861 [Startup:StartupSatComms] Stopped
2014-09-24T01:07:48.861Z,1411520868.861 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-09-24T01:07:48.861Z,1411520868.861 [Startup:StartupSatComms:B] Stopped
2014-09-24T01:07:48.862Z,1411520868.862 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-09-24T01:07:48.862Z,1411520868.862 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-09-24T01:07:48.863Z,1411520868.863 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-09-24T01:07:48.863Z,1411520868.863 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-09-24T01:07:48.863Z,1411520868.863 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-09-24T01:07:48.863Z,1411520868.863 [BuoyancyServo](INFO): Powering down
2014-09-24T01:07:48.877Z,1411520868.877 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-09-24T01:07:48.877Z,1411520868.877 [ElevatorServo](INFO): Powering down
2014-09-24T01:07:48.877Z,1411520868.877 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-09-24T01:07:48.878Z,1411520868.878 [MassServo](INFO): Powering down
2014-09-24T01:07:48.878Z,1411520868.878 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-09-24T01:07:48.878Z,1411520868.878 [RudderServo](INFO): Powering down
2014-09-24T01:07:48.879Z,1411520868.879 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-09-24T01:07:48.879Z,1411520868.879 [ThrusterServo](INFO): Powering down
2014-09-24T01:07:48.880Z,1411520868.880 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-09-24T01:07:48.881Z,1411520868.881 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-09-24T01:07:48.881Z,1411520868.881 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-09-24T01:07:48.913Z,1411520868.913 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:48.976Z,1411520868.976 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:48.980Z,1411520868.980 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:49.038Z,1411520869.038 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:49.204Z,1411520869.204 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-24T01:07:49.255Z,1411520869.255 [logger ThreadHandler](INFO): Thread cancelled.