2015-02-13T00:21:52.139Z,1423786912.139 [Supervisor](DEBUG): Initializing supervisor.
2015-02-13T00:21:52.142Z,1423786912.142 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-02-13T00:21:52.142Z,1423786912.142 [SyncHandler](INFO): Protected caller Thread ID is 942
2015-02-13T00:21:52.143Z,1423786912.143 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-02-13T00:21:52.148Z,1423786912.148 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-02-13T00:21:52.149Z,1423786912.149 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 943
2015-02-13T00:21:52.161Z,1423786912.161 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-02-13T00:21:52.176Z,1423786912.176 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-02-13T00:21:52.218Z,1423786912.218 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-02-13T00:21:52.218Z,1423786912.218 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 944
2015-02-13T00:21:52.219Z,1423786912.219 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-02-13T00:21:52.228Z,1423786912.228 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-02-13T00:21:52.229Z,1423786912.229 [logger ThreadHandler](INFO): Protected caller Thread ID is 946
2015-02-13T00:21:52.244Z,1423786912.244 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-02-13T00:21:52.252Z,1423786912.252 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-02-13T00:21:52.258Z,1423786912.258 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-02-13T00:21:52.875Z,1423786912.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-02-13T00:21:52.875Z,1423786912.875 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-02-13T00:21:53.043Z,1423786913.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-02-13T00:21:53.044Z,1423786913.044 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-02-13T00:21:53.152Z,1423786913.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-02-13T00:21:53.153Z,1423786913.153 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-02-13T00:21:53.275Z,1423786913.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-02-13T00:21:53.275Z,1423786913.275 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-02-13T00:21:53.361Z,1423786913.361 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-02-13T00:21:53.524Z,1423786913.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-02-13T00:21:53.525Z,1423786913.525 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-02-13T00:21:53.615Z,1423786913.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-02-13T00:21:53.616Z,1423786913.616 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-02-13T00:21:53.837Z,1423786913.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-02-13T00:21:53.838Z,1423786913.838 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-02-13T00:21:54.195Z,1423786914.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-02-13T00:21:54.196Z,1423786914.196 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-02-13T00:21:54.523Z,1423786914.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-02-13T00:21:54.523Z,1423786914.523 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-02-13T00:21:55.020Z,1423786915.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-02-13T00:21:55.020Z,1423786915.020 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-02-13T00:21:55.259Z,1423786915.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-02-13T00:21:55.260Z,1423786915.260 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-02-13T00:21:55.364Z,1423786915.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-02-13T00:21:55.364Z,1423786915.364 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-02-13T00:21:55.779Z,1423786915.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-02-13T00:21:55.780Z,1423786915.780 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-02-13T00:21:55.890Z,1423786915.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-02-13T00:21:55.892Z,1423786915.892 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2015-02-13T00:21:55.893Z,1423786915.893 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2015-02-13T00:21:56.166Z,1423786916.166 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-02-13T00:21:56.167Z,1423786916.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2015-02-13T00:21:56.282Z,1423786916.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2015-02-13T00:21:56.379Z,1423786916.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2015-02-13T00:21:56.475Z,1423786916.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2015-02-13T00:21:56.582Z,1423786916.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2015-02-13T00:21:56.723Z,1423786916.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2015-02-13T00:21:56.893Z,1423786916.893 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2015-02-13T00:21:57.020Z,1423786917.020 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2015-02-13T00:21:57.110Z,1423786917.110 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2015-02-13T00:21:57.210Z,1423786917.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2015-02-13T00:21:57.307Z,1423786917.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2015-02-13T00:21:57.490Z,1423786917.490 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2015-02-13T00:21:57.491Z,1423786917.491 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2015-02-13T00:21:57.900Z,1423786917.900 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-02-13T00:21:57.956Z,1423786917.956 [VerticalControl](DEBUG): Construct VerticalControl.
2015-02-13T00:21:58.209Z,1423786918.209 [VerticalControl] Loaded
2015-02-13T00:21:58.209Z,1423786918.209 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-02-13T00:21:58.210Z,1423786918.210 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-02-13T00:21:58.285Z,1423786918.285 [HorizontalControl] Loaded
2015-02-13T00:21:58.285Z,1423786918.285 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-02-13T00:21:58.286Z,1423786918.286 [SpeedControl](DEBUG): Construct SpeedControl.
2015-02-13T00:21:58.292Z,1423786918.292 [SpeedControl] Loaded
2015-02-13T00:21:58.292Z,1423786918.292 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-02-13T00:21:58.293Z,1423786918.293 [LoopControl](DEBUG): Construct LoopControl.
2015-02-13T00:21:58.294Z,1423786918.294 [LoopControl] Loaded
2015-02-13T00:21:58.294Z,1423786918.294 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-02-13T00:21:58.294Z,1423786918.294 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-02-13T00:21:58.295Z,1423786918.295 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-02-13T00:21:58.402Z,1423786918.402 [SBIT](DEBUG): Construct Startup Built In Test.
2015-02-13T00:21:58.413Z,1423786918.413 [SBIT] Loaded
2015-02-13T00:21:58.413Z,1423786918.413 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-02-13T00:21:58.414Z,1423786918.414 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-02-13T00:21:58.444Z,1423786918.444 [IBIT] Loaded
2015-02-13T00:21:58.445Z,1423786918.445 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-02-13T00:21:58.447Z,1423786918.447 [CBIT](DEBUG): Construct CBIT Built In Test.
2015-02-13T00:21:58.584Z,1423786918.584 [CBIT] Loaded
2015-02-13T00:21:58.584Z,1423786918.584 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-02-13T00:21:58.584Z,1423786918.584 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-02-13T00:21:58.585Z,1423786918.585 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-02-13T00:21:58.605Z,1423786918.605 [DepthRateCalculator] Loaded
2015-02-13T00:21:58.605Z,1423786918.605 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-02-13T00:21:58.611Z,1423786918.611 [PitchRateCalculator] Loaded
2015-02-13T00:21:58.611Z,1423786918.611 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-02-13T00:21:58.623Z,1423786918.623 [SpeedCalculator] Loaded
2015-02-13T00:21:58.623Z,1423786918.623 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-02-13T00:21:58.640Z,1423786918.640 [TempGradientCalculator] Loaded
2015-02-13T00:21:58.641Z,1423786918.641 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-02-13T00:21:58.646Z,1423786918.646 [YawRateCalculator] Loaded
2015-02-13T00:21:58.646Z,1423786918.646 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-02-13T00:21:58.647Z,1423786918.647 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-02-13T00:21:58.647Z,1423786918.647 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-02-13T00:22:09.564Z,1423786929.564 [HFRCMReconstructedInterpolator] Loaded
2015-02-13T00:22:09.564Z,1423786929.564 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2015-02-13T00:22:09.565Z,1423786929.565 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-02-13T00:22:09.565Z,1423786929.565 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-02-13T00:22:09.815Z,1423786929.815 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-02-13T00:22:09.815Z,1423786929.815 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-02-13T00:22:09.905Z,1423786929.905 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-02-13T00:22:09.906Z,1423786929.906 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-02-13T00:22:09.954Z,1423786929.954 [DeadReckonUsingSpeedCalculator] Loaded
2015-02-13T00:22:09.954Z,1423786929.954 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-02-13T00:22:09.973Z,1423786929.973 [NavChart] Loaded
2015-02-13T00:22:09.973Z,1423786929.973 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-02-13T00:22:09.977Z,1423786929.977 [UniversalFixResidualReporter] Loaded
2015-02-13T00:22:09.977Z,1423786929.977 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-02-13T00:22:09.978Z,1423786929.978 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-02-13T00:22:09.979Z,1423786929.979 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-02-13T00:22:09.985Z,1423786929.985 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-02-13T00:22:09.985Z,1423786929.985 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-02-13T00:22:10.084Z,1423786930.084 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-02-13T00:22:10.084Z,1423786930.084 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-02-13T00:22:10.410Z,1423786930.410 [DataOverHttps] Loaded
2015-02-13T00:22:10.410Z,1423786930.410 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-02-13T00:22:10.494Z,1423786930.494 [Depth_Keller] Loaded
2015-02-13T00:22:10.495Z,1423786930.495 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-02-13T00:22:10.594Z,1423786930.594 [NAL9602] Loaded
2015-02-13T00:22:10.594Z,1423786930.594 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-02-13T00:22:10.637Z,1423786930.637 [Onboard] Loaded
2015-02-13T00:22:10.638Z,1423786930.638 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-02-13T00:22:10.645Z,1423786930.645 [Radio_Surface] Loaded
2015-02-13T00:22:10.645Z,1423786930.645 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-02-13T00:22:10.647Z,1423786930.647 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408074E0
2015-02-13T00:22:10.647Z,1423786930.647 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1028
2015-02-13T00:22:10.699Z,1423786930.699 [PNI_TCM] Loaded
2015-02-13T00:22:10.699Z,1423786930.699 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2015-02-13T00:22:12.627Z,1423786932.627 [BPC1] Loaded
2015-02-13T00:22:12.628Z,1423786932.628 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-02-13T00:22:12.628Z,1423786932.628 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-02-13T00:22:12.629Z,1423786932.629 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-02-13T00:22:12.736Z,1423786932.736 [BuoyancyServo] Loaded
2015-02-13T00:22:12.736Z,1423786932.736 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-02-13T00:22:12.749Z,1423786932.749 [ElevatorServo] Loaded
2015-02-13T00:22:12.749Z,1423786932.749 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-02-13T00:22:12.761Z,1423786932.761 [MassServo] Loaded
2015-02-13T00:22:12.762Z,1423786932.762 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-02-13T00:22:12.774Z,1423786932.774 [RudderServo] Loaded
2015-02-13T00:22:12.774Z,1423786932.774 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-02-13T00:22:12.786Z,1423786932.786 [ThrusterServo] Loaded
2015-02-13T00:22:12.787Z,1423786932.787 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-02-13T00:22:12.787Z,1423786932.787 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-02-13T00:22:12.788Z,1423786932.788 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-02-13T00:22:13.038Z,1423786933.038 [ExternalSim] Loaded
2015-02-13T00:22:13.039Z,1423786933.039 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2015-02-13T00:22:13.039Z,1423786933.039 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-02-13T00:22:13.040Z,1423786933.040 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-02-13T00:22:13.065Z,1423786933.065 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-02-13T00:22:13.068Z,1423786933.068 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-02-13T00:22:13.069Z,1423786933.069 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-02-13T00:22:13.076Z,1423786933.076 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-02-13T00:22:13.077Z,1423786933.077 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408B84E0
2015-02-13T00:22:13.077Z,1423786933.077 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1029
2015-02-13T00:22:13.083Z,1423786933.083 [Supervisor](INFO): Main Thread ID is 941
2015-02-13T00:22:13.083Z,1423786933.083 [Supervisor](DEBUG): Running supervisor.
2015-02-13T00:22:13.083Z,1423786933.083 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1030
2015-02-13T00:22:13.088Z,1423786933.088 [controlThread ThreadHandler](INFO): Handler Thread ID is 1031
2015-02-13T00:22:13.088Z,1423786933.088 [controlThread](DEBUG): Initializing ControlThread
2015-02-13T00:22:13.089Z,1423786933.089 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-02-13T00:22:13.091Z,1423786933.091 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-02-13T00:22:13.092Z,1423786933.092 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-02-13T00:22:13.092Z,1423786933.092 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-02-13T00:22:13.092Z,1423786933.092 [SBIT](INFO): Initialize SBIT Component.
2015-02-13T00:22:13.093Z,1423786933.093 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12012
2015-02-13T00:22:13.093Z,1423786933.093 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-02-13T00:22:13.094Z,1423786933.094 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-02-13T00:22:13.094Z,1423786933.094 [IBIT](INFO): Initialize IBIT Component.
2015-02-13T00:22:13.095Z,1423786933.095 [CBIT](DEBUG): Initialize CBIT Component.
2015-02-13T00:22:13.095Z,1423786933.095 [CBIT](CRITICAL): Watchdog Timer failed to initialize.
2015-02-13T00:22:13.095Z,1423786933.095 [CBIT] Hardware Fault, FailCount= 1
2015-02-13T00:22:13.096Z,1423786933.096 [CBIT](ERROR): Hardware Fault
2015-02-13T00:22:13.097Z,1423786933.097 [logger ThreadHandler](INFO): Handler Thread ID is 1032
2015-02-13T00:22:13.115Z,1423786933.115 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1033
2015-02-13T00:22:13.121Z,1423786933.121 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-02-13T00:22:13.121Z,1423786933.121 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-02-13T00:22:13.122Z,1423786933.122 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-02-13T00:22:13.122Z,1423786933.122 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-02-13T00:22:13.123Z,1423786933.123 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-02-13T00:22:13.124Z,1423786933.124 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2015-02-13T00:22:13.125Z,1423786933.125 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-02-13T00:22:13.126Z,1423786933.126 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-02-13T00:22:13.126Z,1423786933.126 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-02-13T00:22:13.126Z,1423786933.126 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-02-13T00:22:13.132Z,1423786933.132 [ExternalSim](INFO): ExternalSim initializing...
2015-02-13T00:22:13.139Z,1423786933.139 [Radio_Surface](INFO): Powering up
2015-02-13T00:22:13.159Z,1423786933.159 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1034
2015-02-13T00:22:13.169Z,1423786933.169 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-02-13T00:22:13.170Z,1423786933.170 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-02-13T00:22:13.170Z,1423786933.170 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-02-13T00:22:13.170Z,1423786933.170 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-02-13T00:22:13.171Z,1423786933.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-02-13T00:22:13.171Z,1423786933.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-02-13T00:22:13.171Z,1423786933.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-02-13T00:22:13.171Z,1423786933.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-02-13T00:22:13.172Z,1423786933.172 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-02-13T00:22:13.448Z,1423786933.448 [ExternalSim](DEBUG): beaconLat = 36.80340
2015-02-13T00:22:13.448Z,1423786933.448 [ExternalSim](DEBUG): beaconLon = -121.82230
2015-02-13T00:22:13.448Z,1423786933.448 [ExternalSim](DEBUG): beaconDepth = 25.00
2015-02-13T00:22:14.609Z,1423786934.609 [ExternalSim](DEBUG): Simulator initialized
2015-02-13T00:22:14.873Z,1423786934.873 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-02-13T00:22:14.919Z,1423786934.919 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-02-13T00:22:14.941Z,1423786934.941 [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>


2015-02-13T00:22:14.941Z,1423786934.941 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-02-13T00:22:15.022Z,1423786935.022 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2015-02-13T00:22:15.042Z,1423786935.042 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2015-02-13T00:22:15.046Z,1423786935.046 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2015-02-13T00:22:15.064Z,1423786935.064 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2015-02-13T00:22:15.099Z,1423786935.099 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-02-13T00:22:15.119Z,1423786935.119 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2015-02-13T00:22:15.142Z,1423786935.142 [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>


2015-02-13T00:22:15.163Z,1423786935.163 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-02-13T00:22:15.569Z,1423786935.569 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:15.595Z,1423786935.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T00:22:15.735Z,1423786935.735 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-02-13T00:22:15.739Z,1423786935.739 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-02-13T00:22:15.761Z,1423786935.761 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-02-13T00:22:15.767Z,1423786935.767 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-02-13T00:22:15.789Z,1423786935.789 [MassServo](DEBUG): Initializing EZServoServo.
2015-02-13T00:22:15.795Z,1423786935.795 [MassServo](DEBUG): Initializing MassServo.
2015-02-13T00:22:15.801Z,1423786935.801 [RudderServo](DEBUG): Initializing EZServoServo.
2015-02-13T00:22:15.807Z,1423786935.807 [RudderServo](DEBUG): Initializing RudderServo.
2015-02-13T00:22:15.813Z,1423786935.813 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-02-13T00:22:15.823Z,1423786935.823 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-02-13T00:22:15.875Z,1423786935.875 [CBIT](DEBUG): Uninitialize CBIT Component.
2015-02-13T00:22:16.701Z,1423786936.701 [NAL9602](INFO): Powering up NAL9602
2015-02-13T00:22:16.755Z,1423786936.755 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:16.764Z,1423786936.764 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T00:22:17.121Z,1423786937.121 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2015-02-13T00:22:17.121Z,1423786937.121 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2015-02-13T00:22:17.121Z,1423786937.121 [BuoyancyServo] Communications Fault, FailCount= 1
2015-02-13T00:22:17.121Z,1423786937.121 [BuoyancyServo](ERROR): Communications Fault
2015-02-13T00:22:17.433Z,1423786937.433 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2015-02-13T00:22:17.433Z,1423786937.433 [ElevatorServo](FAULT): Elevator failed to initialize
2015-02-13T00:22:17.433Z,1423786937.433 [ElevatorServo] Communications Fault, FailCount= 1
2015-02-13T00:22:17.434Z,1423786937.434 [ElevatorServo](ERROR): Communications Fault
2015-02-13T00:22:17.738Z,1423786937.738 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2015-02-13T00:22:17.738Z,1423786937.738 [RudderServo](FAULT): Rudder failed to initialize
2015-02-13T00:22:17.738Z,1423786937.738 [RudderServo] Communications Fault, FailCount= 1
2015-02-13T00:22:17.738Z,1423786937.738 [RudderServo](ERROR): Communications Fault
2015-02-13T00:22:18.050Z,1423786938.050 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2015-02-13T00:22:18.363Z,1423786938.363 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2015-02-13T00:22:18.363Z,1423786938.363 [ThrusterServo](FAULT): Thruster failed to initialize
2015-02-13T00:22:18.363Z,1423786938.363 [ThrusterServo] Communications Fault, FailCount= 1
2015-02-13T00:22:18.363Z,1423786938.363 [ThrusterServo](ERROR): Communications Fault
2015-02-13T00:22:27.776Z,1423786947.776 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:27.777Z,1423786947.777 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T00:22:27.796Z,1423786947.796 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-02-13T00:22:27.796Z,1423786947.796 [BuoyancyServo](INFO): Powering down
2015-02-13T00:22:27.886Z,1423786947.886 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2015-02-13T00:22:27.886Z,1423786947.886 [ElevatorServo](INFO): Powering down
2015-02-13T00:22:28.262Z,1423786948.262 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2015-02-13T00:22:28.262Z,1423786948.262 [MassServo] Communications Fault, FailCount= 1
2015-02-13T00:22:28.262Z,1423786948.262 [MassServo](ERROR): Communications Fault
2015-02-13T00:22:28.263Z,1423786948.263 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2015-02-13T00:22:28.263Z,1423786948.263 [RudderServo](INFO): Powering down
2015-02-13T00:22:28.294Z,1423786948.294 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2015-02-13T00:22:28.294Z,1423786948.294 [ThrusterServo](INFO): Powering down
2015-02-13T00:22:28.613Z,1423786948.613 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:28.623Z,1423786948.623 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T00:22:28.647Z,1423786948.647 [MassServo](DEBUG): Uninitialize Mass Servo.
2015-02-13T00:22:28.647Z,1423786948.647 [MassServo](INFO): Powering down
2015-02-13T00:22:28.717Z,1423786948.717 [SBIT](IMPORTANT): Beginning Startup BIT
2015-02-13T00:22:28.883Z,1423786948.883 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:28.884Z,1423786948.884 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T00:22:28.909Z,1423786948.909 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-02-13T00:22:29.028Z,1423786949.028 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-02-13T00:22:29.030Z,1423786949.030 [RudderServo](DEBUG): Initializing EZServoServo.
2015-02-13T00:22:29.148Z,1423786949.148 [RudderServo](DEBUG): Initializing RudderServo.
2015-02-13T00:22:29.476Z,1423786949.476 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.121993
2015-02-13T00:22:29.600Z,1423786949.600 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:29.601Z,1423786949.601 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-02-13T00:22:29.609Z,1423786949.609 [MassServo](DEBUG): Initializing EZServoServo.
2015-02-13T00:22:29.729Z,1423786949.729 [MassServo](DEBUG): Initializing MassServo.
2015-02-13T00:22:29.873Z,1423786949.873 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:30.293Z,1423786950.293 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:30.737Z,1423786950.737 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:31.083Z,1423786951.083 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:31.547Z,1423786951.547 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:31.916Z,1423786951.916 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:32.277Z,1423786952.277 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:32.712Z,1423786952.712 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:33.113Z,1423786953.113 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:33.478Z,1423786953.478 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:34.091Z,1423786954.091 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:34.371Z,1423786954.371 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:34.704Z,1423786954.704 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:35.096Z,1423786955.096 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:35.741Z,1423786955.741 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:35.902Z,1423786955.902 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:36.302Z,1423786956.302 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:36.707Z,1423786956.707 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:37.130Z,1423786957.130 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:37.488Z,1423786957.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:37.889Z,1423786957.889 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:38.285Z,1423786958.285 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:38.652Z,1423786958.652 [NAL9602](ERROR): NAL9602 initialization error.
2015-02-13T00:22:38.652Z,1423786958.652 [NAL9602] Communications Fault, FailCount= 1
2015-02-13T00:22:38.652Z,1423786958.652 [NAL9602](ERROR): Communications Fault
2015-02-13T00:22:38.794Z,1423786958.794 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:39.056Z,1423786959.056 [NAL9602](INFO): Powering down
2015-02-13T00:22:39.166Z,1423786959.166 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:39.527Z,1423786959.527 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:39.908Z,1423786959.908 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:40.312Z,1423786960.312 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:40.745Z,1423786960.745 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:41.160Z,1423786961.160 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:41.814Z,1423786961.814 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:41.842Z,1423786961.842 [SBIT](ERROR): Could not read elevatorAngleReader_.
2015-02-13T00:22:41.842Z,1423786961.842 [SBIT](ERROR): Could not read rudderAngleReader_.
2015-02-13T00:22:41.842Z,1423786961.842 [SBIT](ERROR): Could not read massPosReader_.
2015-02-13T00:22:42.053Z,1423786962.053 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:42.419Z,1423786962.419 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:42.828Z,1423786962.828 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:43.453Z,1423786963.453 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:43.677Z,1423786963.677 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:44.074Z,1423786964.074 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:44.458Z,1423786964.458 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:45.059Z,1423786965.059 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:45.296Z,1423786965.296 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:45.703Z,1423786965.703 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:46.190Z,1423786966.190 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:46.582Z,1423786966.582 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:46.912Z,1423786966.912 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:47.441Z,1423786967.441 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:47.892Z,1423786967.892 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:48.345Z,1423786968.345 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:48.830Z,1423786968.830 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:49.142Z,1423786969.142 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:49.474Z,1423786969.474 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:49.937Z,1423786969.937 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:50.289Z,1423786970.289 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:50.692Z,1423786970.692 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:51.077Z,1423786971.077 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:51.493Z,1423786971.493 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:52.082Z,1423786972.082 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:52.299Z,1423786972.299 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:52.696Z,1423786972.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:53.097Z,1423786973.097 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:53.453Z,1423786973.453 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:53.919Z,1423786973.919 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:54.328Z,1423786974.328 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:54.654Z,1423786974.654 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:55.109Z,1423786975.109 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:55.464Z,1423786975.464 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:55.909Z,1423786975.909 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:56.346Z,1423786976.346 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:56.677Z,1423786976.677 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:56.732Z,1423786976.732 [CommandLine](IMPORTANT): got command failComponent
2015-02-13T00:22:56.732Z,1423786976.732 [CommandLine](IMPORTANT): Failed components:
2015-02-13T00:22:56.733Z,1423786976.733 [CommandLine](IMPORTANT): CBIT: Hardware Fault
2015-02-13T00:22:56.733Z,1423786976.733 [CommandLine](IMPORTANT): NAL9602: Communications Fault
2015-02-13T00:22:56.733Z,1423786976.733 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault
2015-02-13T00:22:56.734Z,1423786976.734 [CommandLine](IMPORTANT): ElevatorServo: Communications Fault
2015-02-13T00:22:56.734Z,1423786976.734 [CommandLine](IMPORTANT): MassServo: Communications Fault
2015-02-13T00:22:56.734Z,1423786976.734 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2015-02-13T00:22:56.734Z,1423786976.734 [CommandLine](IMPORTANT): ThrusterServo: Communications Fault
2015-02-13T00:22:57.222Z,1423786977.222 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:57.522Z,1423786977.522 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:57.955Z,1423786977.955 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:58.350Z,1423786978.350 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:58.696Z,1423786978.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:59.149Z,1423786979.149 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:59.524Z,1423786979.524 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:22:59.913Z,1423786979.913 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:00.329Z,1423786980.329 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:00.700Z,1423786980.700 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:01.089Z,1423786981.089 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:01.494Z,1423786981.494 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:01.889Z,1423786981.889 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:02.327Z,1423786982.327 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:02.703Z,1423786982.703 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:02.845Z,1423786982.845 [CommandLine](IMPORTANT): got command restart application
2015-02-13T00:23:03.100Z,1423786983.100 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:03.503Z,1423786983.503 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:03.852Z,1423786983.852 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2015-02-13T00:23:03.853Z,1423786983.853 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:03.901Z,1423786983.901 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2015-02-13T00:23:03.902Z,1423786983.902 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:03.902Z,1423786983.902 [CommandLine](INFO): Join timeout helper Thread ID is 1048
2015-02-13T00:23:03.910Z,1423786983.910 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:03.916Z,1423786983.916 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2015-02-13T00:23:03.916Z,1423786983.916 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:03.916Z,1423786983.916 [NavChartDb](INFO): Join timeout helper Thread ID is 1049
2015-02-13T00:23:04.314Z,1423786984.314 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:04.710Z,1423786984.710 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:05.028Z,1423786985.028 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2015-02-13T00:23:05.028Z,1423786985.028 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:05.051Z,1423786985.051 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2015-02-13T00:23:05.051Z,1423786985.051 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:05.052Z,1423786985.052 [Radio_Surface](INFO): Join timeout helper Thread ID is 1050
2015-02-13T00:23:05.096Z,1423786985.096 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-02-13T00:23:05.264Z,1423786985.264 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2015-02-13T00:23:05.264Z,1423786985.264 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:05.276Z,1423786985.276 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2015-02-13T00:23:05.276Z,1423786985.276 [logger ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:05.276Z,1423786985.276 [logger](INFO): Join timeout helper Thread ID is 1051
2015-02-13T00:23:05.284Z,1423786985.284 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2015-02-13T00:23:05.284Z,1423786985.284 [logger ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:05.296Z,1423786985.296 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2015-02-13T00:23:05.296Z,1423786985.296 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:05.296Z,1423786985.296 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2015-02-13T00:23:05.296Z,1423786985.296 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:05.296Z,1423786985.296 [controlThread](INFO): Join timeout helper Thread ID is 1052
2015-02-13T00:23:05.328Z,1423786985.328 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2015-02-13T00:23:05.328Z,1423786985.328 [controlThread](DEBUG): Uninitializing ControlThread
2015-02-13T00:23:05.329Z,1423786985.329 [NAL9602](INFO): Powering down
2015-02-13T00:23:05.332Z,1423786985.332 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-02-13T00:23:05.333Z,1423786985.333 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2015-02-13T00:23:05.337Z,1423786985.337 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2015-02-13T00:23:05.337Z,1423786985.337 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2015-02-13T00:23:05.338Z,1423786985.338 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2015-02-13T00:23:05.338Z,1423786985.338 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2015-02-13T00:23:05.338Z,1423786985.338 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-02-13T00:23:05.338Z,1423786985.338 [BuoyancyServo](INFO): Powering down
2015-02-13T00:23:05.352Z,1423786985.352 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2015-02-13T00:23:05.352Z,1423786985.352 [ElevatorServo](INFO): Powering down
2015-02-13T00:23:05.353Z,1423786985.353 [MassServo](DEBUG): Uninitialize Mass Servo.
2015-02-13T00:23:05.353Z,1423786985.353 [MassServo](INFO): Powering down
2015-02-13T00:23:05.354Z,1423786985.354 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2015-02-13T00:23:05.354Z,1423786985.354 [RudderServo](INFO): Powering down
2015-02-13T00:23:05.355Z,1423786985.355 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2015-02-13T00:23:05.355Z,1423786985.355 [ThrusterServo](INFO): Powering down
2015-02-13T00:23:05.356Z,1423786985.356 [SBIT](DEBUG): Uninitialize SBIT Component.
2015-02-13T00:23:05.356Z,1423786985.356 [IBIT](DEBUG): Uninitialize IBIT Component.
2015-02-13T00:23:05.356Z,1423786985.356 [CBIT](DEBUG): Uninitialize CBIT Component.
2015-02-13T00:23:05.402Z,1423786985.402 [CBIT](CRITICAL): run-time exception #7
2015-02-13T00:23:05.403Z,1423786985.402 [CBIT](CRITICAL): Bad address: 0x40319004
2015-02-13T00:23:05.403Z,1423786985.403 [CBIT](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x16f3dc]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]

2015-02-13T00:23:05.403Z,1423786985.403 [CBIT] Software Fault, FailCount= 1
2015-02-13T00:23:05.403Z,1423786985.403 [CBIT](ERROR): Software Fault
2015-02-13T00:23:05.405Z,1423786985.405 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-02-13T00:23:05.405Z,1423786985.405 [SyncHandler](INFO): Protected caller Thread ID is 1053
2015-02-13T00:23:05.406Z,1423786985.406 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:05.524Z,1423786985.524 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:06.047Z,1423786986.047 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-02-13T00:23:06.088Z,1423786986.088 [logger ThreadHandler](INFO): Thread cancelled.