2015-06-08T21:51:20.891Z,1433800280.891 [Supervisor](DEBUG): Initializing supervisor.
2015-06-08T21:51:20.895Z,1433800280.895 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-06-08T21:51:20.896Z,1433800280.896 [SyncHandler](INFO): Protected caller Thread ID is 765
2015-06-08T21:51:20.896Z,1433800280.896 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-06-08T21:51:20.899Z,1433800280.899 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-06-08T21:51:20.900Z,1433800280.900 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 766
2015-06-08T21:51:20.902Z,1433800280.902 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-06-08T21:51:20.915Z,1433800280.915 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-06-08T21:51:20.919Z,1433800280.919 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-06-08T21:51:20.920Z,1433800280.920 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 767
2015-06-08T21:51:20.921Z,1433800280.921 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-06-08T21:51:20.923Z,1433800280.923 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-06-08T21:51:20.924Z,1433800280.924 [logger ThreadHandler](INFO): Protected caller Thread ID is 768
2015-06-08T21:51:20.926Z,1433800280.926 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-06-08T21:51:20.926Z,1433800280.926 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-06-08T21:51:20.930Z,1433800280.930 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-06-08T21:51:21.321Z,1433800281.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-06-08T21:51:21.323Z,1433800281.323 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-06-08T21:51:21.512Z,1433800281.512 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-06-08T21:51:21.513Z,1433800281.513 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-06-08T21:51:21.632Z,1433800281.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-06-08T21:51:21.633Z,1433800281.633 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-06-08T21:51:21.774Z,1433800281.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-06-08T21:51:21.774Z,1433800281.774 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-06-08T21:51:21.874Z,1433800281.874 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-06-08T21:51:22.094Z,1433800282.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-06-08T21:51:22.095Z,1433800282.095 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-06-08T21:51:22.190Z,1433800282.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-06-08T21:51:22.191Z,1433800282.191 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-06-08T21:51:22.447Z,1433800282.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-06-08T21:51:22.447Z,1433800282.447 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-06-08T21:51:22.865Z,1433800282.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-06-08T21:51:22.865Z,1433800282.865 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-06-08T21:51:23.220Z,1433800283.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-06-08T21:51:23.221Z,1433800283.221 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-06-08T21:51:23.793Z,1433800283.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-06-08T21:51:23.794Z,1433800283.794 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-06-08T21:51:24.021Z,1433800284.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-06-08T21:51:24.022Z,1433800284.022 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-06-08T21:51:24.142Z,1433800284.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-06-08T21:51:24.142Z,1433800284.142 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-06-08T21:51:24.633Z,1433800284.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-06-08T21:51:24.634Z,1433800284.634 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-06-08T21:51:24.813Z,1433800284.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-06-08T21:51:24.815Z,1433800284.815 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2015-06-08T21:51:24.818Z,1433800284.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2015-06-08T21:51:25.087Z,1433800285.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-06-08T21:51:25.089Z,1433800285.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2015-06-08T21:51:25.223Z,1433800285.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2015-06-08T21:51:25.332Z,1433800285.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2015-06-08T21:51:25.440Z,1433800285.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2015-06-08T21:51:25.548Z,1433800285.548 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2015-06-08T21:51:25.716Z,1433800285.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2015-06-08T21:51:25.904Z,1433800285.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2015-06-08T21:51:26.017Z,1433800286.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2015-06-08T21:51:26.117Z,1433800286.117 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2015-06-08T21:51:26.211Z,1433800286.211 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2015-06-08T21:51:26.321Z,1433800286.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2015-06-08T21:51:26.524Z,1433800286.524 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2015-06-08T21:51:26.527Z,1433800286.527 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-06-08T21:51:26.601Z,1433800286.601 [VerticalControl](DEBUG): Construct VerticalControl.
2015-06-08T21:51:26.734Z,1433800286.734 [VerticalControl] Loaded
2015-06-08T21:51:26.734Z,1433800286.734 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-06-08T21:51:26.735Z,1433800286.735 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-06-08T21:51:26.814Z,1433800286.814 [HorizontalControl] Loaded
2015-06-08T21:51:26.815Z,1433800286.815 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-06-08T21:51:26.815Z,1433800286.815 [SpeedControl](DEBUG): Construct SpeedControl.
2015-06-08T21:51:26.821Z,1433800286.821 [SpeedControl] Loaded
2015-06-08T21:51:26.821Z,1433800286.821 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-06-08T21:51:26.822Z,1433800286.822 [LoopControl](DEBUG): Construct LoopControl.
2015-06-08T21:51:26.823Z,1433800286.823 [LoopControl] Loaded
2015-06-08T21:51:26.823Z,1433800286.823 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-06-08T21:51:26.823Z,1433800286.823 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-06-08T21:51:26.824Z,1433800286.824 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-06-08T21:51:26.981Z,1433800286.981 [SBIT](DEBUG): Construct Startup Built In Test.
2015-06-08T21:51:26.997Z,1433800286.997 [SBIT] Loaded
2015-06-08T21:51:26.997Z,1433800286.997 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-06-08T21:51:26.998Z,1433800286.998 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-06-08T21:51:27.028Z,1433800287.028 [IBIT] Loaded
2015-06-08T21:51:27.028Z,1433800287.028 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-06-08T21:51:27.030Z,1433800287.030 [CBIT](DEBUG): Construct Continuous Built In Test.
2015-06-08T21:51:27.184Z,1433800287.184 [CBIT] Loaded
2015-06-08T21:51:27.185Z,1433800287.185 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-06-08T21:51:27.185Z,1433800287.185 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-06-08T21:51:27.186Z,1433800287.186 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-06-08T21:51:27.221Z,1433800287.221 [DepthRateCalculator] Loaded
2015-06-08T21:51:27.222Z,1433800287.222 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-06-08T21:51:27.227Z,1433800287.227 [PitchRateCalculator] Loaded
2015-06-08T21:51:27.227Z,1433800287.227 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-06-08T21:51:27.239Z,1433800287.239 [SpeedCalculator] Loaded
2015-06-08T21:51:27.239Z,1433800287.239 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-06-08T21:51:27.256Z,1433800287.256 [TempGradientCalculator] Loaded
2015-06-08T21:51:27.256Z,1433800287.256 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-06-08T21:51:27.265Z,1433800287.265 [YawRateCalculator] Loaded
2015-06-08T21:51:27.266Z,1433800287.266 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-06-08T21:51:27.266Z,1433800287.266 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-06-08T21:51:27.267Z,1433800287.267 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-06-08T21:51:31.744Z,1433800291.744 [HFRadarCompactModelForecaster] Loaded
2015-06-08T21:51:31.744Z,1433800291.744 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2015-06-08T21:51:32.846Z,1433800292.846 [HFRCMSpaceInterpolator] Loaded
2015-06-08T21:51:32.846Z,1433800292.846 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2015-06-08T21:51:32.849Z,1433800292.849 [HFRCMTimeInterpolator] Loaded
2015-06-08T21:51:32.849Z,1433800292.849 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2015-06-08T21:51:34.002Z,1433800294.002 [HFRCMReconstructedInterpolator] Loaded
2015-06-08T21:51:34.002Z,1433800294.002 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2015-06-08T21:51:34.014Z,1433800294.014 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2015-06-08T21:51:34.014Z,1433800294.014 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2015-06-08T21:51:34.047Z,1433800294.047 [HFRCMVirtualSurfaceDrifter] Loaded
2015-06-08T21:51:34.047Z,1433800294.047 [ComponentRegistry](DEBUG): SyncComponent "HFRCMVirtualSurfaceDrifter" handled in the control thread.
2015-06-08T21:51:34.047Z,1433800294.047 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-06-08T21:51:34.048Z,1433800294.048 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-06-08T21:51:34.340Z,1433800294.340 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-06-08T21:51:34.341Z,1433800294.341 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-06-08T21:51:34.471Z,1433800294.471 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-06-08T21:51:34.472Z,1433800294.472 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-06-08T21:51:34.536Z,1433800294.536 [DeadReckonUsingSpeedCalculator] Loaded
2015-06-08T21:51:34.537Z,1433800294.537 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-06-08T21:51:34.554Z,1433800294.554 [NavChart] Loaded
2015-06-08T21:51:34.554Z,1433800294.554 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-06-08T21:51:34.559Z,1433800294.559 [UniversalFixResidualReporter] Loaded
2015-06-08T21:51:34.559Z,1433800294.559 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-06-08T21:51:34.559Z,1433800294.559 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-06-08T21:51:34.560Z,1433800294.560 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-06-08T21:51:34.569Z,1433800294.569 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-06-08T21:51:34.569Z,1433800294.569 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-06-08T21:51:34.782Z,1433800294.782 [CANONSampler] Loaded
2015-06-08T21:51:34.782Z,1433800294.782 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2015-06-08T21:51:34.868Z,1433800294.868 [CTD_NeilBrown] Loaded
2015-06-08T21:51:34.868Z,1433800294.868 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2015-06-08T21:51:34.873Z,1433800294.873 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406EF4E0
2015-06-08T21:51:34.873Z,1433800294.873 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 850
2015-06-08T21:51:34.889Z,1433800294.889 [PAR_Licor] Loaded
2015-06-08T21:51:34.889Z,1433800294.889 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2015-06-08T21:51:34.946Z,1433800294.946 [WetLabsBB2FL] Loaded
2015-06-08T21:51:34.946Z,1433800294.946 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2015-06-08T21:51:34.949Z,1433800294.949 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4071F4E0
2015-06-08T21:51:34.950Z,1433800294.950 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 851
2015-06-08T21:51:34.950Z,1433800294.950 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-06-08T21:51:34.951Z,1433800294.951 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-06-08T21:51:35.476Z,1433800295.476 [AcousticModem_Benthos_ATM900] Loaded
2015-06-08T21:51:35.477Z,1433800295.477 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2015-06-08T21:51:35.662Z,1433800295.662 [DataOverHttps] Loaded
2015-06-08T21:51:35.662Z,1433800295.662 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-06-08T21:51:35.676Z,1433800295.676 [Depth_Keller] Loaded
2015-06-08T21:51:35.676Z,1433800295.676 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-06-08T21:51:35.685Z,1433800295.685 [DropWeight] Loaded
2015-06-08T21:51:35.686Z,1433800295.686 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2015-06-08T21:51:35.787Z,1433800295.787 [NAL9602] Loaded
2015-06-08T21:51:35.787Z,1433800295.787 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-06-08T21:51:35.834Z,1433800295.834 [Onboard] Loaded
2015-06-08T21:51:35.835Z,1433800295.835 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-06-08T21:51:35.842Z,1433800295.842 [Radio_Surface] Loaded
2015-06-08T21:51:35.842Z,1433800295.842 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-06-08T21:51:35.847Z,1433800295.847 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409634E0
2015-06-08T21:51:35.847Z,1433800295.847 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 852
2015-06-08T21:51:36.064Z,1433800296.064 [PNI_TCM] Loaded
2015-06-08T21:51:36.065Z,1433800296.065 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2015-06-08T21:51:38.146Z,1433800298.146 [BPC1] Loaded
2015-06-08T21:51:38.146Z,1433800298.146 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-06-08T21:51:38.147Z,1433800298.147 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-06-08T21:51:38.147Z,1433800298.147 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-06-08T21:51:38.278Z,1433800298.278 [BuoyancyServo] Loaded
2015-06-08T21:51:38.279Z,1433800298.279 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-06-08T21:51:38.291Z,1433800298.291 [ElevatorServo] Loaded
2015-06-08T21:51:38.291Z,1433800298.291 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-06-08T21:51:38.303Z,1433800298.303 [MassServo] Loaded
2015-06-08T21:51:38.304Z,1433800298.304 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-06-08T21:51:38.316Z,1433800298.316 [RudderServo] Loaded
2015-06-08T21:51:38.317Z,1433800298.317 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-06-08T21:51:38.329Z,1433800298.329 [ThrusterServo] Loaded
2015-06-08T21:51:38.329Z,1433800298.329 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-06-08T21:51:38.329Z,1433800298.329 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-06-08T21:51:38.330Z,1433800298.330 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-06-08T21:51:38.476Z,1433800298.476 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-06-08T21:51:38.476Z,1433800298.476 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-06-08T21:51:38.519Z,1433800298.519 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-06-08T21:51:38.523Z,1433800298.523 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-06-08T21:51:38.524Z,1433800298.524 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-06-08T21:51:38.531Z,1433800298.531 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-06-08T21:51:38.535Z,1433800298.535 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A114E0
2015-06-08T21:51:38.535Z,1433800298.535 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 853
2015-06-08T21:51:38.540Z,1433800298.540 [Supervisor](INFO): Main Thread ID is 764
2015-06-08T21:51:38.540Z,1433800298.540 [Supervisor](DEBUG): Running supervisor.
2015-06-08T21:51:38.541Z,1433800298.541 [CommandLine ThreadHandler](INFO): Handler Thread ID is 854
2015-06-08T21:51:38.547Z,1433800298.547 [controlThread ThreadHandler](INFO): Handler Thread ID is 855
2015-06-08T21:51:38.548Z,1433800298.548 [controlThread](DEBUG): Initializing ControlThread
2015-06-08T21:51:38.549Z,1433800298.549 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-06-08T21:51:38.550Z,1433800298.550 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-06-08T21:51:38.551Z,1433800298.551 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-06-08T21:51:38.552Z,1433800298.552 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-06-08T21:51:38.552Z,1433800298.552 [SBIT](INFO): Initialize SBIT Component.
2015-06-08T21:51:38.553Z,1433800298.553 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12296
2015-06-08T21:51:38.553Z,1433800298.553 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-06-08T21:51:38.553Z,1433800298.553 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-06-08T21:51:38.554Z,1433800298.554 [IBIT](INFO): Initialize IBIT Component.
2015-06-08T21:51:38.555Z,1433800298.555 [CBIT](DEBUG): Initialize CBIT Component.
2015-06-08T21:51:38.555Z,1433800298.555 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2015-06-08T21:51:38.555Z,1433800298.555 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-06-08T21:51:38.559Z,1433800298.559 [logger ThreadHandler](INFO): Handler Thread ID is 856
2015-06-08T21:51:38.579Z,1433800298.579 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-06-08T21:51:38.579Z,1433800298.579 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-06-08T21:51:38.580Z,1433800298.580 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-06-08T21:51:38.580Z,1433800298.580 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-06-08T21:51:38.581Z,1433800298.581 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-06-08T21:51:38.616Z,1433800298.616 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 857
2015-06-08T21:51:38.616Z,1433800298.616 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2015-06-08T21:51:38.622Z,1433800298.622 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 859
2015-06-08T21:51:38.624Z,1433800298.624 [WetLabsBB2FL](INFO): Powering down
2015-06-08T21:51:38.644Z,1433800298.644 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2015-06-08T21:51:38.664Z,1433800298.664 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 860
2015-06-08T21:51:38.670Z,1433800298.670 [Radio_Surface](INFO): Powering up
2015-06-08T21:51:38.676Z,1433800298.676 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2015-06-08T21:51:38.677Z,1433800298.677 [HFRadarCompactModelForecaster](ERROR): Most recent ECs are more than six hours old.
2015-06-08T21:51:38.684Z,1433800298.684 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 861
2015-06-08T21:51:38.690Z,1433800298.690 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-06-08T21:51:38.691Z,1433800298.691 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-06-08T21:51:38.692Z,1433800298.692 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-06-08T21:51:38.692Z,1433800298.692 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-06-08T21:51:38.692Z,1433800298.692 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-06-08T21:51:38.692Z,1433800298.692 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-06-08T21:51:38.693Z,1433800298.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-06-08T21:51:38.693Z,1433800298.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-06-08T21:51:38.693Z,1433800298.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-06-08T21:51:39.068Z,1433800299.068 [CTD_NeilBrown](DEBUG): CTD uart timeout: serial timeout
2015-06-08T21:51:39.068Z,1433800299.068 [CTD_NeilBrown](ERROR): Could not get real or simulated CTD data. startTime.elapsed() = 0.423761
2015-06-08T21:51:47.792Z,1433800307.792 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201505192300 published
2015-06-08T21:51:47.815Z,1433800307.815 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 0.
2015-06-08T21:51:47.817Z,1433800307.817 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2015-06-08T21:51:47.826Z,1433800307.826 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2015-06-08T21:51:47.827Z,1433800307.827 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2015-06-08T21:51:47.828Z,1433800307.828 [HFRCMVirtualSurfaceDrifter](DEBUG): Initializing HFRCMVirtualSurfaceDrifter component.
2015-06-08T21:51:47.828Z,1433800307.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-06-08T21:51:47.829Z,1433800307.829 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-06-08T21:51:47.829Z,1433800307.829 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-06-08T21:51:47.837Z,1433800307.837 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-06-08T21:51:47.838Z,1433800307.838 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-06-08T21:51:47.838Z,1433800307.838 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-06-08T21:51:47.839Z,1433800307.839 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-06-08T21:51:47.839Z,1433800307.839 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-06-08T21:51:47.847Z,1433800307.847 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2015-06-08T21:51:47.847Z,1433800307.847 [DropWeight] Hardware Fault, FailCount= 1
2015-06-08T21:51:47.847Z,1433800307.847 [DropWeight](ERROR): Hardware Fault
2015-06-08T21:51:47.851Z,1433800307.851 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-06-08T21:51:47.891Z,1433800307.891 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-06-08T21:51:47.921Z,1433800307.921 [MissionManager](DEBUG):
2015-06-08T21:51:47.930Z,1433800307.930 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-06-08T21:51:48.019Z,1433800308.019 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2015-06-08T21:51:48.020Z,1433800308.020 [Default:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-06-08T21:51:48.077Z,1433800308.077 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2015-06-08T21:51:48.118Z,1433800308.118 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-06-08T21:51:48.121Z,1433800308.121 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2015-06-08T21:51:48.151Z,1433800308.151 [MissionManager](DEBUG):
0
5.0
1.0
400
10
500
Default mission has been running for
Burn 300
Dropped weight due to communications timeout.
5
2015-06-08T21:51:48.167Z,1433800308.167 [controlThread](DEBUG): Component order: CycleStarter,CANONSampler,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-06-08T21:51:48.184Z,1433800308.184 [CANONSampler](INFO): Powering down
2015-06-08T21:51:48.210Z,1433800308.210 [AcousticModem_Benthos_ATM900](INFO): Powering up
2015-06-08T21:51:48.210Z,1433800308.210 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2015-06-08T21:51:48.436Z,1433800308.436 [NAL9602](INFO): Powering up NAL9602
2015-06-08T21:51:58.346Z,1433800318.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:51:58.670Z,1433800318.670 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:51:58.815Z,1433800318.815 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-06-08T21:51:58.823Z,1433800318.823 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-06-08T21:51:58.846Z,1433800318.846 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-06-08T21:51:58.867Z,1433800318.867 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-06-08T21:51:58.873Z,1433800318.873 [MassServo](DEBUG): Initializing EZServoServo.
2015-06-08T21:51:58.879Z,1433800318.879 [MassServo](DEBUG): Initializing MassServo.
2015-06-08T21:51:58.886Z,1433800318.886 [RudderServo](DEBUG): Initializing EZServoServo.
2015-06-08T21:51:58.915Z,1433800318.915 [RudderServo](DEBUG): Initializing RudderServo.
2015-06-08T21:51:58.928Z,1433800318.928 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-06-08T21:51:58.935Z,1433800318.935 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-06-08T21:51:58.971Z,1433800318.971 [CommandLine](FAULT): Scheduling is paused
2015-06-08T21:51:58.973Z,1433800318.973 [CBIT](ERROR): Hardware Fault in component: DropWeight
2015-06-08T21:51:58.974Z,1433800318.974 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2015-06-08T21:51:59.073Z,1433800319.073 [AcousticModem_Benthos_ATM900](DEBUG):
2015-06-08T21:51:59.075Z,1433800319.075 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2015-06-08T21:51:59.083Z,1433800319.083 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2015-06-08T21:51:59.084Z,1433800319.084 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2015-06-08T21:51:59.085Z,1433800319.085 [AcousticModem_Benthos_ATM900](DEBUG): Jun 8 2015 21:55:12
2015-06-08T21:51:59.190Z,1433800319.190 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:51:59.195Z,1433800319.195 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:51:59.228Z,1433800319.228 [SBIT](IMPORTANT): Beginning Startup BIT
2015-06-08T21:51:59.231Z,1433800319.231 [CBIT](IMPORTANT): Beginning GF scan
2015-06-08T21:51:59.480Z,1433800319.480 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:51:59.481Z,1433800319.481 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:51:59.836Z,1433800319.836 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2015-06-08T21:51:59.836Z,1433800319.836 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2015-06-08T21:51:59.836Z,1433800319.836 [BuoyancyServo] Communications Fault, FailCount= 1
2015-06-08T21:51:59.836Z,1433800319.836 [BuoyancyServo](ERROR): Communications Fault
2015-06-08T21:52:00.043Z,1433800320.043 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2015-06-08T21:52:00.054Z,1433800320.054 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2015-06-08T21:52:00.092Z,1433800320.092 [NAL9602](INFO): NAL9602 initialized
2015-06-08T21:52:00.164Z,1433800320.164 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:52:00.165Z,1433800320.165 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:52:00.197Z,1433800320.197 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-06-08T21:52:00.198Z,1433800320.198 [BuoyancyServo](INFO): Powering down
2015-06-08T21:52:00.458Z,1433800320.458 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-06-08T21:52:00.459Z,1433800320.459 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2015-06-08T21:52:00.459Z,1433800320.459 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2015-06-08T21:52:00.514Z,1433800320.514 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:52:00.515Z,1433800320.515 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:52:00.854Z,1433800320.854 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2015-06-08T21:52:00.936Z,1433800320.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:52:00.937Z,1433800320.937 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:52:01.255Z,1433800321.255 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2015-06-08T21:52:01.256Z,1433800321.256 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2015-06-08T21:52:01.366Z,1433800321.366 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:52:01.367Z,1433800321.367 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:52:01.659Z,1433800321.659 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2015-06-08T21:52:01.777Z,1433800321.777 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:52:01.778Z,1433800321.778 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-06-08T21:52:02.396Z,1433800322.396 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2015-06-08T21:52:02.396Z,1433800322.396 [BuoyancyServo] No Fault, FailCount= 1
2015-06-08T21:52:02.604Z,1433800322.604 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-06-08T21:52:02.745Z,1433800322.745 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-06-08T21:52:12.377Z,1433800332.377 [SBIT](ERROR): Could not read massPosReader_.
2015-06-08T21:52:14.542Z,1433800334.542 [BPC1](ERROR): Failed to receive battery data
2015-06-08T21:52:14.542Z,1433800334.542 [BPC1] Communications Fault, FailCount= 1
2015-06-08T21:52:14.547Z,1433800334.547 [BPC1](ERROR): Communications Fault
2015-06-08T21:52:14.719Z,1433800334.719 [CBIT](ERROR): Communications Fault in component: BPC1
2015-06-08T21:52:15.849Z,1433800335.849 [CBIT](INFO): Clearing failed state for component BPC1
2015-06-08T21:52:15.849Z,1433800335.849 [BPC1] No Fault, FailCount= 1
2015-06-08T21:52:25.801Z,1433800345.801 [CBIT](IMPORTANT): No ground fault detected
2015-06-08T21:52:53.157Z,1433800373.157 [SBIT](CRITICAL): SBIT FAILED
2015-06-08T21:52:53.527Z,1433800373.527 [MissionManager](IMPORTANT): Started mission Startup
2015-06-08T21:52:53.527Z,1433800373.527 [Startup] Running Loop=1
2015-06-08T21:52:53.527Z,1433800373.527 [Startup](INFO): Aggregate::initialize Startup
2015-06-08T21:52:53.527Z,1433800373.527 [Startup:A.GoToSurface] Running Loop=1
2015-06-08T21:52:53.528Z,1433800373.528 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-06-08T21:52:53.528Z,1433800373.528 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-06-08T21:52:53.528Z,1433800373.528 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-06-08T21:52:53.529Z,1433800373.529 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-06-08T21:52:53.535Z,1433800373.535 [Startup:StartupSatComms] Running Loop=1
2015-06-08T21:52:53.535Z,1433800373.535 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2015-06-08T21:52:53.535Z,1433800373.535 [Startup:StartupSatComms:A] Running Loop=1
2015-06-08T21:52:53.929Z,1433800373.929 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-06-08T21:52:55.573Z,1433800375.573 [BPC1](ERROR): Failed to parse message.
2015-06-08T21:52:55.573Z,1433800375.573 [BPC1](ERROR): Failed to parse bank B battery data
2015-06-08T21:52:55.573Z,1433800375.573 [BPC1] Data Fault, FailCount= 1
2015-06-08T21:52:55.573Z,1433800375.573 [BPC1](ERROR): Data Fault
2015-06-08T21:52:55.638Z,1433800375.638 [CBIT](ERROR): Data Fault in component: BPC1
2015-06-08T21:52:56.828Z,1433800376.828 [CBIT](INFO): Clearing failed state for component BPC1
2015-06-08T21:52:56.828Z,1433800376.828 [BPC1] No Fault, FailCount= 1
2015-06-08T21:53:18.092Z,1433800398.092 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003806
2015-06-08T21:53:53.748Z,1433800433.748 [Startup:StartupSatComms:A](INFO): Timed out from 2015-06-08T21:52:53.5Z
2015-06-08T21:53:53.748Z,1433800433.748 [Startup:StartupSatComms:A] Stopped
2015-06-08T21:53:53.748Z,1433800433.748 [Startup:StartupSatComms:B] Running Loop=1
2015-06-08T21:53:54.221Z,1433800434.221 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-06-08T21:53:58.236Z,1433800438.236 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20150608T213825/Courier0004.lzma
2015-06-08T21:53:59.016Z,1433800439.016 [DataOverHttps](INFO): Moved sent file to Logs/20150608T213825/Courier0004.lzma.bak
2015-06-08T21:53:59.016Z,1433800439.016 [DataOverHttps](INFO): SBD MOMSN=3549899
2015-06-08T21:54:08.094Z,1433800448.094 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20150608T215120/Courier0000.lzma
2015-06-08T21:54:08.860Z,1433800448.860 [DataOverHttps](INFO): Moved sent file to Logs/20150608T215120/Courier0000.lzma.bak
2015-06-08T21:54:08.860Z,1433800448.860 [DataOverHttps](INFO): SBD MOMSN=3549901
2015-06-08T21:54:18.079Z,1433800458.079 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20150608T213825/Express0005.lzma
2015-06-08T21:54:18.823Z,1433800458.823 [DataOverHttps](INFO): Moved sent file to Logs/20150608T213825/Express0005.lzma.bak
2015-06-08T21:54:18.823Z,1433800458.823 [DataOverHttps](INFO): SBD MOMSN=3549907
2015-06-08T21:54:27.969Z,1433800467.969 [DataOverHttps](INFO): Sending 708 bytes from file Logs/20150608T215120/Express0001.lzma
2015-06-08T21:54:28.735Z,1433800468.735 [DataOverHttps](INFO): Moved sent file to Logs/20150608T215120/Express0001.lzma.bak
2015-06-08T21:54:28.735Z,1433800468.735 [DataOverHttps](INFO): SBD MOMSN=3549909
2015-06-08T21:54:29.218Z,1433800469.218 [HFRadarCompactModelForecaster](DEBUG): Did not find new historical expansion coefficients.
2015-06-08T21:54:29.225Z,1433800469.225 [Startup:StartupSatComms:B] Stopped
2015-06-08T21:54:29.226Z,1433800469.226 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2015-06-08T21:54:29.226Z,1433800469.226 [Startup:StartupSatComms] Stopped
2015-06-08T21:54:29.226Z,1433800469.226 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2015-06-08T21:54:29.227Z,1433800469.227 [Startup](INFO): Completed Startup
2015-06-08T21:54:29.227Z,1433800469.227 [Startup] Stopped
2015-06-08T21:54:29.227Z,1433800469.227 [Startup](INFO): Aggregate::uninitialize Startup
2015-06-08T21:54:29.227Z,1433800469.227 [Startup:A.GoToSurface] Stopped
2015-06-08T21:54:29.227Z,1433800469.227 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-06-08T21:54:29.575Z,1433800469.575 [MissionManager](IMPORTANT): Started mission Default
2015-06-08T21:54:29.575Z,1433800469.575 [Default] Running Loop=1
2015-06-08T21:54:29.576Z,1433800469.576 [Default](INFO): Aggregate::initialize Default
2015-06-08T21:54:29.576Z,1433800469.576 [Default:A.GoToSurface] Running Loop=1
2015-06-08T21:54:29.576Z,1433800469.576 [Default:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-06-08T21:54:29.576Z,1433800469.576 [Default:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-06-08T21:54:29.576Z,1433800469.576 [Default:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-06-08T21:54:29.577Z,1433800469.577 [Default:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-06-08T21:54:29.583Z,1433800469.583 [Default:WaitAtTheSurface] Running Loop=1
2015-06-08T21:54:29.583Z,1433800469.583 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2015-06-08T21:54:29.583Z,1433800469.583 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-06-08T21:54:29.583Z,1433800469.583 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2015-06-08T21:54:32.933Z,1433800472.933 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-06-08T21:54:50.020Z,1433800490.020 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-06-08T21:54:50.021Z,1433800490.021 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2015-06-08T21:54:50.021Z,1433800490.021 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-06-08T21:54:50.023Z,1433800490.023 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-06-08T21:54:50.023Z,1433800490.023 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2015-06-08T21:54:50.023Z,1433800490.023 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-06-08T21:54:50.034Z,1433800490.034 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-06-08T21:54:50.034Z,1433800490.034 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-06-08T21:54:55.037Z,1433800495.037 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-06-08T21:54:55.037Z,1433800495.037 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2015-06-08T21:54:55.038Z,1433800495.038 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-06-08T21:54:55.038Z,1433800495.038 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2015-06-08T21:54:59.991Z,1433800499.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-06-08T21:54:59.991Z,1433800499.991 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-06-08T21:54:59.991Z,1433800499.991 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-06-08T21:54:59.992Z,1433800499.992 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-06-08T21:54:59.992Z,1433800499.992 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after this many seconds: 120.000000
2015-06-08T21:54:59.993Z,1433800499.993 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after this many seconds: 20.000000
2015-06-08T21:55:04.994Z,1433800504.994 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 14.973796 seconds since last successful read, accepting data for 20.000000 seconds.
2015-06-08T21:55:10.011Z,1433800510.011 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 19.990798 seconds since last successful read, accepting data for 20.000000 seconds.
2015-06-08T21:55:14.987Z,1433800514.987 [BPC1](ERROR): Failed to receive battery data
2015-06-08T21:55:14.987Z,1433800514.987 [BPC1] Communications Fault, FailCount= 1
2015-06-08T21:55:14.987Z,1433800514.987 [BPC1](ERROR): Communications Fault
2015-06-08T21:55:15.010Z,1433800515.010 [CBIT](ERROR): Communications Fault in component: BPC1
2015-06-08T21:55:30.003Z,1433800530.003 [CBIT](INFO): Clearing failed state for component BPC1
2015-06-08T21:55:30.003Z,1433800530.003 [BPC1] No Fault, FailCount= 1
2015-06-08T21:56:00.001Z,1433800560.001 [BPC1](ERROR): Failed to receive battery data
2015-06-08T21:56:00.002Z,1433800560.002 [BPC1] Communications Fault, FailCount= 2
2015-06-08T21:56:00.002Z,1433800560.002 [BPC1](ERROR): Communications Fault
2015-06-08T21:56:00.053Z,1433800560.053 [CBIT](ERROR): Communications Fault in component: BPC1
2015-06-08T21:56:15.015Z,1433800575.015 [CBIT](INFO): Clearing failed state for component BPC1
2015-06-08T21:56:15.015Z,1433800575.015 [BPC1] No Fault, FailCount= 2
2015-06-08T21:56:42.868Z,1433800602.868 [BPC1](ERROR): Failed to receive battery data
2015-06-08T21:56:42.868Z,1433800602.868 [BPC1] Communications Fault, FailCount= 3
2015-06-08T21:56:42.868Z,1433800602.868 [BPC1](ERROR): Communications Fault
2015-06-08T21:56:42.999Z,1433800602.999 [CBIT](ERROR): Communications Fault in component: BPC1
2015-06-08T21:56:55.001Z,1433800615.001 [CBIT](INFO): Clearing failed state for component BPC1
2015-06-08T21:56:55.001Z,1433800615.001 [BPC1] No Fault, FailCount= 3
2015-06-08T21:56:58.127Z,1433800618.127 [CommandLine](IMPORTANT): got command show variable Rowe_600
2015-06-08T21:56:58.179Z,1433800618.179 [CommandLine](IMPORTANT): Rowe_600.loadAtStartup (bool)
2015-06-08T21:56:58.179Z,1433800618.179 [CommandLine](IMPORTANT): Rowe_600.simulateHardware (bool)
2015-06-08T21:56:58.180Z,1433800618.180 [CommandLine](IMPORTANT): Rowe_600.verbosity (count)
2015-06-08T21:56:58.180Z,1433800618.180 [CommandLine](IMPORTANT): Rowe_600.writeBeamVelocityProfile (bool)
2015-06-08T21:56:58.180Z,1433800618.180 [CommandLine](IMPORTANT): Rowe_600.writeInstrumentVelocityProfile (bool)
2015-06-08T21:56:58.181Z,1433800618.181 [CommandLine](IMPORTANT): Rowe_600.writeEarthVelocityProfile (bool)
2015-06-08T21:56:58.181Z,1433800618.181 [CommandLine](IMPORTANT): Rowe_600.writeAmplitudeProfile (bool)
2015-06-08T21:56:58.182Z,1433800618.182 [CommandLine](IMPORTANT): Rowe_600.writeCorrelationProfile (bool)
2015-06-08T21:56:58.182Z,1433800618.182 [CommandLine](IMPORTANT): Rowe_600.writeGoodBeamPingsProfile (bool)
2015-06-08T21:56:58.182Z,1433800618.182 [CommandLine](IMPORTANT): Rowe_600.writeGoodEarthPingsProfile (bool)
2015-06-08T21:56:58.183Z,1433800618.183 [CommandLine](IMPORTANT): Rowe_600.writeRawEnsemble (bool)
2015-06-08T21:56:58.183Z,1433800618.183 [CommandLine](IMPORTANT): Rowe_600.acousticBlankingDistance (meter)
2015-06-08T21:56:58.183Z,1433800618.183 [CommandLine](IMPORTANT): Rowe_600.numberOfBeams (count)
2015-06-08T21:56:58.184Z,1433800618.184 [CommandLine](IMPORTANT): Rowe_600.numberOfBins (count)
2015-06-08T21:56:58.184Z,1433800618.184 [CommandLine](IMPORTANT): Rowe_600.sampleTime (second)
2015-06-08T21:56:58.185Z,1433800618.185 [CommandLine](IMPORTANT): Rowe_600.bottomTrackVelocityAccuracy (meter_per_second)
2015-06-08T21:56:58.185Z,1433800618.185 [CommandLine](IMPORTANT): Rowe_600.waterTrackVelocityAccuracy (meter_per_second)
2015-06-08T21:56:58.185Z,1433800618.185 [CommandLine](IMPORTANT): Rowe_600.altitudeAccuracy (meter)
2015-06-08T21:56:58.186Z,1433800618.186 [CommandLine](IMPORTANT): Rowe_600.rollOffset (degree)
2015-06-08T21:56:58.186Z,1433800618.186 [CommandLine](IMPORTANT): Rowe_600.pitchOffset (degree)
2015-06-08T21:56:58.187Z,1433800618.187 [CommandLine](IMPORTANT): Rowe_600.headingOffset (degree)
2015-06-08T21:56:58.187Z,1433800618.187 [CommandLine](IMPORTANT): Rowe_600.maxSpeed (meter_per_second)
2015-06-08T21:56:58.187Z,1433800618.187 [CommandLine](IMPORTANT): Rowe_600.waterReferenceLayerBin (count)
2015-06-08T21:56:58.256Z,1433800618.256 [CommandLine](IMPORTANT): Rowe_600.loadControl (none)
2015-06-08T21:56:58.256Z,1433800618.256 [CommandLine](IMPORTANT): Rowe_600.uart (none)
2015-06-08T21:56:58.257Z,1433800618.257 [CommandLine](IMPORTANT): Rowe_600.baud (bit_per_second)
2015-06-08T21:57:04.945Z,1433800624.945 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2015-06-08T21:57:16.853Z,1433800636.853 [CommandLine](IMPORTANT): got command get Rowe_600.loadAtStartup
2015-06-08T21:57:16.854Z,1433800636.854 [CommandLine](IMPORTANT): Rowe_600.loadAtStartup 0 bool
2015-06-08T21:57:54.789Z,1433800674.789 [CommandLine](IMPORTANT): got command restart application
2015-06-08T21:57:55.813Z,1433800675.813 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2015-06-08T21:57:55.813Z,1433800675.813 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:55.857Z,1433800675.857 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2015-06-08T21:57:55.857Z,1433800675.857 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:55.858Z,1433800675.858 [CommandLine](INFO): Join timeout helper Thread ID is 901
2015-06-08T21:57:55.858Z,1433800675.858 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2015-06-08T21:57:55.858Z,1433800675.858 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:55.859Z,1433800675.859 [NavChartDb](INFO): Join timeout helper Thread ID is 902
2015-06-08T21:57:56.152Z,1433800676.152 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2015-06-08T21:57:56.152Z,1433800676.152 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:56.158Z,1433800676.158 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2015-06-08T21:57:56.158Z,1433800676.158 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:56.159Z,1433800676.159 [Radio_Surface](INFO): Join timeout helper Thread ID is 903
2015-06-08T21:57:56.431Z,1433800676.431 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2015-06-08T21:57:56.431Z,1433800676.431 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:56.439Z,1433800676.439 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2015-06-08T21:57:56.439Z,1433800676.439 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:56.439Z,1433800676.439 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 904
2015-06-08T21:57:56.723Z,1433800676.723 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2015-06-08T21:57:56.723Z,1433800676.723 [WetLabsBB2FL](INFO): Powering down
2015-06-08T21:57:56.724Z,1433800676.724 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:56.739Z,1433800676.739 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2015-06-08T21:57:56.739Z,1433800676.739 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:56.740Z,1433800676.740 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 905
2015-06-08T21:57:56.932Z,1433800676.932 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2015-06-08T21:57:56.932Z,1433800676.932 [CTD_NeilBrown](INFO): Powering down
2015-06-08T21:57:56.933Z,1433800676.933 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:56.940Z,1433800676.940 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2015-06-08T21:57:56.940Z,1433800676.940 [logger ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:56.941Z,1433800676.941 [logger](INFO): Join timeout helper Thread ID is 906
2015-06-08T21:57:56.948Z,1433800676.948 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2015-06-08T21:57:56.948Z,1433800676.948 [logger ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:56.961Z,1433800676.961 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2015-06-08T21:57:56.961Z,1433800676.961 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:56.961Z,1433800676.961 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2015-06-08T21:57:56.961Z,1433800676.961 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:57:56.962Z,1433800676.962 [controlThread](INFO): Join timeout helper Thread ID is 907
2015-06-08T21:57:59.928Z,1433800679.928 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2015-06-08T21:57:59.929Z,1433800679.929 [controlThread](DEBUG): Uninitializing ControlThread
2015-06-08T21:57:59.929Z,1433800679.929 [AcousticModem_Benthos_ATM900](INFO): Powering down
2015-06-08T21:58:00.052Z,1433800680.052 [NAL9602](INFO): Powering down
2015-06-08T21:58:00.054Z,1433800680.054 [PNI_TCM](INFO): Powering down
2015-06-08T21:58:00.141Z,1433800680.141 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-06-08T21:58:00.144Z,1433800680.144 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2015-06-08T21:58:00.145Z,1433800680.145 [Default] Stopped
2015-06-08T21:58:00.145Z,1433800680.145 [Default](INFO): Aggregate::uninitialize Default
2015-06-08T21:58:00.145Z,1433800680.145 [Default:A.GoToSurface] Stopped
2015-06-08T21:58:00.145Z,1433800680.145 [Default:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-06-08T21:58:00.145Z,1433800680.145 [Default:WaitAtTheSurface] Stopped
2015-06-08T21:58:00.145Z,1433800680.145 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface
2015-06-08T21:58:00.145Z,1433800680.145 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Stopped
2015-06-08T21:58:00.145Z,1433800680.145 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Uninitialize.
2015-06-08T21:58:00.148Z,1433800680.148 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2015-06-08T21:58:00.148Z,1433800680.148 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2015-06-08T21:58:00.149Z,1433800680.149 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2015-06-08T21:58:00.149Z,1433800680.149 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2015-06-08T21:58:00.149Z,1433800680.149 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-06-08T21:58:00.149Z,1433800680.149 [BuoyancyServo](INFO): Powering down
2015-06-08T21:58:00.161Z,1433800680.161 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2015-06-08T21:58:00.161Z,1433800680.161 [ElevatorServo](INFO): Powering down
2015-06-08T21:58:00.162Z,1433800680.162 [MassServo](DEBUG): Uninitialize Mass Servo.
2015-06-08T21:58:00.162Z,1433800680.162 [MassServo](INFO): Powering down
2015-06-08T21:58:00.162Z,1433800680.162 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2015-06-08T21:58:00.163Z,1433800680.163 [RudderServo](INFO): Powering down
2015-06-08T21:58:00.163Z,1433800680.163 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2015-06-08T21:58:00.163Z,1433800680.163 [ThrusterServo](INFO): Powering down
2015-06-08T21:58:00.164Z,1433800680.164 [SBIT](DEBUG): Uninitialize SBIT Component.
2015-06-08T21:58:00.165Z,1433800680.165 [IBIT](DEBUG): Uninitialize IBIT Component.
2015-06-08T21:58:00.165Z,1433800680.165 [CBIT](DEBUG): Uninitialize CBIT Component.
2015-06-08T21:58:00.197Z,1433800680.197 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:58:00.257Z,1433800680.257 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:58:00.261Z,1433800680.261 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:58:00.324Z,1433800680.324 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:58:00.367Z,1433800680.367 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-06-08T21:58:00.419Z,1433800680.419 [logger ThreadHandler](INFO): Thread cancelled.