2015-03-06T01:20:26.995Z,1425604826.995 [Supervisor](DEBUG): Initializing supervisor.
2015-03-06T01:20:26.998Z,1425604826.998 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-03-06T01:20:26.999Z,1425604826.999 [SyncHandler](INFO): Protected caller Thread ID is 768
2015-03-06T01:20:26.999Z,1425604826.999 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-03-06T01:20:27.001Z,1425604827.001 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-03-06T01:20:27.001Z,1425604827.001 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 769
2015-03-06T01:20:27.004Z,1425604827.004 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-03-06T01:20:27.017Z,1425604827.017 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-03-06T01:20:27.021Z,1425604827.021 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-03-06T01:20:27.021Z,1425604827.021 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 770
2015-03-06T01:20:27.022Z,1425604827.022 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-03-06T01:20:27.025Z,1425604827.025 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-03-06T01:20:27.025Z,1425604827.025 [logger ThreadHandler](INFO): Protected caller Thread ID is 771
2015-03-06T01:20:27.027Z,1425604827.027 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-03-06T01:20:27.028Z,1425604827.028 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-03-06T01:20:27.031Z,1425604827.031 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-03-06T01:20:27.471Z,1425604827.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-03-06T01:20:27.472Z,1425604827.472 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-03-06T01:20:27.675Z,1425604827.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-03-06T01:20:27.675Z,1425604827.675 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-03-06T01:20:27.807Z,1425604827.807 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-03-06T01:20:27.807Z,1425604827.807 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-03-06T01:20:27.955Z,1425604827.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-03-06T01:20:27.957Z,1425604827.957 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-03-06T01:20:28.058Z,1425604828.058 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-03-06T01:20:28.256Z,1425604828.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-03-06T01:20:28.257Z,1425604828.257 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-03-06T01:20:28.358Z,1425604828.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-03-06T01:20:28.358Z,1425604828.358 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-03-06T01:20:28.620Z,1425604828.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-03-06T01:20:28.621Z,1425604828.621 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-03-06T01:20:29.081Z,1425604829.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-03-06T01:20:29.082Z,1425604829.082 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-03-06T01:20:29.482Z,1425604829.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-03-06T01:20:29.483Z,1425604829.483 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-03-06T01:20:30.123Z,1425604830.123 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-03-06T01:20:30.124Z,1425604830.124 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-03-06T01:20:30.438Z,1425604830.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-03-06T01:20:30.439Z,1425604830.439 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-03-06T01:20:30.622Z,1425604830.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-03-06T01:20:30.623Z,1425604830.623 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-03-06T01:20:31.169Z,1425604831.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-03-06T01:20:31.169Z,1425604831.169 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-03-06T01:20:31.304Z,1425604831.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-03-06T01:20:31.306Z,1425604831.306 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2015-03-06T01:20:31.309Z,1425604831.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2015-03-06T01:20:31.431Z,1425604831.431 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2015-03-06T01:20:31.577Z,1425604831.577 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2015-03-06T01:20:31.693Z,1425604831.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2015-03-06T01:20:31.826Z,1425604831.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2015-03-06T01:20:31.938Z,1425604831.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2015-03-06T01:20:32.094Z,1425604832.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2015-03-06T01:20:32.290Z,1425604832.290 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2015-03-06T01:20:32.407Z,1425604832.407 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2015-03-06T01:20:32.511Z,1425604832.511 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2015-03-06T01:20:32.605Z,1425604832.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2015-03-06T01:20:32.719Z,1425604832.719 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2015-03-06T01:20:32.859Z,1425604832.859 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2015-03-06T01:20:33.147Z,1425604833.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-03-06T01:20:33.148Z,1425604833.148 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2015-03-06T01:20:33.152Z,1425604833.152 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-03-06T01:20:33.235Z,1425604833.235 [VerticalControl](DEBUG): Construct VerticalControl.
2015-03-06T01:20:33.380Z,1425604833.380 [VerticalControl] Loaded
2015-03-06T01:20:33.381Z,1425604833.381 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-03-06T01:20:33.381Z,1425604833.381 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-03-06T01:20:33.473Z,1425604833.473 [HorizontalControl] Loaded
2015-03-06T01:20:33.474Z,1425604833.474 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-03-06T01:20:33.474Z,1425604833.474 [SpeedControl](DEBUG): Construct SpeedControl.
2015-03-06T01:20:33.481Z,1425604833.481 [SpeedControl] Loaded
2015-03-06T01:20:33.481Z,1425604833.481 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-03-06T01:20:33.482Z,1425604833.482 [LoopControl](DEBUG): Construct LoopControl.
2015-03-06T01:20:33.483Z,1425604833.483 [LoopControl] Loaded
2015-03-06T01:20:33.483Z,1425604833.483 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-03-06T01:20:33.484Z,1425604833.484 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-03-06T01:20:33.484Z,1425604833.484 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-03-06T01:20:33.632Z,1425604833.632 [SBIT](DEBUG): Construct Startup Built In Test.
2015-03-06T01:20:33.645Z,1425604833.645 [SBIT] Loaded
2015-03-06T01:20:33.645Z,1425604833.645 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-03-06T01:20:33.646Z,1425604833.646 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-03-06T01:20:33.682Z,1425604833.682 [IBIT] Loaded
2015-03-06T01:20:33.682Z,1425604833.682 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-03-06T01:20:33.685Z,1425604833.685 [CBIT](DEBUG): Construct CBIT Built In Test.
2015-03-06T01:20:33.845Z,1425604833.845 [CBIT] Loaded
2015-03-06T01:20:33.846Z,1425604833.846 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-03-06T01:20:33.846Z,1425604833.846 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-03-06T01:20:33.847Z,1425604833.847 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-03-06T01:20:33.881Z,1425604833.881 [DepthRateCalculator] Loaded
2015-03-06T01:20:33.882Z,1425604833.882 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-03-06T01:20:33.888Z,1425604833.888 [PitchRateCalculator] Loaded
2015-03-06T01:20:33.888Z,1425604833.888 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-03-06T01:20:33.901Z,1425604833.901 [SpeedCalculator] Loaded
2015-03-06T01:20:33.902Z,1425604833.902 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-03-06T01:20:33.921Z,1425604833.921 [TempGradientCalculator] Loaded
2015-03-06T01:20:33.921Z,1425604833.921 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-03-06T01:20:33.927Z,1425604833.927 [YawRateCalculator] Loaded
2015-03-06T01:20:33.927Z,1425604833.927 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-03-06T01:20:33.928Z,1425604833.928 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-03-06T01:20:33.928Z,1425604833.928 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-03-06T01:20:34.030Z,1425604834.030 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-03-06T01:20:34.031Z,1425604834.031 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-03-06T01:20:34.347Z,1425604834.347 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-03-06T01:20:34.348Z,1425604834.348 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-03-06T01:20:34.472Z,1425604834.472 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-03-06T01:20:34.472Z,1425604834.472 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-03-06T01:20:34.527Z,1425604834.527 [DeadReckonUsingSpeedCalculator] Loaded
2015-03-06T01:20:34.527Z,1425604834.527 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-03-06T01:20:34.550Z,1425604834.550 [NavChart] Loaded
2015-03-06T01:20:34.551Z,1425604834.551 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-03-06T01:20:34.555Z,1425604834.555 [UniversalFixResidualReporter] Loaded
2015-03-06T01:20:34.556Z,1425604834.556 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-03-06T01:20:34.556Z,1425604834.556 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-03-06T01:20:34.557Z,1425604834.557 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-03-06T01:20:34.566Z,1425604834.566 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-03-06T01:20:34.566Z,1425604834.566 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-03-06T01:20:34.778Z,1425604834.778 [Aanderaa_O2] Loaded
2015-03-06T01:20:34.778Z,1425604834.778 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2015-03-06T01:20:34.872Z,1425604834.872 [CTD_NeilBrown] Loaded
2015-03-06T01:20:34.872Z,1425604834.872 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2015-03-06T01:20:34.877Z,1425604834.877 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405714E0
2015-03-06T01:20:34.877Z,1425604834.877 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 856
2015-03-06T01:20:34.892Z,1425604834.892 [ISUS] Loaded
2015-03-06T01:20:34.892Z,1425604834.892 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2015-03-06T01:20:34.912Z,1425604834.912 [PAR_Licor] Loaded
2015-03-06T01:20:34.913Z,1425604834.913 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2015-03-06T01:20:34.970Z,1425604834.970 [WetLabsBB2FL] Loaded
2015-03-06T01:20:34.970Z,1425604834.970 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2015-03-06T01:20:34.973Z,1425604834.973 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405A14E0
2015-03-06T01:20:34.973Z,1425604834.973 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 857
2015-03-06T01:20:34.974Z,1425604834.974 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-03-06T01:20:34.975Z,1425604834.975 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-03-06T01:20:35.786Z,1425604835.786 [AHRS_sp3003D] Loaded
2015-03-06T01:20:35.786Z,1425604835.786 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2015-03-06T01:20:35.977Z,1425604835.977 [DataOverHttps] Loaded
2015-03-06T01:20:35.977Z,1425604835.977 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-03-06T01:20:35.997Z,1425604835.997 [Depth_Keller] Loaded
2015-03-06T01:20:35.997Z,1425604835.997 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-03-06T01:20:36.002Z,1425604836.002 [DropWeight] Loaded
2015-03-06T01:20:36.003Z,1425604836.003 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2015-03-06T01:20:36.168Z,1425604836.168 [DVL_micro] Loaded
2015-03-06T01:20:36.168Z,1425604836.168 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2015-03-06T01:20:36.286Z,1425604836.286 [NAL9602] Loaded
2015-03-06T01:20:36.286Z,1425604836.286 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-03-06T01:20:36.338Z,1425604836.338 [Onboard] Loaded
2015-03-06T01:20:36.339Z,1425604836.339 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-03-06T01:20:36.347Z,1425604836.347 [Radio_Surface] Loaded
2015-03-06T01:20:36.347Z,1425604836.347 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-03-06T01:20:36.351Z,1425604836.351 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407F14E0
2015-03-06T01:20:36.352Z,1425604836.352 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 858
2015-03-06T01:20:38.640Z,1425604838.640 [BPC1] Loaded
2015-03-06T01:20:38.640Z,1425604838.640 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-03-06T01:20:38.641Z,1425604838.641 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-03-06T01:20:38.642Z,1425604838.642 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-03-06T01:20:38.841Z,1425604838.841 [BuoyancyServo] Loaded
2015-03-06T01:20:38.842Z,1425604838.842 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-03-06T01:20:38.856Z,1425604838.856 [ElevatorServo] Loaded
2015-03-06T01:20:38.856Z,1425604838.856 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-03-06T01:20:38.869Z,1425604838.869 [MassServo] Loaded
2015-03-06T01:20:38.870Z,1425604838.870 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-03-06T01:20:38.888Z,1425604838.888 [RudderServo] Loaded
2015-03-06T01:20:38.888Z,1425604838.888 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-03-06T01:20:38.901Z,1425604838.901 [ThrusterServo] Loaded
2015-03-06T01:20:38.902Z,1425604838.902 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-03-06T01:20:38.902Z,1425604838.902 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-03-06T01:20:38.903Z,1425604838.903 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-03-06T01:20:39.072Z,1425604839.072 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-03-06T01:20:39.073Z,1425604839.073 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-03-06T01:20:39.118Z,1425604839.118 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-03-06T01:20:39.120Z,1425604839.120 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-03-06T01:20:39.121Z,1425604839.121 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-03-06T01:20:39.132Z,1425604839.132 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-03-06T01:20:39.135Z,1425604839.135 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408A24E0
2015-03-06T01:20:39.136Z,1425604839.136 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 859
2015-03-06T01:20:39.141Z,1425604839.141 [Supervisor](INFO): Main Thread ID is 764
2015-03-06T01:20:39.141Z,1425604839.141 [Supervisor](DEBUG): Running supervisor.
2015-03-06T01:20:39.142Z,1425604839.142 [CommandLine ThreadHandler](INFO): Handler Thread ID is 860
2015-03-06T01:20:39.148Z,1425604839.148 [controlThread ThreadHandler](INFO): Handler Thread ID is 861
2015-03-06T01:20:39.148Z,1425604839.148 [controlThread](DEBUG): Initializing ControlThread
2015-03-06T01:20:39.149Z,1425604839.149 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-03-06T01:20:39.151Z,1425604839.151 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-03-06T01:20:39.152Z,1425604839.152 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-03-06T01:20:39.152Z,1425604839.152 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-03-06T01:20:39.153Z,1425604839.153 [SBIT](INFO): Initialize SBIT Component.
2015-03-06T01:20:39.153Z,1425604839.153 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12048
2015-03-06T01:20:39.153Z,1425604839.153 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-03-06T01:20:39.154Z,1425604839.154 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-03-06T01:20:39.154Z,1425604839.154 [IBIT](INFO): Initialize IBIT Component.
2015-03-06T01:20:39.155Z,1425604839.155 [CBIT](DEBUG): Initialize CBIT Component.
2015-03-06T01:20:39.156Z,1425604839.156 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2015-03-06T01:20:39.156Z,1425604839.156 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-03-06T01:20:39.160Z,1425604839.160 [logger ThreadHandler](INFO): Handler Thread ID is 862
2015-03-06T01:20:39.180Z,1425604839.180 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-03-06T01:20:39.180Z,1425604839.180 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-03-06T01:20:39.181Z,1425604839.181 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-03-06T01:20:39.181Z,1425604839.181 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-03-06T01:20:39.181Z,1425604839.181 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-03-06T01:20:39.182Z,1425604839.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-03-06T01:20:39.182Z,1425604839.182 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-03-06T01:20:39.183Z,1425604839.183 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-03-06T01:20:39.183Z,1425604839.183 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-03-06T01:20:39.193Z,1425604839.193 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2015-03-06T01:20:39.193Z,1425604839.193 [DropWeight] Hardware Fault, FailCount= 1
2015-03-06T01:20:39.193Z,1425604839.193 [DropWeight](ERROR): Hardware Fault
2015-03-06T01:20:39.197Z,1425604839.197 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-03-06T01:20:39.209Z,1425604839.209 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 863
2015-03-06T01:20:39.210Z,1425604839.210 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2015-03-06T01:20:39.213Z,1425604839.213 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2015-03-06T01:20:39.235Z,1425604839.235 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-03-06T01:20:39.241Z,1425604839.241 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 865
2015-03-06T01:20:39.242Z,1425604839.242 [WetLabsBB2FL](INFO): Powering down
2015-03-06T01:20:39.272Z,1425604839.272 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 866
2015-03-06T01:20:39.280Z,1425604839.280 [Radio_Surface](INFO): Powering up
2015-03-06T01:20:39.289Z,1425604839.289 [MissionManager](DEBUG):
2015-03-06T01:20:39.290Z,1425604839.290 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-03-06T01:20:39.308Z,1425604839.308 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 867
2015-03-06T01:20:39.320Z,1425604839.320 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-03-06T01:20:39.322Z,1425604839.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-03-06T01:20:39.322Z,1425604839.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-03-06T01:20:39.323Z,1425604839.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-03-06T01:20:39.323Z,1425604839.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-03-06T01:20:39.323Z,1425604839.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-03-06T01:20:39.323Z,1425604839.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-03-06T01:20:39.324Z,1425604839.324 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-03-06T01:20:39.324Z,1425604839.324 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-03-06T01:20:39.344Z,1425604839.344 [CTD_NeilBrown](DEBUG): CTD uart timeout: serial timeout
2015-03-06T01:20:39.345Z,1425604839.345 [CTD_NeilBrown](ERROR): Could not get real or simulated CTD data. startTime.elapsed() = 0.130805
2015-03-06T01:20:39.410Z,1425604839.410 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2015-03-06T01:20:39.429Z,1425604839.429 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2015-03-06T01:20:39.434Z,1425604839.434 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2015-03-06T01:20:39.466Z,1425604839.466 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2015-03-06T01:20:39.489Z,1425604839.489 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-03-06T01:20:39.513Z,1425604839.513 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2015-03-06T01:20:39.539Z,1425604839.539 [MissionManager](DEBUG):
0
0
5.0
1.0
400
Burn 300
Dropped drop weight due to communications timeout
5.0
Default mission has been running for
5
2015-03-06T01:20:39.545Z,1425604839.545 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ISUS,PAR_Licor,AHRS_sp3003D,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-03-06T01:20:39.720Z,1425604839.720 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2015-03-06T01:20:40.368Z,1425604840.368 [DVL_micro](INFO): Initializing
2015-03-06T01:20:40.711Z,1425604840.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:20:40.873Z,1425604840.873 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-03-06T01:20:40.879Z,1425604840.879 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-03-06T01:20:40.908Z,1425604840.908 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-03-06T01:20:40.915Z,1425604840.915 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-03-06T01:20:40.943Z,1425604840.943 [MassServo](DEBUG): Initializing EZServoServo.
2015-03-06T01:20:40.951Z,1425604840.951 [MassServo](DEBUG): Initializing MassServo.
2015-03-06T01:20:40.966Z,1425604840.966 [RudderServo](DEBUG): Initializing EZServoServo.
2015-03-06T01:20:40.967Z,1425604840.967 [RudderServo](DEBUG): Initializing RudderServo.
2015-03-06T01:20:41.001Z,1425604841.001 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-03-06T01:20:41.007Z,1425604841.007 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-03-06T01:20:41.060Z,1425604841.060 [CommandLine](FAULT): Scheduling is paused
2015-03-06T01:20:41.062Z,1425604841.062 [CBIT](ERROR): Hardware Fault in component: DropWeight
2015-03-06T01:20:41.070Z,1425604841.070 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2015-03-06T01:20:41.345Z,1425604841.345 [NAL9602](INFO): Powering up NAL9602
2015-03-06T01:20:41.391Z,1425604841.391 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:20:41.740Z,1425604841.740 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2015-03-06T01:20:41.740Z,1425604841.740 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2015-03-06T01:20:41.740Z,1425604841.740 [BuoyancyServo] Communications Fault, FailCount= 1
2015-03-06T01:20:41.740Z,1425604841.740 [BuoyancyServo](ERROR): Communications Fault
2015-03-06T01:20:42.054Z,1425604842.054 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2015-03-06T01:20:42.420Z,1425604842.420 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:20:42.447Z,1425604842.447 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-03-06T01:20:42.461Z,1425604842.461 [BuoyancyServo](INFO): Powering down
2015-03-06T01:20:42.893Z,1425604842.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:20:43.379Z,1425604843.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:20:43.623Z,1425604843.623 [Aanderaa_O2](INFO): Powering down
2015-03-06T01:20:44.051Z,1425604844.051 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:20:44.635Z,1425604844.635 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2015-03-06T01:20:44.635Z,1425604844.635 [BuoyancyServo] No Fault, FailCount= 1
2015-03-06T01:20:45.061Z,1425604845.061 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-03-06T01:20:45.182Z,1425604845.182 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-03-06T01:20:52.319Z,1425604852.319 [NAL9602](INFO): NAL9602 initialized
2015-03-06T01:20:54.838Z,1425604854.838 [SBIT](IMPORTANT): Beginning Startup BIT
2015-03-06T01:20:54.841Z,1425604854.841 [CBIT](IMPORTANT): Beginning GF scan
2015-03-06T01:21:08.160Z,1425604868.160 [SBIT](FAULT): Mass: EXPECTED:0.005000 ACTUAL:0.004282
2015-03-06T01:21:21.947Z,1425604881.947 [CBIT](IMPORTANT): No ground fault detected
2015-03-06T01:21:49.373Z,1425604909.373 [SBIT](CRITICAL): SBIT FAILED
2015-03-06T01:21:49.906Z,1425604909.906 [MissionManager](IMPORTANT): Started mission Startup
2015-03-06T01:21:49.906Z,1425604909.906 [Startup] Running Loop=1
2015-03-06T01:21:49.906Z,1425604909.906 [Startup](INFO): Aggregate::initialize Startup
2015-03-06T01:21:49.907Z,1425604909.907 [Startup:A.GoToSurface] Running Loop=1
2015-03-06T01:21:49.907Z,1425604909.907 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-03-06T01:21:49.907Z,1425604909.907 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-03-06T01:21:49.908Z,1425604909.908 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-03-06T01:21:49.908Z,1425604909.908 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-03-06T01:21:49.914Z,1425604909.914 [Startup:StartupSatComms] Running Loop=1
2015-03-06T01:21:49.914Z,1425604909.914 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2015-03-06T01:21:49.915Z,1425604909.915 [Startup:StartupSatComms:A] Running Loop=1
2015-03-06T01:21:50.409Z,1425604910.409 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-03-06T01:22:21.813Z,1425604941.813 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:22.221Z,1425604942.221 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:22.633Z,1425604942.633 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:23.051Z,1425604943.051 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:23.524Z,1425604943.524 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:23.943Z,1425604943.943 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:24.444Z,1425604944.444 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:24.873Z,1425604944.873 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:25.338Z,1425604945.338 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:25.802Z,1425604945.802 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:26.223Z,1425604946.223 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:26.636Z,1425604946.636 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:27.048Z,1425604947.048 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:27.493Z,1425604947.493 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:27.919Z,1425604947.919 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:28.326Z,1425604948.326 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:28.731Z,1425604948.731 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:29.148Z,1425604949.148 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:29.592Z,1425604949.592 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:30.035Z,1425604950.035 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:30.471Z,1425604950.471 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:30.928Z,1425604950.928 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:31.347Z,1425604951.347 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:31.756Z,1425604951.756 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:32.165Z,1425604952.165 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:32.585Z,1425604952.585 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:32.998Z,1425604952.998 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:33.410Z,1425604953.410 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:33.815Z,1425604953.815 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:34.252Z,1425604954.252 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:34.700Z,1425604954.700 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:35.141Z,1425604955.141 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:35.565Z,1425604955.565 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:35.994Z,1425604955.994 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:36.411Z,1425604956.411 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:36.816Z,1425604956.816 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:37.252Z,1425604957.252 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:37.725Z,1425604957.725 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:38.150Z,1425604958.150 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:38.594Z,1425604958.594 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:39.007Z,1425604959.007 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:39.445Z,1425604959.445 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:39.908Z,1425604959.908 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:40.358Z,1425604960.358 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:40.798Z,1425604960.798 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:41.226Z,1425604961.226 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:41.647Z,1425604961.647 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:42.052Z,1425604962.052 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:42.460Z,1425604962.460 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:42.869Z,1425604962.869 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:43.277Z,1425604963.277 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:43.722Z,1425604963.722 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:44.135Z,1425604964.135 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:44.555Z,1425604964.555 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:44.976Z,1425604964.976 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:45.409Z,1425604965.409 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:45.851Z,1425604965.851 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:46.274Z,1425604966.274 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:46.743Z,1425604966.743 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:47.160Z,1425604967.160 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:47.572Z,1425604967.572 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:47.985Z,1425604967.985 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:48.407Z,1425604968.407 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2015-03-06T01:22:50.146Z,1425604970.146 [Startup:StartupSatComms:A](INFO): Timed out from 2015-03-06T01:21:49.9Z
2015-03-06T01:22:50.146Z,1425604970.146 [Startup:StartupSatComms:A] Stopped
2015-03-06T01:22:50.146Z,1425604970.146 [Startup:StartupSatComms:B] Running Loop=1
2015-03-06T01:22:50.583Z,1425604970.583 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-03-06T01:22:54.571Z,1425604974.571 [BPC1](ERROR): Failed to receive battery data
2015-03-06T01:22:54.571Z,1425604974.571 [BPC1] Communications Fault, FailCount= 1
2015-03-06T01:22:54.571Z,1425604974.571 [BPC1](ERROR): Communications Fault
2015-03-06T01:22:54.590Z,1425604974.590 [CBIT](ERROR): Communications Fault in component: BPC1
2015-03-06T01:22:55.929Z,1425604975.929 [CBIT](INFO): Clearing failed state for component BPC1
2015-03-06T01:22:55.929Z,1425604975.929 [BPC1] No Fault, FailCount= 1
2015-03-06T01:23:39.578Z,1425605019.578 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-03-06T01:23:39.578Z,1425605019.578 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2015-03-06T01:23:39.578Z,1425605019.578 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-03-06T01:23:39.580Z,1425605019.580 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-03-06T01:23:39.580Z,1425605019.580 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2015-03-06T01:23:39.580Z,1425605019.580 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-03-06T01:23:39.612Z,1425605019.612 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-03-06T01:23:39.612Z,1425605019.612 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-03-06T01:23:40.019Z,1425605020.019 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-03-06T01:23:40.019Z,1425605020.019 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2015-03-06T01:23:40.019Z,1425605020.019 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-03-06T01:23:40.019Z,1425605020.019 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2015-03-06T01:23:40.433Z,1425605020.433 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-03-06T01:23:40.434Z,1425605020.434 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-03-06T01:23:40.844Z,1425605020.844 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:23:41.267Z,1425605021.267 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:23:41.695Z,1425605021.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:23:42.124Z,1425605022.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:23:42.569Z,1425605022.569 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:23:42.989Z,1425605022.989 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:23:45.081Z,1425605025.081 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2015-03-06T01:23:45.081Z,1425605025.081 [DVL_micro] Data Fault, FailCount= 1
2015-03-06T01:23:45.081Z,1425605025.081 [DVL_micro](ERROR): Data Fault
2015-03-06T01:23:45.134Z,1425605025.134 [CBIT](ERROR): Data Fault in component: DVL_micro
2015-03-06T01:23:45.561Z,1425605025.561 [DVL_micro](INFO): uninitialize:Powering down
2015-03-06T01:23:46.438Z,1425605026.438 [CBIT](INFO): Clearing failed state for component DVL_micro
2015-03-06T01:23:46.438Z,1425605026.438 [DVL_micro] No Fault, FailCount= 1
2015-03-06T01:23:46.802Z,1425605026.802 [DVL_micro](INFO): Initializing
2015-03-06T01:23:50.246Z,1425605030.246 [Startup:StartupSatComms:B](INFO): Timed out from 2015-03-06T01:22:50.1Z
2015-03-06T01:23:50.246Z,1425605030.246 [Startup:StartupSatComms:B] Stopped
2015-03-06T01:23:50.246Z,1425605030.246 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2015-03-06T01:23:50.246Z,1425605030.246 [Startup:StartupSatComms] Stopped
2015-03-06T01:23:50.246Z,1425605030.246 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2015-03-06T01:23:50.247Z,1425605030.247 [Startup](INFO): Completed Startup
2015-03-06T01:23:50.247Z,1425605030.247 [Startup] Stopped
2015-03-06T01:23:50.247Z,1425605030.247 [Startup](INFO): Aggregate::uninitialize Startup
2015-03-06T01:23:50.247Z,1425605030.247 [Startup:A.GoToSurface] Stopped
2015-03-06T01:23:50.248Z,1425605030.248 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-03-06T01:23:50.688Z,1425605030.688 [MissionManager](IMPORTANT): Started mission Default
2015-03-06T01:23:50.688Z,1425605030.688 [Default] Running Loop=1
2015-03-06T01:23:50.688Z,1425605030.688 [Default](INFO): Aggregate::initialize Default
2015-03-06T01:23:50.688Z,1425605030.688 [Default:B.GoToSurface] Running Loop=1
2015-03-06T01:23:50.689Z,1425605030.689 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-03-06T01:23:50.689Z,1425605030.689 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-03-06T01:23:50.689Z,1425605030.689 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-03-06T01:23:50.690Z,1425605030.690 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-03-06T01:23:50.690Z,1425605030.690 [Default:StartClock] Running Loop=1
2015-03-06T01:23:50.690Z,1425605030.690 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock
2015-03-06T01:23:50.690Z,1425605030.690 [Default:StartClock:A] Running Loop=1
2015-03-06T01:23:50.690Z,1425605030.690 [Default:CheckIn] Running Loop=1
2015-03-06T01:23:50.691Z,1425605030.691 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-03-06T01:23:50.691Z,1425605030.691 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-03-06T01:23:50.691Z,1425605030.691 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-03-06T01:23:50.691Z,1425605030.691 [Default:CheckIn:Read_GPS] Running Loop=1
2015-03-06T01:23:50.692Z,1425605030.692 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-03-06T01:23:50.694Z,1425605030.694 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-03-06T01:23:50.707Z,1425605030.707 [Default:StartClock:A] Stopped
2015-03-06T01:23:50.707Z,1425605030.707 [Default:StartClock](INFO): Completed Default:StartClock
2015-03-06T01:23:50.707Z,1425605030.707 [Default:StartClock] Stopped
2015-03-06T01:23:50.707Z,1425605030.707 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock
2015-03-06T01:23:51.114Z,1425605031.114 [Default:WaitAtTheSurface] Running Loop=1
2015-03-06T01:23:51.114Z,1425605031.114 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2015-03-06T01:23:51.114Z,1425605031.114 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-03-06T01:23:51.114Z,1425605031.114 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2015-03-06T01:23:51.521Z,1425605031.521 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-03-06T01:23:54.081Z,1425605034.081 [BPC1](ERROR): Failed to receive battery data
2015-03-06T01:23:54.082Z,1425605034.082 [BPC1] Communications Fault, FailCount= 1
2015-03-06T01:23:54.082Z,1425605034.082 [BPC1](ERROR): Communications Fault
2015-03-06T01:23:54.126Z,1425605034.126 [CBIT](ERROR): Communications Fault in component: BPC1
2015-03-06T01:23:55.354Z,1425605035.354 [CBIT](INFO): Clearing failed state for component BPC1
2015-03-06T01:23:55.354Z,1425605035.354 [BPC1] No Fault, FailCount= 1
2015-03-06T01:25:39.666Z,1425605139.666 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-03-06T01:25:39.666Z,1425605139.666 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-03-06T01:25:39.666Z,1425605139.666 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-03-06T01:25:39.668Z,1425605139.668 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-03-06T01:25:39.668Z,1425605139.668 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-03-06T01:25:40.083Z,1425605140.083 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 1.816810 min
2015-03-06T01:25:40.108Z,1425605140.108 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-03-06T01:25:40.108Z,1425605140.108 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-03-06T01:25:40.108Z,1425605140.108 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-03-06T01:25:40.108Z,1425605140.108 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-03-06T01:25:56.470Z,1425605156.470 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2015-03-06T01:26:29.925Z,1425605189.925 [CommandLine](IMPORTANT): got command failComponent
2015-03-06T01:26:29.925Z,1425605189.925 [CommandLine](IMPORTANT): Failed components:
2015-03-06T01:26:29.925Z,1425605189.925 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2015-03-06T01:26:40.677Z,1425605200.677 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-03-06T01:26:40.677Z,1425605200.677 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2015-03-06T01:26:40.677Z,1425605200.677 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-03-06T01:26:40.679Z,1425605200.679 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-03-06T01:26:40.680Z,1425605200.680 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2015-03-06T01:26:40.680Z,1425605200.680 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2015-03-06T01:26:40.744Z,1425605200.744 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-03-06T01:26:40.745Z,1425605200.745 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2015-03-06T01:26:41.147Z,1425605201.147 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-03-06T01:26:41.147Z,1425605201.147 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2015-03-06T01:26:41.148Z,1425605201.148 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2015-03-06T01:26:41.148Z,1425605201.148 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2015-03-06T01:26:41.579Z,1425605201.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-03-06T01:26:41.579Z,1425605201.579 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-03-06T01:26:41.987Z,1425605201.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:26:42.428Z,1425605202.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:26:42.850Z,1425605202.850 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:26:43.283Z,1425605203.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:26:43.690Z,1425605203.690 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-03-06T01:26:44.137Z,1425605204.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since