2014-08-21T05:11:04.587Z,1408597864.587 [Supervisor](DEBUG): Initializing supervisor. 2014-08-21T05:11:04.590Z,1408597864.590 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0 2014-08-21T05:11:04.591Z,1408597864.591 [SyncHandler](INFO): Protected caller Thread ID is 2686 2014-08-21T05:11:04.591Z,1408597864.591 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2014-08-21T05:11:04.592Z,1408597864.592 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0 2014-08-21T05:11:04.593Z,1408597864.593 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2687 2014-08-21T05:11:04.601Z,1408597864.601 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2014-08-21T05:11:04.613Z,1408597864.613 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2014-08-21T05:11:04.614Z,1408597864.614 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0 2014-08-21T05:11:04.615Z,1408597864.615 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2688 2014-08-21T05:11:04.616Z,1408597864.616 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2014-08-21T05:11:04.617Z,1408597864.617 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0 2014-08-21T05:11:04.617Z,1408597864.617 [logger ThreadHandler](INFO): Protected caller Thread ID is 2689 2014-08-21T05:11:04.619Z,1408597864.619 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2014-08-21T05:11:04.620Z,1408597864.620 [Supervisor](INFO): Looking for Config files in directory: Config/ 2014-08-21T05:11:04.621Z,1408597864.621 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2014-08-21T05:11:04.955Z,1408597864.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2014-08-21T05:11:04.956Z,1408597864.956 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2014-08-21T05:11:05.116Z,1408597865.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2014-08-21T05:11:05.116Z,1408597865.116 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2014-08-21T05:11:05.227Z,1408597865.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2014-08-21T05:11:05.228Z,1408597865.228 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2014-08-21T05:11:05.354Z,1408597865.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2014-08-21T05:11:05.354Z,1408597865.354 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2014-08-21T05:11:05.444Z,1408597865.444 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2014-08-21T05:11:05.590Z,1408597865.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2014-08-21T05:11:05.591Z,1408597865.591 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2014-08-21T05:11:05.679Z,1408597865.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2014-08-21T05:11:05.680Z,1408597865.680 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2014-08-21T05:11:05.863Z,1408597865.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2014-08-21T05:11:05.864Z,1408597865.864 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2014-08-21T05:11:06.165Z,1408597866.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2014-08-21T05:11:06.166Z,1408597866.166 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2014-08-21T05:11:06.471Z,1408597866.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2014-08-21T05:11:06.471Z,1408597866.471 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2014-08-21T05:11:06.965Z,1408597866.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2014-08-21T05:11:06.965Z,1408597866.965 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2014-08-21T05:11:07.158Z,1408597867.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2014-08-21T05:11:07.159Z,1408597867.159 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2014-08-21T05:11:07.266Z,1408597867.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2014-08-21T05:11:07.266Z,1408597867.266 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2014-08-21T05:11:07.667Z,1408597867.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2014-08-21T05:11:07.667Z,1408597867.667 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2014-08-21T05:11:07.780Z,1408597867.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2014-08-21T05:11:07.782Z,1408597867.782 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2014-08-21T05:11:07.783Z,1408597867.783 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2014-08-21T05:11:07.890Z,1408597867.890 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2014-08-21T05:11:08.010Z,1408597868.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2014-08-21T05:11:08.111Z,1408597868.111 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg 2014-08-21T05:11:08.224Z,1408597868.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2014-08-21T05:11:08.324Z,1408597868.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2014-08-21T05:11:08.455Z,1408597868.455 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2014-08-21T05:11:08.605Z,1408597868.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2014-08-21T05:11:08.709Z,1408597868.709 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2014-08-21T05:11:08.804Z,1408597868.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2014-08-21T05:11:08.899Z,1408597868.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2014-08-21T05:11:08.999Z,1408597868.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2014-08-21T05:11:09.114Z,1408597869.114 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2014-08-21T05:11:09.114Z,1408597869.114 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2014-08-21T05:11:09.119Z,1408597869.119 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2014-08-21T05:11:09.169Z,1408597869.169 [VerticalControl](DEBUG): Construct VerticalControl. 2014-08-21T05:11:09.287Z,1408597869.287 [VerticalControl] Loaded 2014-08-21T05:11:09.287Z,1408597869.287 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2014-08-21T05:11:09.288Z,1408597869.288 [HorizontalControl](DEBUG): Construct HorizontalControl. 2014-08-21T05:11:09.390Z,1408597869.390 [HorizontalControl] Loaded 2014-08-21T05:11:09.390Z,1408597869.390 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2014-08-21T05:11:09.391Z,1408597869.391 [SpeedControl](DEBUG): Construct SpeedControl. 2014-08-21T05:11:09.397Z,1408597869.397 [SpeedControl] Loaded 2014-08-21T05:11:09.397Z,1408597869.397 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2014-08-21T05:11:09.398Z,1408597869.398 [LoopControl](DEBUG): Construct LoopControl. 2014-08-21T05:11:09.398Z,1408597869.398 [LoopControl] Loaded 2014-08-21T05:11:09.398Z,1408597869.398 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2014-08-21T05:11:09.399Z,1408597869.399 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2014-08-21T05:11:09.399Z,1408597869.399 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2014-08-21T05:11:09.499Z,1408597869.499 [SBIT](DEBUG): Construct Startup Built In Test. 2014-08-21T05:11:09.510Z,1408597869.510 [SBIT] Loaded 2014-08-21T05:11:09.511Z,1408597869.511 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2014-08-21T05:11:09.511Z,1408597869.511 [IBIT](DEBUG): Construct Initiated Built In Test. 2014-08-21T05:11:09.540Z,1408597869.540 [IBIT] Loaded 2014-08-21T05:11:09.540Z,1408597869.540 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2014-08-21T05:11:09.543Z,1408597869.543 [CBIT](DEBUG): Construct CBIT Built In Test. 2014-08-21T05:11:09.660Z,1408597869.660 [CBIT] Loaded 2014-08-21T05:11:09.661Z,1408597869.661 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2014-08-21T05:11:09.661Z,1408597869.661 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2014-08-21T05:11:09.662Z,1408597869.662 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2014-08-21T05:11:09.680Z,1408597869.680 [DepthRateCalculator] Loaded 2014-08-21T05:11:09.681Z,1408597869.681 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2014-08-21T05:11:09.686Z,1408597869.686 [PitchRateCalculator] Loaded 2014-08-21T05:11:09.686Z,1408597869.686 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2014-08-21T05:11:09.697Z,1408597869.697 [SpeedCalculator] Loaded 2014-08-21T05:11:09.698Z,1408597869.698 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2014-08-21T05:11:09.714Z,1408597869.714 [TempGradientCalculator] Loaded 2014-08-21T05:11:09.714Z,1408597869.714 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2014-08-21T05:11:09.720Z,1408597869.720 [YawRateCalculator] Loaded 2014-08-21T05:11:09.720Z,1408597869.720 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2014-08-21T05:11:09.720Z,1408597869.720 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2014-08-21T05:11:09.721Z,1408597869.721 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2014-08-21T05:11:13.931Z,1408597873.931 [HFRadarCompactModelForecaster] Loaded 2014-08-21T05:11:13.932Z,1408597873.932 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread. 2014-08-21T05:11:15.020Z,1408597875.020 [HFRCMSpaceInterpolator] Loaded 2014-08-21T05:11:15.020Z,1408597875.020 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread. 2014-08-21T05:11:15.022Z,1408597875.022 [HFRCMTimeInterpolator] Loaded 2014-08-21T05:11:15.022Z,1408597875.022 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread. 2014-08-21T05:11:16.051Z,1408597876.051 [HFRCMReconstructedInterpolator] Loaded 2014-08-21T05:11:16.051Z,1408597876.051 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread. 2014-08-21T05:11:16.063Z,1408597876.063 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded 2014-08-21T05:11:16.063Z,1408597876.063 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread. 2014-08-21T05:11:16.064Z,1408597876.064 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2014-08-21T05:11:16.064Z,1408597876.064 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2014-08-21T05:11:16.291Z,1408597876.291 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2014-08-21T05:11:16.292Z,1408597876.292 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2014-08-21T05:11:16.371Z,1408597876.371 [DeadReckonUsingMultipleVelocitySourcesVector] Loaded 2014-08-21T05:11:16.372Z,1408597876.372 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySourcesVector" handled in the control thread. 2014-08-21T05:11:16.427Z,1408597876.427 [DeadReckonUsingMultipleVelocitySources] Loaded 2014-08-21T05:11:16.427Z,1408597876.427 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2014-08-21T05:11:16.472Z,1408597876.472 [DeadReckonWithRespectToWater] Loaded 2014-08-21T05:11:16.472Z,1408597876.472 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread. 2014-08-21T05:11:16.517Z,1408597876.517 [DeadReckonWithRespectToSeafloor] Loaded 2014-08-21T05:11:16.518Z,1408597876.518 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2014-08-21T05:11:16.577Z,1408597876.577 [DeadReckonUsingDVLWaterTrack] Loaded 2014-08-21T05:11:16.577Z,1408597876.577 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread. 2014-08-21T05:11:16.623Z,1408597876.623 [DeadReckonUsingCompactModelForecast] Loaded 2014-08-21T05:11:16.623Z,1408597876.623 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread. 2014-08-21T05:11:16.640Z,1408597876.640 [NavChart] Loaded 2014-08-21T05:11:16.641Z,1408597876.641 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2014-08-21T05:11:16.645Z,1408597876.645 [UniversalFixResidualReporter] Loaded 2014-08-21T05:11:16.645Z,1408597876.645 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2014-08-21T05:11:16.646Z,1408597876.646 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2014-08-21T05:11:16.646Z,1408597876.646 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2014-08-21T05:11:16.652Z,1408597876.652 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2014-08-21T05:11:16.653Z,1408597876.653 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2014-08-21T05:11:16.828Z,1408597876.828 [CTD_NeilBrown] Loaded 2014-08-21T05:11:16.828Z,1408597876.828 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2014-08-21T05:11:16.830Z,1408597876.830 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406C64E0 2014-08-21T05:11:16.830Z,1408597876.830 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2771 2014-08-21T05:11:16.845Z,1408597876.845 [PAR_Licor] Loaded 2014-08-21T05:11:16.846Z,1408597876.846 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2014-08-21T05:11:16.881Z,1408597876.881 [WetLabsBB2FL] Loaded 2014-08-21T05:11:16.881Z,1408597876.881 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2014-08-21T05:11:16.882Z,1408597876.882 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406F64E0 2014-08-21T05:11:16.883Z,1408597876.883 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2772 2014-08-21T05:11:16.883Z,1408597876.883 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2014-08-21T05:11:16.884Z,1408597876.884 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2014-08-21T05:11:17.174Z,1408597877.174 [AHRS_sp3003D] Loaded 2014-08-21T05:11:17.174Z,1408597877.174 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2014-08-21T05:11:17.432Z,1408597877.432 [Batt_Ocean_Server] Loaded 2014-08-21T05:11:17.433Z,1408597877.433 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2014-08-21T05:11:17.508Z,1408597877.508 [DataOverHttps] Loaded 2014-08-21T05:11:17.508Z,1408597877.508 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2014-08-21T05:11:17.522Z,1408597877.522 [Depth_Keller] Loaded 2014-08-21T05:11:17.522Z,1408597877.522 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2014-08-21T05:11:17.654Z,1408597877.654 [DVL_micro] Loaded 2014-08-21T05:11:17.655Z,1408597877.655 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2014-08-21T05:11:17.747Z,1408597877.747 [NAL9602] Loaded 2014-08-21T05:11:17.748Z,1408597877.748 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2014-08-21T05:11:17.789Z,1408597877.789 [Onboard] Loaded 2014-08-21T05:11:17.790Z,1408597877.790 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2014-08-21T05:11:17.797Z,1408597877.797 [Radio_Freewave] Loaded 2014-08-21T05:11:17.797Z,1408597877.797 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread. 2014-08-21T05:11:17.798Z,1408597877.798 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 409314E0 2014-08-21T05:11:17.799Z,1408597877.799 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 2773 2014-08-21T05:11:17.924Z,1408597877.924 [DAT] Loaded 2014-08-21T05:11:17.924Z,1408597877.924 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2014-08-21T05:11:17.931Z,1408597877.931 [SCPI] Loaded 2014-08-21T05:11:17.931Z,1408597877.931 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2014-08-21T05:11:17.932Z,1408597877.932 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2014-08-21T05:11:17.932Z,1408597877.932 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2014-08-21T05:11:18.031Z,1408597878.031 [BuoyancyServo] Loaded 2014-08-21T05:11:18.031Z,1408597878.031 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2014-08-21T05:11:18.043Z,1408597878.043 [ElevatorServo] Loaded 2014-08-21T05:11:18.044Z,1408597878.044 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2014-08-21T05:11:18.055Z,1408597878.055 [MassServo] Loaded 2014-08-21T05:11:18.055Z,1408597878.055 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2014-08-21T05:11:18.067Z,1408597878.067 [RudderServo] Loaded 2014-08-21T05:11:18.067Z,1408597878.067 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2014-08-21T05:11:18.079Z,1408597878.079 [ThrusterServo] Loaded 2014-08-21T05:11:18.079Z,1408597878.079 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2014-08-21T05:11:18.079Z,1408597878.079 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2014-08-21T05:11:18.080Z,1408597878.080 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2014-08-21T05:11:18.239Z,1408597878.239 [InternalSim] Loaded 2014-08-21T05:11:18.240Z,1408597878.240 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2014-08-21T05:11:18.240Z,1408597878.240 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2014-08-21T05:11:18.241Z,1408597878.241 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2014-08-21T05:11:18.264Z,1408597878.264 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2014-08-21T05:11:18.266Z,1408597878.266 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2014-08-21T05:11:18.267Z,1408597878.267 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2014-08-21T05:11:18.274Z,1408597878.274 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2014-08-21T05:11:18.275Z,1408597878.275 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409E24E0 2014-08-21T05:11:18.276Z,1408597878.276 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2774 2014-08-21T05:11:18.281Z,1408597878.281 [Supervisor](INFO): Main Thread ID is 768 2014-08-21T05:11:18.281Z,1408597878.281 [Supervisor](DEBUG): Running supervisor. 2014-08-21T05:11:18.282Z,1408597878.282 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2775 2014-08-21T05:11:18.286Z,1408597878.286 [controlThread ThreadHandler](INFO): Handler Thread ID is 2776 2014-08-21T05:11:18.287Z,1408597878.287 [controlThread](DEBUG): Initializing ControlThread 2014-08-21T05:11:18.288Z,1408597878.288 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2014-08-21T05:11:18.289Z,1408597878.289 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2014-08-21T05:11:18.290Z,1408597878.290 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2014-08-21T05:11:18.291Z,1408597878.291 [LoopControl](DEBUG): Initialize LoopControlComponent. 2014-08-21T05:11:18.291Z,1408597878.291 [SBIT](INFO): Initialize SBIT Component. 2014-08-21T05:11:18.291Z,1408597878.291 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11562 2014-08-21T05:11:18.292Z,1408597878.292 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2014-08-21T05:11:18.292Z,1408597878.292 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 2014-08-21T05:11:18.293Z,1408597878.293 [IBIT](INFO): Initialize IBIT Component. 2014-08-21T05:11:18.293Z,1408597878.293 [CBIT](DEBUG): Initialize CBIT Component. 2014-08-21T05:11:18.294Z,1408597878.294 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2014-08-21T05:11:18.295Z,1408597878.295 [logger ThreadHandler](INFO): Handler Thread ID is 2777 2014-08-21T05:11:18.313Z,1408597878.313 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2778 2014-08-21T05:11:18.314Z,1408597878.314 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2014-08-21T05:11:18.317Z,1408597878.317 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2014-08-21T05:11:18.317Z,1408597878.317 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2014-08-21T05:11:18.317Z,1408597878.317 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2014-08-21T05:11:18.318Z,1408597878.318 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2014-08-21T05:11:18.318Z,1408597878.318 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2014-08-21T05:11:18.325Z,1408597878.325 [HFRadarCompactModelForecaster](DEBUG): Found new ECs! 2014-08-21T05:11:18.337Z,1408597878.337 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2014-08-21T05:11:18.346Z,1408597878.346 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2780 2014-08-21T05:11:18.347Z,1408597878.347 [WetLabsBB2FL](INFO): Powering down 2014-08-21T05:11:18.377Z,1408597878.377 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 2781 2014-08-21T05:11:18.417Z,1408597878.417 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2783 2014-08-21T05:11:18.421Z,1408597878.421 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2014-08-21T05:11:18.422Z,1408597878.422 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2014-08-21T05:11:18.422Z,1408597878.422 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2014-08-21T05:11:18.422Z,1408597878.422 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2014-08-21T05:11:18.422Z,1408597878.422 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2014-08-21T05:11:18.423Z,1408597878.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2014-08-21T05:11:18.423Z,1408597878.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2014-08-21T05:11:18.423Z,1408597878.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2014-08-21T05:11:18.423Z,1408597878.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2014-08-21T05:11:18.699Z,1408597878.699 [Radio_Freewave](INFO): Powering up 2014-08-21T05:11:19.504Z,1408597879.504 [Radio_Freewave](IMPORTANT): Starting PPPD with command:/sbin/pppd nocrtscts /dev/ttyS1 noauth 115200 10.1.1.2:10.1.1.1 persist maxfail 0 2014-08-21T05:11:27.598Z,1408597887.598 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201408210200 published 2014-08-21T05:11:27.609Z,1408597887.609 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 0. 2014-08-21T05:11:27.619Z,1408597887.619 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component. 2014-08-21T05:11:27.620Z,1408597887.620 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s. 2014-08-21T05:11:27.622Z,1408597887.622 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component. 2014-08-21T05:11:27.622Z,1408597887.622 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Initializing DeadReckonUsingMultipleVelocitySourcesVector component. 2014-08-21T05:11:27.631Z,1408597887.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2014-08-21T05:11:27.631Z,1408597887.631 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component. 2014-08-21T05:11:27.632Z,1408597887.632 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2014-08-21T05:11:27.632Z,1408597887.632 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component. 2014-08-21T05:11:27.633Z,1408597887.633 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component. 2014-08-21T05:11:27.633Z,1408597887.633 [NavChart](DEBUG): Initialize NavChart Navigation. 2014-08-21T05:11:27.634Z,1408597887.634 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2014-08-21T05:11:29.946Z,1408597889.946 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2014-08-21T05:11:29.978Z,1408597889.978 [InternalSim](DEBUG): InternalSim initializing... 2014-08-21T05:11:30.092Z,1408597890.092 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2014-08-21T05:11:30.129Z,1408597890.129 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2014-08-21T05:11:30.179Z,1408597890.179 [MissionManager](DEBUG): 2014-08-21T05:11:30.187Z,1408597890.187 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2014-08-21T05:11:30.257Z,1408597890.257 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h 2014-08-21T05:11:30.273Z,1408597890.273 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h 2014-08-21T05:11:30.278Z,1408597890.278 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2014-08-21T05:11:30.335Z,1408597890.335 [Default:CheckIn:A.SetSpeed](DEBUG): Construct. 2014-08-21T05:11:30.341Z,1408597890.341 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2014-08-21T05:11:30.361Z,1408597890.361 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct. 2014-08-21T05:11:30.388Z,1408597890.388 [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 2014-08-21T05:11:30.402Z,1408597890.402 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,PAR_Licor,AHRS_sp3003D,Batt_Ocean_Server,DataOverHttps,Depth_Keller,DVL_micro,NAL9602,Onboard,DAT,SCPI,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,DeadReckonUsingMultipleVelocitySourcesVector,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2014-08-21T05:11:30.578Z,1408597890.578 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2014-08-21T05:11:30.766Z,1408597890.766 [DVL_micro](INFO): Initializing 2014-08-21T05:11:30.794Z,1408597890.794 [NAL9602](INFO): Powering up NAL9602 2014-08-21T05:11:30.848Z,1408597890.848 [DAT](INFO): Powering up 2014-08-21T05:11:30.848Z,1408597890.848 [DAT](DEBUG): Initializing DAT. 2014-08-21T05:11:37.803Z,1408597897.803 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:37.832Z,1408597897.832 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:37.848Z,1408597897.848 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:37.854Z,1408597897.854 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:37.895Z,1408597897.895 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:37.902Z,1408597897.902 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:38.036Z,1408597898.036 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2014-08-21T05:11:38.043Z,1408597898.043 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2014-08-21T05:11:38.050Z,1408597898.050 [ElevatorServo](DEBUG): Initializing EZServoServo. 2014-08-21T05:11:38.055Z,1408597898.055 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2014-08-21T05:11:38.087Z,1408597898.087 [MassServo](DEBUG): Initializing EZServoServo. 2014-08-21T05:11:38.095Z,1408597898.095 [MassServo](DEBUG): Initializing MassServo. 2014-08-21T05:11:38.101Z,1408597898.101 [RudderServo](DEBUG): Initializing EZServoServo. 2014-08-21T05:11:38.107Z,1408597898.107 [RudderServo](DEBUG): Initializing RudderServo. 2014-08-21T05:11:38.125Z,1408597898.125 [ThrusterServo](DEBUG): Initializing EZServoServo. 2014-08-21T05:11:38.131Z,1408597898.131 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2014-08-21T05:11:39.685Z,1408597899.685 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:39.709Z,1408597899.709 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:39.711Z,1408597899.711 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:39.711Z,1408597899.711 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:39.712Z,1408597899.712 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:39.713Z,1408597899.713 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame. 2014-08-21T05:11:40.007Z,1408597900.007 [SBIT](IMPORTANT): Beginning Startup BIT 2014-08-21T05:11:40.010Z,1408597900.010 [CBIT](IMPORTANT): Beginning GF scan 2014-08-21T05:11:41.656Z,1408597901.656 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003706 2014-08-21T05:11:41.717Z,1408597901.717 [NAL9602](INFO): NAL9602 initialized 2014-08-21T05:11:43.515Z,1408597903.515 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:44.491Z,1408597904.491 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:45.007Z,1408597905.007 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:45.640Z,1408597905.640 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:46.202Z,1408597906.202 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:46.223Z,1408597906.223 [DAT](INFO): entering command mode 2014-08-21T05:11:46.878Z,1408597906.878 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:47.497Z,1408597907.497 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:47.547Z,1408597907.547 [DAT](INFO): checking for command mode acknowledgment 2014-08-21T05:11:47.548Z,1408597907.548 [DAT](INFO): read user prompt: user:1> 2014-08-21T05:11:47.548Z,1408597907.548 [DAT](INFO): command mode acknowledged 2014-08-21T05:11:48.156Z,1408597908.156 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:48.190Z,1408597908.190 [DAT](INFO): setting local address to 2 2014-08-21T05:11:48.671Z,1408597908.671 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:49.234Z,1408597909.234 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:49.291Z,1408597909.291 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:11:49.291Z,1408597909.291 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:11:49.776Z,1408597909.776 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:49.804Z,1408597909.804 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:11:49.804Z,1408597909.804 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:11:50.280Z,1408597910.280 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:50.301Z,1408597910.301 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: LocalAddr | 2 2014-08-21T05:11:50.301Z,1408597910.301 [DAT](FAULT): failed to initialize; deviceResponse_ available: user:2>dr | 2 2014-08-21T05:11:50.301Z,1408597910.301 [DAT] Communications Fault, FailCount= 1 2014-08-21T05:11:50.301Z,1408597910.301 [DAT](ERROR): Communications Fault 2014-08-21T05:11:50.422Z,1408597910.422 [CBIT](ERROR): Communications Fault in component: DAT 2014-08-21T05:11:50.893Z,1408597910.893 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:50.956Z,1408597910.956 [DAT](INFO): Powering down 2014-08-21T05:11:51.610Z,1408597911.610 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:52.086Z,1408597912.086 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:52.209Z,1408597912.209 [CBIT](INFO): Clearing failed state for component DAT 2014-08-21T05:11:52.209Z,1408597912.209 [DAT] No Fault, FailCount= 1 2014-08-21T05:11:52.563Z,1408597912.563 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:53.076Z,1408597913.076 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:53.649Z,1408597913.649 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:54.142Z,1408597914.142 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:54.156Z,1408597914.156 [DAT](INFO): Powering up 2014-08-21T05:11:54.157Z,1408597914.157 [DAT](DEBUG): Initializing DAT. 2014-08-21T05:11:54.626Z,1408597914.626 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:55.068Z,1408597915.068 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:55.680Z,1408597915.680 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:56.714Z,1408597916.714 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:57.239Z,1408597917.239 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:57.767Z,1408597917.767 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:58.288Z,1408597918.288 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:58.809Z,1408597918.809 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:59.398Z,1408597919.398 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:11:59.967Z,1408597919.967 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:00.500Z,1408597920.500 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:01.153Z,1408597921.153 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:01.729Z,1408597921.729 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:02.423Z,1408597922.423 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:02.907Z,1408597922.907 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:03.404Z,1408597923.404 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:03.953Z,1408597923.953 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:04.442Z,1408597924.442 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:04.910Z,1408597924.910 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:05.363Z,1408597925.363 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:05.949Z,1408597925.949 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:06.473Z,1408597926.473 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:06.974Z,1408597926.974 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:07.113Z,1408597927.113 [CBIT](IMPORTANT): No ground fault detected 2014-08-21T05:12:07.482Z,1408597927.482 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:08.035Z,1408597928.035 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:08.484Z,1408597928.484 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:08.525Z,1408597928.525 [DAT](INFO): entering command mode 2014-08-21T05:12:09.025Z,1408597929.025 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:09.031Z,1408597929.031 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:81 Calculated:64 2014-08-21T05:12:09.031Z,1408597929.031 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -757 -190 -147 -259 2 3 3 2 -814.1 9.8 2 -757.2 -312.8 -364.8 2 -814 92 -364 2 -757 -312 -364 2 3.58 -0.69 28.9 -3.0 20.8 0.005 35.0 1489 81 2014-08-21T05:12:09.526Z,1408597929.526 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:09.561Z,1408597929.561 [DAT](INFO): checking for command mode acknowledgment 2014-08-21T05:12:09.561Z,1408597929.561 [DAT](INFO): read user prompt: user:1> 2014-08-21T05:12:09.561Z,1408597929.561 [DAT](INFO): command mode acknowledged 2014-08-21T05:12:10.026Z,1408597930.026 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:10.059Z,1408597930.059 [DAT](INFO): setting local address to 2 2014-08-21T05:12:10.527Z,1408597930.527 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:10.997Z,1408597930.997 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:11.021Z,1408597931.021 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:11.021Z,1408597931.021 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:11.562Z,1408597931.562 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:11.589Z,1408597931.589 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:11.589Z,1408597931.589 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:12.041Z,1408597932.041 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:12.087Z,1408597932.087 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:12.087Z,1408597932.087 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:12.534Z,1408597932.534 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:12.591Z,1408597932.591 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:12.591Z,1408597932.591 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:13.052Z,1408597933.052 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:13.084Z,1408597933.084 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:13.084Z,1408597933.084 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:13.536Z,1408597933.536 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:13.561Z,1408597933.561 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: LocalAddr | 2 2014-08-21T05:12:13.561Z,1408597933.561 [DAT](FAULT): failed to initialize; deviceResponse_ available: user:2>dr | 2 2014-08-21T05:12:13.561Z,1408597933.561 [DAT] Communications Fault, FailCount= 2 2014-08-21T05:12:13.561Z,1408597933.561 [DAT](ERROR): Communications Fault 2014-08-21T05:12:13.699Z,1408597933.699 [CBIT](ERROR): Communications Fault in component: DAT 2014-08-21T05:12:14.133Z,1408597934.133 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:14.166Z,1408597934.166 [DAT](INFO): Powering down 2014-08-21T05:12:14.730Z,1408597934.730 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:15.175Z,1408597935.175 [Batt_Ocean_Server](ERROR): Battery controller B communication timeout. Re-initializing 2014-08-21T05:12:15.178Z,1408597935.178 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:15.270Z,1408597935.270 [CBIT](INFO): Clearing failed state for component DAT 2014-08-21T05:12:15.270Z,1408597935.270 [DAT] No Fault, FailCount= 2 2014-08-21T05:12:15.648Z,1408597935.648 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2014-08-21T05:12:15.648Z,1408597935.648 [Batt_Ocean_Server](ERROR): Communications Fault 2014-08-21T05:12:17.216Z,1408597937.216 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2014-08-21T05:12:17.217Z,1408597937.217 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2014-08-21T05:12:17.222Z,1408597937.222 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:17.319Z,1408597937.319 [DAT](INFO): Powering up 2014-08-21T05:12:17.319Z,1408597937.319 [DAT](DEBUG): Initializing DAT. 2014-08-21T05:12:17.424Z,1408597937.424 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2014-08-21T05:12:17.866Z,1408597937.866 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:17.952Z,1408597937.952 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2014-08-21T05:12:17.952Z,1408597937.952 [Batt_Ocean_Server] No Fault, FailCount= 1 2014-08-21T05:12:20.041Z,1408597940.041 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2014-08-21T05:12:20.074Z,1408597940.074 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:20.512Z,1408597940.512 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:20.935Z,1408597940.935 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:21.364Z,1408597941.364 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:21.768Z,1408597941.768 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:22.197Z,1408597942.197 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:22.771Z,1408597942.771 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:23.607Z,1408597943.607 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:24.141Z,1408597944.141 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:24.613Z,1408597944.613 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:25.199Z,1408597945.199 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:25.775Z,1408597945.775 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:26.441Z,1408597946.441 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:26.852Z,1408597946.852 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:27.305Z,1408597947.305 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:27.723Z,1408597947.723 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:28.162Z,1408597948.162 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:28.615Z,1408597948.615 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:29.060Z,1408597949.060 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:29.553Z,1408597949.553 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:29.969Z,1408597949.969 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:30.551Z,1408597950.551 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:30.959Z,1408597950.959 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:31.395Z,1408597951.395 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:31.416Z,1408597951.416 [DAT](INFO): entering command mode 2014-08-21T05:12:32.281Z,1408597952.281 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:32.708Z,1408597952.708 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:32.716Z,1408597952.716 [DAT](INFO): checking for command mode acknowledgment 2014-08-21T05:12:32.716Z,1408597952.716 [DAT](INFO): read user prompt: user:1> 2014-08-21T05:12:32.716Z,1408597952.716 [DAT](INFO): command mode acknowledged 2014-08-21T05:12:33.138Z,1408597953.138 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:33.196Z,1408597953.196 [DAT](INFO): setting local address to 2 2014-08-21T05:12:33.623Z,1408597953.623 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:34.047Z,1408597954.047 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:34.057Z,1408597954.057 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:34.057Z,1408597954.057 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:34.125Z,1408597954.125 [SBIT](IMPORTANT): SBIT PASSED 2014-08-21T05:12:34.488Z,1408597954.488 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:34.495Z,1408597954.495 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:34.495Z,1408597954.495 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:34.510Z,1408597954.510 [MissionManager](IMPORTANT): Started mission Startup 2014-08-21T05:12:34.510Z,1408597954.510 [Startup] Running Loop=1 2014-08-21T05:12:34.510Z,1408597954.510 [Startup](INFO): Aggregate::initialize Startup 2014-08-21T05:12:34.511Z,1408597954.511 [Startup:A.GoToSurface] Running Loop=1 2014-08-21T05:12:34.511Z,1408597954.511 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-08-21T05:12:34.512Z,1408597954.512 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2014-08-21T05:12:34.512Z,1408597954.512 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2014-08-21T05:12:34.513Z,1408597954.513 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s. 2014-08-21T05:12:34.555Z,1408597954.555 [Startup:StartupSatComms] Running Loop=1 2014-08-21T05:12:34.555Z,1408597954.555 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2014-08-21T05:12:34.556Z,1408597954.556 [Startup:StartupSatComms:A] Running Loop=1 2014-08-21T05:12:34.949Z,1408597954.949 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:34.974Z,1408597954.974 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:34.974Z,1408597954.974 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:35.036Z,1408597955.036 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2014-08-21T05:12:35.550Z,1408597955.550 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:35.555Z,1408597955.555 [NAL9602](IMPORTANT): GPS fix at: 1408597954.00 2014-08-21T05:12:35.562Z,1408597955.562 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:35.562Z,1408597955.562 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:35.655Z,1408597955.655 [Startup:StartupSatComms:A] Stopped 2014-08-21T05:12:35.655Z,1408597955.655 [Startup:StartupSatComms:B] Running Loop=1 2014-08-21T05:12:36.079Z,1408597956.079 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:36.108Z,1408597956.108 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:36.108Z,1408597956.108 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:36.116Z,1408597956.116 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:36.118Z,1408597956.118 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:36.134Z,1408597956.134 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2014-08-21T05:12:37.346Z,1408597957.346 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:37.377Z,1408597957.377 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: LocalAddr | 2 2014-08-21T05:12:37.377Z,1408597957.377 [DAT](FAULT): failed to initialize; deviceResponse_ available: user:2>dr | 2 2014-08-21T05:12:37.377Z,1408597957.377 [DAT] Communications Fault, FailCount= 3 2014-08-21T05:12:37.377Z,1408597957.377 [DAT](ERROR): Communications Fault 2014-08-21T05:12:37.385Z,1408597957.385 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:37.388Z,1408597957.388 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:37.479Z,1408597957.479 [CBIT](ERROR): Communications Fault in component: DAT 2014-08-21T05:12:37.909Z,1408597957.909 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:37.916Z,1408597957.916 [DAT](INFO): Powering down 2014-08-21T05:12:38.062Z,1408597958.062 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:38.064Z,1408597958.064 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:39.458Z,1408597959.458 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:39.497Z,1408597959.497 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:39.503Z,1408597959.503 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:40.044Z,1408597960.044 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20140821T050451/Courier0004.lzma 2014-08-21T05:12:40.052Z,1408597960.052 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:40.106Z,1408597960.106 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:40.112Z,1408597960.112 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:40.215Z,1408597960.215 [CBIT](INFO): Clearing failed state for component DAT 2014-08-21T05:12:40.215Z,1408597960.215 [DAT] No Fault, FailCount= 3 2014-08-21T05:12:40.713Z,1408597960.713 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:40.803Z,1408597960.803 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:40.809Z,1408597960.809 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:41.277Z,1408597961.277 [DataOverHttps](INFO): Moved sent file to Logs/20140821T050451/Courier0004.lzma.bak 2014-08-21T05:12:41.277Z,1408597961.277 [DataOverHttps](INFO): SBD MOMSN=1090470 2014-08-21T05:12:41.283Z,1408597961.283 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:41.293Z,1408597961.293 [DAT](INFO): Powering up 2014-08-21T05:12:41.293Z,1408597961.293 [DAT](DEBUG): Initializing DAT. 2014-08-21T05:12:41.367Z,1408597961.367 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:41.369Z,1408597961.369 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:42.196Z,1408597962.196 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:42.233Z,1408597962.233 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:42.239Z,1408597962.239 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:42.705Z,1408597962.705 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:42.737Z,1408597962.737 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:42.738Z,1408597962.738 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:43.643Z,1408597963.643 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:43.644Z,1408597963.644 [NAL9602](INFO): Powering down 2014-08-21T05:12:43.659Z,1408597963.659 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:43.685Z,1408597963.685 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:44.152Z,1408597964.152 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:44.205Z,1408597964.205 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:44.207Z,1408597964.207 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:44.637Z,1408597964.637 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:44.651Z,1408597964.651 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:44.653Z,1408597964.653 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:45.133Z,1408597965.133 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:45.158Z,1408597965.158 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:45.160Z,1408597965.160 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:45.598Z,1408597965.598 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:45.611Z,1408597965.611 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:45.613Z,1408597965.613 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:46.078Z,1408597966.078 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:46.112Z,1408597966.112 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:46.118Z,1408597966.118 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:47.111Z,1408597967.111 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:47.124Z,1408597967.124 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:47.127Z,1408597967.127 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:47.558Z,1408597967.558 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20140821T051104/Courier0000.lzma 2014-08-21T05:12:47.568Z,1408597967.568 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:47.652Z,1408597967.652 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:47.654Z,1408597967.654 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:48.085Z,1408597968.085 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:48.098Z,1408597968.098 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:48.119Z,1408597968.119 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:48.560Z,1408597968.560 [DataOverHttps](INFO): Moved sent file to Logs/20140821T051104/Courier0000.lzma.bak 2014-08-21T05:12:48.560Z,1408597968.560 [DataOverHttps](INFO): SBD MOMSN=1090474 2014-08-21T05:12:48.586Z,1408597968.586 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:48.655Z,1408597968.655 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:48.657Z,1408597968.657 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:49.334Z,1408597969.334 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:49.348Z,1408597969.348 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:49.365Z,1408597969.365 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:49.824Z,1408597969.824 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:49.846Z,1408597969.846 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:49.868Z,1408597969.868 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:51.310Z,1408597971.310 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:51.408Z,1408597971.408 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:51.418Z,1408597971.418 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:51.963Z,1408597971.963 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:51.978Z,1408597971.978 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:51.980Z,1408597971.980 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:52.428Z,1408597972.428 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:52.453Z,1408597972.453 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:52.455Z,1408597972.455 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:52.876Z,1408597972.876 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:52.890Z,1408597972.890 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:52.911Z,1408597972.911 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:53.333Z,1408597973.333 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:53.358Z,1408597973.358 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:53.361Z,1408597973.361 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:53.790Z,1408597973.790 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:53.807Z,1408597973.807 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:53.825Z,1408597973.825 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:54.888Z,1408597974.888 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:54.985Z,1408597974.985 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:54.991Z,1408597974.991 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:55.488Z,1408597975.488 [DataOverHttps](INFO): Sending 466 bytes from file Logs/20140821T050451/Express0005.lzma 2014-08-21T05:12:55.517Z,1408597975.517 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:55.560Z,1408597975.560 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:55.562Z,1408597975.562 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:56.090Z,1408597976.090 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:56.108Z,1408597976.108 [DAT](INFO): entering command mode 2014-08-21T05:12:56.119Z,1408597976.119 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:56.154Z,1408597976.154 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:56.583Z,1408597976.583 [DataOverHttps](INFO): Moved sent file to Logs/20140821T050451/Express0005.lzma.bak 2014-08-21T05:12:56.583Z,1408597976.583 [DataOverHttps](INFO): SBD MOMSN=1090477 2014-08-21T05:12:56.588Z,1408597976.588 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:56.601Z,1408597976.601 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:56.603Z,1408597976.603 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:57.324Z,1408597977.324 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:57.338Z,1408597977.338 [DAT](INFO): checking for command mode acknowledgment 2014-08-21T05:12:57.339Z,1408597977.339 [DAT](INFO): read user prompt: user:1> 2014-08-21T05:12:57.339Z,1408597977.339 [DAT](INFO): command mode acknowledged 2014-08-21T05:12:57.347Z,1408597977.347 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:57.349Z,1408597977.349 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:57.802Z,1408597977.802 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:57.810Z,1408597977.810 [DAT](INFO): setting local address to 2 2014-08-21T05:12:57.827Z,1408597977.827 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:57.829Z,1408597977.829 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:58.743Z,1408597978.743 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:58.777Z,1408597978.777 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:58.779Z,1408597978.779 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:59.252Z,1408597979.252 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:59.259Z,1408597979.259 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:59.259Z,1408597979.259 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:59.267Z,1408597979.267 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:59.269Z,1408597979.269 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:59.704Z,1408597979.704 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:12:59.715Z,1408597979.715 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:12:59.715Z,1408597979.715 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:12:59.723Z,1408597979.723 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:12:59.725Z,1408597979.725 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:00.188Z,1408597980.188 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:00.205Z,1408597980.205 [DAT](INFO): checking for local address setting acknowledgment 2014-08-21T05:13:00.205Z,1408597980.205 [DAT](INFO): cannot read until LocalAddr | 2014-08-21T05:13:00.212Z,1408597980.212 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:00.214Z,1408597980.214 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:00.632Z,1408597980.632 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:00.639Z,1408597980.639 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: LocalAddr | 2 2014-08-21T05:13:00.639Z,1408597980.639 [DAT](FAULT): failed to initialize; deviceResponse_ available: user:2>dr | 2 2014-08-21T05:13:00.640Z,1408597980.640 [DAT] Communications Fault, FailCount= 4 2014-08-21T05:13:00.640Z,1408597980.640 [DAT](ERROR): Communications Fault 2014-08-21T05:13:00.647Z,1408597980.647 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:00.649Z,1408597980.649 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:00.762Z,1408597980.762 [CBIT](ERROR): Communications Fault in component: DAT 2014-08-21T05:13:01.210Z,1408597981.210 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:01.217Z,1408597981.217 [DAT](INFO): Powering down 2014-08-21T05:13:01.348Z,1408597981.348 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:01.350Z,1408597981.350 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:02.248Z,1408597982.248 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:02.285Z,1408597982.285 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:02.288Z,1408597982.288 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:02.719Z,1408597982.719 [DataOverHttps](INFO): Sending 979 bytes from file Logs/20140821T051104/Express0001.lzma 2014-08-21T05:13:02.732Z,1408597982.732 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:02.767Z,1408597982.767 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:02.769Z,1408597982.769 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:02.792Z,1408597982.792 [CBIT](INFO): Clearing failed state for component DAT 2014-08-21T05:13:02.792Z,1408597982.792 [DAT] No Fault, FailCount= 4 2014-08-21T05:13:03.173Z,1408597983.173 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:03.186Z,1408597983.186 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:03.188Z,1408597983.188 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:03.600Z,1408597983.600 [DataOverHttps](INFO): Moved sent file to Logs/20140821T051104/Express0001.lzma.bak 2014-08-21T05:13:03.600Z,1408597983.600 [DataOverHttps](INFO): SBD MOMSN=1090489 2014-08-21T05:13:03.628Z,1408597983.628 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:03.642Z,1408597983.642 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:03.644Z,1408597983.644 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:04.352Z,1408597984.352 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:04.358Z,1408597984.358 [DAT](INFO): Powering up 2014-08-21T05:13:04.359Z,1408597984.359 [DAT](DEBUG): Initializing DAT. 2014-08-21T05:13:04.416Z,1408597984.416 [HFRadarCompactModelForecaster](DEBUG): Did not find new historical expansion coefficients. 2014-08-21T05:13:04.418Z,1408597984.418 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:04.420Z,1408597984.420 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:04.452Z,1408597984.452 [Startup:StartupSatComms:B] Stopped 2014-08-21T05:13:04.452Z,1408597984.452 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2014-08-21T05:13:04.453Z,1408597984.453 [Startup:StartupSatComms] Stopped 2014-08-21T05:13:04.453Z,1408597984.453 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2014-08-21T05:13:04.454Z,1408597984.454 [Startup](INFO): Completed Startup 2014-08-21T05:13:04.454Z,1408597984.454 [Startup] Stopped 2014-08-21T05:13:04.454Z,1408597984.454 [Startup](INFO): Aggregate::uninitialize Startup 2014-08-21T05:13:04.454Z,1408597984.454 [Startup:A.GoToSurface] Stopped 2014-08-21T05:13:04.454Z,1408597984.454 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-08-21T05:13:04.911Z,1408597984.911 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:04.924Z,1408597984.924 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:04.926Z,1408597984.926 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:04.941Z,1408597984.941 [MissionManager](IMPORTANT): Started mission Default 2014-08-21T05:13:04.941Z,1408597984.941 [Default] Running Loop=1 2014-08-21T05:13:04.941Z,1408597984.941 [Default](INFO): Aggregate::initialize Default 2014-08-21T05:13:04.941Z,1408597984.941 [Default:B.GoToSurface] Running Loop=1 2014-08-21T05:13:04.942Z,1408597984.942 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-08-21T05:13:04.942Z,1408597984.942 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2014-08-21T05:13:04.942Z,1408597984.942 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2014-08-21T05:13:04.943Z,1408597984.943 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s. 2014-08-21T05:13:04.943Z,1408597984.943 [Default:StartClock] Running Loop=1 2014-08-21T05:13:04.943Z,1408597984.943 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock 2014-08-21T05:13:04.943Z,1408597984.943 [Default:StartClock:A] Running Loop=1 2014-08-21T05:13:04.968Z,1408597984.968 [Default:StartClock:A] Stopped 2014-08-21T05:13:04.968Z,1408597984.968 [Default:StartClock](INFO): Completed Default:StartClock 2014-08-21T05:13:04.968Z,1408597984.968 [Default:StartClock] Stopped 2014-08-21T05:13:04.968Z,1408597984.968 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock 2014-08-21T05:13:05.376Z,1408597985.376 [Batt_Ocean_Server](ERROR): Battery controller B communication timeout. Re-initializing 2014-08-21T05:13:05.861Z,1408597985.861 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:05.901Z,1408597985.901 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:05.904Z,1408597985.904 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:05.938Z,1408597985.938 [Default:WaitAtTheSurface] Running Loop=1 2014-08-21T05:13:05.939Z,1408597985.939 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface 2014-08-21T05:13:05.939Z,1408597985.939 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1 2014-08-21T05:13:05.939Z,1408597985.939 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize. 2014-08-21T05:13:06.322Z,1408597986.322 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2014-08-21T05:13:06.322Z,1408597986.322 [Batt_Ocean_Server](ERROR): Communications Fault 2014-08-21T05:13:07.935Z,1408597987.935 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2014-08-21T05:13:07.964Z,1408597987.964 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:07.998Z,1408597987.998 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:08.001Z,1408597988.001 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:08.026Z,1408597988.026 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1 2014-08-21T05:13:08.061Z,1408597988.061 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2014-08-21T05:13:11.377Z,1408597991.377 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:11.421Z,1408597991.421 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:11.422Z,1408597991.422 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:11.488Z,1408597991.488 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2014-08-21T05:13:11.488Z,1408597991.488 [Batt_Ocean_Server] No Fault, FailCount= 2 2014-08-21T05:13:18.116Z,1408597998.116 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2014-08-21T05:13:18.156Z,1408597998.156 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:18.173Z,1408597998.173 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:18.175Z,1408597998.175 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:21.373Z,1408598001.373 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:21.438Z,1408598001.438 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:21.440Z,1408598001.440 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:26.492Z,1408598006.492 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:26.506Z,1408598006.506 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 00800 bits/sec 2014-08-21T05:13:26.507Z,1408598006.507 [DAT](FAULT): failed to initialize; deviceResponse_ available: 2014-08-21T05:13:26.507Z,1408598006.507 [DAT] Communications Fault, FailCount= 5 2014-08-21T05:13:26.507Z,1408598006.507 [DAT](ERROR): Communications Fault 2014-08-21T05:13:26.519Z,1408598006.519 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:26.521Z,1408598006.521 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:26.546Z,1408598006.546 [CBIT](ERROR): Communications Fault in component: DAT 2014-08-21T05:13:31.462Z,1408598011.462 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:31.488Z,1408598011.488 [DAT](INFO): Powering down 2014-08-21T05:13:31.610Z,1408598011.610 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:31.612Z,1408598011.612 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:36.473Z,1408598016.473 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:36.486Z,1408598016.486 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:36.488Z,1408598016.488 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:41.468Z,1408598021.468 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:41.500Z,1408598021.500 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:41.534Z,1408598021.534 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:41.581Z,1408598021.581 [CBIT](INFO): Clearing failed state for component DAT 2014-08-21T05:13:41.581Z,1408598021.581 [DAT] No Fault, FailCount= 5 2014-08-21T05:13:46.465Z,1408598026.465 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:46.478Z,1408598026.478 [DAT](INFO): Powering up 2014-08-21T05:13:46.478Z,1408598026.478 [DAT](DEBUG): Initializing DAT. 2014-08-21T05:13:46.504Z,1408598026.504 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:46.547Z,1408598026.547 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:51.460Z,1408598031.460 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:51.501Z,1408598031.501 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:51.503Z,1408598031.503 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:56.468Z,1408598036.468 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:13:56.492Z,1408598036.492 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:13:56.494Z,1408598036.494 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:01.492Z,1408598041.492 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:14:01.558Z,1408598041.558 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:01.560Z,1408598041.560 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:06.486Z,1408598046.486 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:14:06.493Z,1408598046.493 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: Aug 21 2014 06:13:47 2014-08-21T05:14:06.493Z,1408598046.493 [DAT](FAULT): failed to initialize; deviceResponse_ available: CONNECT 00800 bits/sec nsponder version 8.3.1 2014-08-21T05:14:06.493Z,1408598046.493 [DAT] Communications Fault, FailCount= 6 2014-08-21T05:14:06.494Z,1408598046.494 [DAT](ERROR): Communications Fault 2014-08-21T05:14:06.501Z,1408598046.501 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:06.503Z,1408598046.503 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:06.578Z,1408598046.578 [CBIT](ERROR): Communications Fault in component: DAT 2014-08-21T05:14:11.474Z,1408598051.474 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:14:11.519Z,1408598051.519 [DAT](INFO): Powering down 2014-08-21T05:14:11.646Z,1408598051.646 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:11.648Z,1408598051.648 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:16.959Z,1408598056.959 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:14:16.973Z,1408598056.973 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:16.975Z,1408598056.975 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:21.478Z,1408598061.478 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:14:21.529Z,1408598061.529 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:21.531Z,1408598061.531 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:21.587Z,1408598061.587 [CBIT](INFO): Clearing failed state for component DAT 2014-08-21T05:14:21.588Z,1408598061.588 [DAT] No Fault, FailCount= 6 2014-08-21T05:14:26.498Z,1408598066.498 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:14:26.507Z,1408598066.507 [DAT](INFO): Powering up 2014-08-21T05:14:26.507Z,1408598066.507 [DAT](DEBUG): Initializing DAT. 2014-08-21T05:14:26.539Z,1408598066.539 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:26.541Z,1408598066.541 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:31.468Z,1408598071.468 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:14:31.530Z,1408598071.530 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:31.532Z,1408598071.532 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:36.469Z,1408598076.469 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:14:36.483Z,1408598076.483 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:36.485Z,1408598076.485 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:41.467Z,1408598081.467 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:14:41.505Z,1408598081.505 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:41.507Z,1408598081.507 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:46.489Z,1408598086.489 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:14:46.496Z,1408598086.496 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: Aug 21 2014 06:14:27 2014-08-21T05:14:46.496Z,1408598086.496 [DAT](FAULT): failed to initialize; deviceResponse_ available: CONNECT 00800 bits/sec nsponder version 8.3.1 2014-08-21T05:14:46.497Z,1408598086.497 [DAT] Communications Fault, FailCount= 7 2014-08-21T05:14:46.497Z,1408598086.497 [DAT](ERROR): Communications Fault 2014-08-21T05:14:46.504Z,1408598086.504 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:46.506Z,1408598086.506 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:46.609Z,1408598086.609 [CBIT](ERROR): Communications Fault in component: DAT 2014-08-21T05:14:51.476Z,1408598091.476 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2014-08-21T05:14:51.477Z,1408598091.477 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2014-08-21T05:14:51.477Z,1408598091.477 [DVL_micro] Data Fault, FailCount= 1 2014-08-21T05:14:51.477Z,1408598091.477 [DVL_micro](ERROR): Data Fault 2014-08-21T05:14:51.484Z,1408598091.484 [DAT](INFO): Powering down 2014-08-21T05:14:51.617Z,1408598091.617 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:51.619Z,1408598091.619 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:51.664Z,1408598091.664 [CBIT](ERROR): Data Fault in component: DVL_micro 2014-08-21T05:14:56.539Z,1408598096.539 [DVL_micro](INFO): uninitialize:Powering down 2014-08-21T05:14:56.573Z,1408598096.573 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:14:56.575Z,1408598096.575 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:15:01.477Z,1408598101.477 [DVL_micro](FAULT): LCB fault: Software Overcurrent. 2014-08-21T05:15:01.477Z,1408598101.477 [DVL_micro] Hardware Fault, FailCount= 1 2014-08-21T05:15:01.477Z,1408598101.477 [DVL_micro](ERROR): Hardware Fault 2014-08-21T05:15:01.530Z,1408598101.530 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:15:01.532Z,1408598101.532 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:15:01.575Z,1408598101.575 [CBIT](INFO): Clearing failed state for component DAT 2014-08-21T05:15:01.575Z,1408598101.575 [DAT] No Fault, FailCount= 7 2014-08-21T05:15:06.480Z,1408598106.480 [DVL_micro](INFO): Initializing 2014-08-21T05:15:06.489Z,1408598106.489 [DAT](INFO): Powering up 2014-08-21T05:15:06.489Z,1408598106.489 [DAT](DEBUG): Initializing DAT. 2014-08-21T05:15:06.603Z,1408598106.603 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:15:06.605Z,1408598106.605 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:15:06.640Z,1408598106.640 [CBIT](INFO): Clearing failed state for component DVL_micro 2014-08-21T05:15:06.640Z,1408598106.640 [DVL_micro] No Fault, FailCount= 1 2014-08-21T05:15:11.450Z,1408598111.450 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:15:11.452Z,1408598111.452 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:15:16.722Z,1408598116.722 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:15:16.724Z,1408598116.724 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:15:22.934Z,1408598122.934 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:15:22.940Z,1408598122.940 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802505, -121.787308) is outside the bounding box 2014-08-21T05:15:23.089Z,1408598123.089 [CommandLine](IMPORTANT): got command restart application 2014-08-21T05:15:24.096Z,1408598124.096 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2014-08-21T05:15:24.097Z,1408598124.097 [CommandLine ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:24.242Z,1408598124.242 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2014-08-21T05:15:24.242Z,1408598124.242 [CommandLine ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:24.242Z,1408598124.242 [CommandLine](INFO): Join timeout helper Thread ID is 2835 2014-08-21T05:15:24.243Z,1408598124.243 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2014-08-21T05:15:24.243Z,1408598124.243 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:24.243Z,1408598124.243 [NavChartDb](INFO): Join timeout helper Thread ID is 2836 2014-08-21T05:15:24.614Z,1408598124.614 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2014-08-21T05:15:24.615Z,1408598124.615 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:24.630Z,1408598124.630 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler 2014-08-21T05:15:24.630Z,1408598124.630 [Radio_Freewave ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:24.631Z,1408598124.631 [Radio_Freewave](INFO): Join timeout helper Thread ID is 2837 2014-08-21T05:15:24.675Z,1408598124.675 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread. 2014-08-21T05:15:24.770Z,1408598124.770 [Radio_Freewave](INFO): Powering down 2014-08-21T05:15:25.175Z,1408598125.175 [Radio_Freewave ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:25.191Z,1408598125.191 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2014-08-21T05:15:25.191Z,1408598125.191 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:25.192Z,1408598125.192 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2839 2014-08-21T05:15:25.348Z,1408598125.348 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2014-08-21T05:15:25.348Z,1408598125.348 [WetLabsBB2FL](INFO): Powering down 2014-08-21T05:15:25.349Z,1408598125.349 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:25.351Z,1408598125.351 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2014-08-21T05:15:25.351Z,1408598125.351 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:25.352Z,1408598125.352 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 2840 2014-08-21T05:15:25.488Z,1408598125.488 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2014-08-21T05:15:25.488Z,1408598125.488 [CTD_NeilBrown](INFO): Powering down 2014-08-21T05:15:25.489Z,1408598125.489 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:25.493Z,1408598125.493 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2014-08-21T05:15:25.493Z,1408598125.493 [logger ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:25.493Z,1408598125.493 [logger](INFO): Join timeout helper Thread ID is 2841 2014-08-21T05:15:25.512Z,1408598125.512 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2014-08-21T05:15:25.512Z,1408598125.512 [logger ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:25.532Z,1408598125.532 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2014-08-21T05:15:25.533Z,1408598125.533 [CommandLine ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:25.533Z,1408598125.533 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2014-08-21T05:15:25.533Z,1408598125.533 [controlThread ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:25.533Z,1408598125.533 [controlThread](INFO): Join timeout helper Thread ID is 2842 2014-08-21T05:15:25.961Z,1408598125.961 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2014-08-21T05:15:25.961Z,1408598125.961 [controlThread](DEBUG): Uninitializing ControlThread 2014-08-21T05:15:25.962Z,1408598125.962 [AHRS_sp3003D](INFO): Powering down 2014-08-21T05:15:26.121Z,1408598126.121 [DVL_micro](INFO): uninitialize:Powering down 2014-08-21T05:15:26.122Z,1408598126.122 [NAL9602](INFO): Powering down 2014-08-21T05:15:26.124Z,1408598126.124 [DAT](INFO): Powering down 2014-08-21T05:15:26.246Z,1408598126.246 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2014-08-21T05:15:26.248Z,1408598126.248 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2014-08-21T05:15:26.249Z,1408598126.249 [Default] Stopped 2014-08-21T05:15:26.249Z,1408598126.249 [Default](INFO): Aggregate::uninitialize Default 2014-08-21T05:15:26.249Z,1408598126.249 [Default:B.GoToSurface] Stopped 2014-08-21T05:15:26.249Z,1408598126.249 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-08-21T05:15:26.250Z,1408598126.250 [Default:WaitAtTheSurface] Stopped 2014-08-21T05:15:26.250Z,1408598126.250 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface 2014-08-21T05:15:26.250Z,1408598126.250 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Stopped 2014-08-21T05:15:26.250Z,1408598126.250 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Uninitialize. 2014-08-21T05:15:26.256Z,1408598126.256 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2014-08-21T05:15:26.256Z,1408598126.256 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2014-08-21T05:15:26.256Z,1408598126.256 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2014-08-21T05:15:26.256Z,1408598126.256 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2014-08-21T05:15:26.257Z,1408598126.257 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2014-08-21T05:15:26.257Z,1408598126.257 [BuoyancyServo](INFO): Powering down 2014-08-21T05:15:26.269Z,1408598126.269 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2014-08-21T05:15:26.269Z,1408598126.269 [ElevatorServo](INFO): Powering down 2014-08-21T05:15:26.270Z,1408598126.270 [MassServo](DEBUG): Uninitialize Mass Servo. 2014-08-21T05:15:26.270Z,1408598126.270 [MassServo](INFO): Powering down 2014-08-21T05:15:26.271Z,1408598126.271 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2014-08-21T05:15:26.271Z,1408598126.271 [RudderServo](INFO): Powering down 2014-08-21T05:15:26.272Z,1408598126.272 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2014-08-21T05:15:26.272Z,1408598126.272 [ThrusterServo](INFO): Powering down 2014-08-21T05:15:26.274Z,1408598126.274 [SBIT](DEBUG): Uninitialize SBIT Component. 2014-08-21T05:15:26.274Z,1408598126.274 [IBIT](DEBUG): Uninitialize IBIT Component. 2014-08-21T05:15:26.274Z,1408598126.274 [CBIT](DEBUG): Uninitialize CBIT Component. 2014-08-21T05:15:26.318Z,1408598126.318 [controlThread ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:26.397Z,1408598126.397 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:26.401Z,1408598126.401 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:26.410Z,1408598126.410 [Radio_Freewave ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:26.475Z,1408598126.475 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2014-08-21T05:15:26.532Z,1408598126.532 [logger ThreadHandler](INFO): Thread cancelled.