2014-06-25T18:04:46.404Z,1403719486.404 [Supervisor](DEBUG): Initializing supervisor.
2014-06-25T18:04:46.410Z,1403719486.410 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-06-25T18:04:46.410Z,1403719486.410 [SyncHandler](INFO): Protected caller Thread ID is 771
2014-06-25T18:04:46.411Z,1403719486.411 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-06-25T18:04:46.414Z,1403719486.414 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-06-25T18:04:46.414Z,1403719486.414 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 772
2014-06-25T18:04:46.417Z,1403719486.417 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-06-25T18:04:46.428Z,1403719486.428 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-06-25T18:04:46.430Z,1403719486.430 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-06-25T18:04:46.430Z,1403719486.430 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 773
2014-06-25T18:04:46.431Z,1403719486.431 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-06-25T18:04:46.434Z,1403719486.434 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-06-25T18:04:46.434Z,1403719486.434 [logger ThreadHandler](INFO): Protected caller Thread ID is 774
2014-06-25T18:04:46.436Z,1403719486.436 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-06-25T18:04:46.437Z,1403719486.437 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-06-25T18:04:46.441Z,1403719486.441 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-06-25T18:04:46.810Z,1403719486.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-06-25T18:04:46.811Z,1403719486.811 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-06-25T18:04:46.983Z,1403719486.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-06-25T18:04:46.984Z,1403719486.984 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-06-25T18:04:47.105Z,1403719487.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-06-25T18:04:47.106Z,1403719487.106 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-06-25T18:04:47.244Z,1403719487.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-06-25T18:04:47.245Z,1403719487.245 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-06-25T18:04:47.343Z,1403719487.343 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-06-25T18:04:47.492Z,1403719487.492 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-06-25T18:04:47.495Z,1403719487.495 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-06-25T18:04:47.591Z,1403719487.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-06-25T18:04:47.592Z,1403719487.592 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-06-25T18:04:47.793Z,1403719487.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-06-25T18:04:47.794Z,1403719487.794 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-06-25T18:04:48.110Z,1403719488.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-06-25T18:04:48.110Z,1403719488.110 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-06-25T18:04:48.444Z,1403719488.444 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-06-25T18:04:48.445Z,1403719488.445 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-06-25T18:04:48.995Z,1403719488.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-06-25T18:04:48.996Z,1403719488.996 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-06-25T18:04:49.211Z,1403719489.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-06-25T18:04:49.214Z,1403719489.214 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-06-25T18:04:49.331Z,1403719489.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-06-25T18:04:49.332Z,1403719489.332 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-06-25T18:04:49.772Z,1403719489.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-06-25T18:04:49.773Z,1403719489.773 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-06-25T18:04:49.923Z,1403719489.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-06-25T18:04:49.925Z,1403719489.925 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-06-25T18:04:49.929Z,1403719489.929 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-06-25T18:04:50.049Z,1403719490.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-06-25T18:04:50.182Z,1403719490.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-06-25T18:04:50.301Z,1403719490.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-06-25T18:04:50.409Z,1403719490.409 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-06-25T18:04:50.549Z,1403719490.549 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-06-25T18:04:50.706Z,1403719490.706 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-06-25T18:04:50.818Z,1403719490.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-06-25T18:04:50.912Z,1403719490.912 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-06-25T18:04:51.014Z,1403719491.014 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-06-25T18:04:51.122Z,1403719491.122 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-06-25T18:04:51.246Z,1403719491.246 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-06-25T18:04:51.341Z,1403719491.341 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2014-06-25T18:04:51.347Z,1403719491.347 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-06-25T18:04:51.421Z,1403719491.421 [VerticalControl](DEBUG): Construct VerticalControl.
2014-06-25T18:04:51.549Z,1403719491.549 [VerticalControl] Loaded
2014-06-25T18:04:51.550Z,1403719491.550 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-06-25T18:04:51.550Z,1403719491.550 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-06-25T18:04:51.628Z,1403719491.628 [HorizontalControl] Loaded
2014-06-25T18:04:51.628Z,1403719491.628 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-06-25T18:04:51.629Z,1403719491.629 [SpeedControl](DEBUG): Construct SpeedControl.
2014-06-25T18:04:51.635Z,1403719491.635 [SpeedControl] Loaded
2014-06-25T18:04:51.635Z,1403719491.635 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-06-25T18:04:51.635Z,1403719491.635 [LoopControl](DEBUG): Construct LoopControl.
2014-06-25T18:04:51.636Z,1403719491.636 [LoopControl] Loaded
2014-06-25T18:04:51.636Z,1403719491.636 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-06-25T18:04:51.637Z,1403719491.637 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-06-25T18:04:51.637Z,1403719491.637 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-06-25T18:04:51.767Z,1403719491.767 [SBIT](DEBUG): Construct Startup Built In Test.
2014-06-25T18:04:51.778Z,1403719491.778 [SBIT] Loaded
2014-06-25T18:04:51.779Z,1403719491.779 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-06-25T18:04:51.779Z,1403719491.779 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-06-25T18:04:51.812Z,1403719491.812 [IBIT] Loaded
2014-06-25T18:04:51.812Z,1403719491.812 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-06-25T18:04:51.814Z,1403719491.814 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-06-25T18:04:51.946Z,1403719491.946 [CBIT] Loaded
2014-06-25T18:04:51.947Z,1403719491.947 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-06-25T18:04:51.947Z,1403719491.947 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-06-25T18:04:51.948Z,1403719491.948 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-06-25T18:04:51.978Z,1403719491.978 [DepthRateCalculator] Loaded
2014-06-25T18:04:51.979Z,1403719491.979 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-06-25T18:04:51.984Z,1403719491.984 [PitchRateCalculator] Loaded
2014-06-25T18:04:51.984Z,1403719491.984 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-06-25T18:04:51.995Z,1403719491.995 [SpeedCalculator] Loaded
2014-06-25T18:04:51.996Z,1403719491.996 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-06-25T18:04:52.015Z,1403719492.015 [TempGradientCalculator] Loaded
2014-06-25T18:04:52.016Z,1403719492.016 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-06-25T18:04:52.021Z,1403719492.021 [YawRateCalculator] Loaded
2014-06-25T18:04:52.021Z,1403719492.021 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-06-25T18:04:52.021Z,1403719492.021 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-06-25T18:04:52.022Z,1403719492.022 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-06-25T18:04:57.989Z,1403719497.989 [HFRadarModelCalc] Loaded
2014-06-25T18:04:57.990Z,1403719497.990 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2014-06-25T18:05:02.244Z,1403719502.244 [HFRadarCompactModelForecaster] Loaded
2014-06-25T18:05:02.244Z,1403719502.244 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2014-06-25T18:05:04.075Z,1403719504.075 [HFRCMSpaceInterpolator] Loaded
2014-06-25T18:05:04.075Z,1403719504.075 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2014-06-25T18:05:04.077Z,1403719504.077 [HFRCMTimeInterpolator] Loaded
2014-06-25T18:05:04.078Z,1403719504.078 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2014-06-25T18:05:05.302Z,1403719505.302 [HFRCMReconstructedInterpolator] Loaded
2014-06-25T18:05:05.302Z,1403719505.302 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-06-25T18:05:05.314Z,1403719505.314 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2014-06-25T18:05:05.314Z,1403719505.314 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2014-06-25T18:05:05.341Z,1403719505.341 [HFRCMVirtualSurfaceDrifter] Loaded
2014-06-25T18:05:05.341Z,1403719505.341 [ComponentRegistry](DEBUG): SyncComponent "HFRCMVirtualSurfaceDrifter" handled in the control thread.
2014-06-25T18:05:05.341Z,1403719505.341 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-06-25T18:05:05.342Z,1403719505.342 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-06-25T18:05:05.614Z,1403719505.614 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-06-25T18:05:05.615Z,1403719505.615 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-06-25T18:05:05.710Z,1403719505.710 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-06-25T18:05:05.710Z,1403719505.710 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-06-25T18:05:05.754Z,1403719505.754 [DeadReckonWithRespectToWater] Loaded
2014-06-25T18:05:05.754Z,1403719505.754 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-06-25T18:05:05.800Z,1403719505.800 [DeadReckonWithRespectToSeafloor] Loaded
2014-06-25T18:05:05.800Z,1403719505.800 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-06-25T18:05:05.859Z,1403719505.859 [DeadReckonUsingDVLWaterTrack] Loaded
2014-06-25T18:05:05.860Z,1403719505.860 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-06-25T18:05:05.906Z,1403719505.906 [DeadReckonUsingCompactModelForecast] Loaded
2014-06-25T18:05:05.906Z,1403719505.906 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-06-25T18:05:05.923Z,1403719505.923 [NavChart] Loaded
2014-06-25T18:05:05.923Z,1403719505.923 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-06-25T18:05:05.927Z,1403719505.927 [UniversalFixResidualReporter] Loaded
2014-06-25T18:05:05.927Z,1403719505.927 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-06-25T18:05:05.928Z,1403719505.928 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-06-25T18:05:05.929Z,1403719505.929 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-06-25T18:05:05.937Z,1403719505.937 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-06-25T18:05:05.938Z,1403719505.938 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-06-25T18:05:06.133Z,1403719506.133 [CTD_NeilBrown] Loaded
2014-06-25T18:05:06.134Z,1403719506.134 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-06-25T18:05:06.135Z,1403719506.135 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407B94E0
2014-06-25T18:05:06.135Z,1403719506.135 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 861
2014-06-25T18:05:06.150Z,1403719506.150 [PAR_Licor] Loaded
2014-06-25T18:05:06.150Z,1403719506.150 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-06-25T18:05:06.186Z,1403719506.186 [WetLabsBB2FL] Loaded
2014-06-25T18:05:06.186Z,1403719506.186 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-06-25T18:05:06.187Z,1403719506.187 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407E94E0
2014-06-25T18:05:06.187Z,1403719506.187 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 862
2014-06-25T18:05:06.188Z,1403719506.188 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-06-25T18:05:06.189Z,1403719506.189 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-06-25T18:05:06.737Z,1403719506.737 [AHRS_sp3003D] Loaded
2014-06-25T18:05:06.737Z,1403719506.737 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-06-25T18:05:06.994Z,1403719506.994 [Batt_Ocean_Server] Loaded
2014-06-25T18:05:06.994Z,1403719506.994 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-06-25T18:05:07.007Z,1403719507.007 [Depth_Keller] Loaded
2014-06-25T18:05:07.008Z,1403719507.008 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-06-25T18:05:07.012Z,1403719507.012 [DropWeight] Loaded
2014-06-25T18:05:07.013Z,1403719507.013 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-06-25T18:05:07.143Z,1403719507.143 [DVL_micro] Loaded
2014-06-25T18:05:07.144Z,1403719507.144 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-06-25T18:05:07.235Z,1403719507.235 [NAL9602] Loaded
2014-06-25T18:05:07.236Z,1403719507.236 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-06-25T18:05:07.277Z,1403719507.277 [Onboard] Loaded
2014-06-25T18:05:07.277Z,1403719507.277 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-06-25T18:05:07.284Z,1403719507.284 [Radio_Freewave] Loaded
2014-06-25T18:05:07.284Z,1403719507.284 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-06-25T18:05:07.285Z,1403719507.285 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 40A1B4E0
2014-06-25T18:05:07.286Z,1403719507.286 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 863
2014-06-25T18:05:07.293Z,1403719507.293 [SCPI] Loaded
2014-06-25T18:05:07.293Z,1403719507.293 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2014-06-25T18:05:07.294Z,1403719507.294 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-06-25T18:05:07.294Z,1403719507.294 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-06-25T18:05:07.417Z,1403719507.417 [BuoyancyServo] Loaded
2014-06-25T18:05:07.417Z,1403719507.417 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-06-25T18:05:07.430Z,1403719507.430 [ElevatorServo] Loaded
2014-06-25T18:05:07.430Z,1403719507.430 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-06-25T18:05:07.441Z,1403719507.441 [MassServo] Loaded
2014-06-25T18:05:07.442Z,1403719507.442 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-06-25T18:05:07.454Z,1403719507.454 [RudderServo] Loaded
2014-06-25T18:05:07.454Z,1403719507.454 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-06-25T18:05:07.465Z,1403719507.465 [ThrusterServo] Loaded
2014-06-25T18:05:07.465Z,1403719507.465 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-06-25T18:05:07.466Z,1403719507.466 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-06-25T18:05:07.467Z,1403719507.467 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-06-25T18:05:07.654Z,1403719507.654 [InternalSim] Loaded
2014-06-25T18:05:07.654Z,1403719507.654 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-06-25T18:05:07.655Z,1403719507.655 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-06-25T18:05:07.655Z,1403719507.655 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-06-25T18:05:07.694Z,1403719507.694 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-06-25T18:05:07.696Z,1403719507.696 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-06-25T18:05:07.697Z,1403719507.697 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-06-25T18:05:07.704Z,1403719507.704 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-06-25T18:05:07.705Z,1403719507.705 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACC4E0
2014-06-25T18:05:07.705Z,1403719507.705 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 864
2014-06-25T18:05:07.710Z,1403719507.710 [Supervisor](INFO): Main Thread ID is 766
2014-06-25T18:05:07.710Z,1403719507.710 [Supervisor](DEBUG): Running supervisor.
2014-06-25T18:05:07.711Z,1403719507.711 [CommandLine ThreadHandler](INFO): Handler Thread ID is 865
2014-06-25T18:05:07.714Z,1403719507.714 [controlThread ThreadHandler](INFO): Handler Thread ID is 866
2014-06-25T18:05:07.714Z,1403719507.714 [controlThread](DEBUG): Initializing ControlThread
2014-06-25T18:05:07.715Z,1403719507.715 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-06-25T18:05:07.717Z,1403719507.717 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-06-25T18:05:07.717Z,1403719507.717 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-06-25T18:05:07.718Z,1403719507.718 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-06-25T18:05:07.718Z,1403719507.718 [SBIT](INFO): Initialize SBIT Component.
2014-06-25T18:05:07.719Z,1403719507.719 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11424
2014-06-25T18:05:07.719Z,1403719507.719 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-06-25T18:05:07.719Z,1403719507.719 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-06-25T18:05:07.720Z,1403719507.720 [IBIT](INFO): Initialize IBIT Component.
2014-06-25T18:05:07.721Z,1403719507.721 [CBIT](DEBUG): Initialize CBIT Component.
2014-06-25T18:05:07.721Z,1403719507.721 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2014-06-25T18:05:07.721Z,1403719507.721 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-06-25T18:05:07.722Z,1403719507.722 [logger ThreadHandler](INFO): Handler Thread ID is 867
2014-06-25T18:05:07.747Z,1403719507.747 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-06-25T18:05:07.747Z,1403719507.747 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-06-25T18:05:07.748Z,1403719507.748 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-06-25T18:05:07.748Z,1403719507.748 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-06-25T18:05:07.749Z,1403719507.749 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-06-25T18:05:07.751Z,1403719507.751 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 868
2014-06-25T18:05:07.752Z,1403719507.752 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-06-25T18:05:07.759Z,1403719507.759 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-06-25T18:05:07.768Z,1403719507.768 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2014-06-25T18:05:07.778Z,1403719507.778 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 870
2014-06-25T18:05:07.780Z,1403719507.780 [WetLabsBB2FL](INFO): Powering down
2014-06-25T18:05:07.815Z,1403719507.815 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 871
2014-06-25T18:05:07.826Z,1403719507.826 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 872
2014-06-25T18:05:07.840Z,1403719507.840 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-06-25T18:05:07.841Z,1403719507.841 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-06-25T18:05:07.841Z,1403719507.841 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-06-25T18:05:07.841Z,1403719507.841 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-06-25T18:05:07.841Z,1403719507.841 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-06-25T18:05:07.842Z,1403719507.842 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-06-25T18:05:07.842Z,1403719507.842 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-06-25T18:05:07.842Z,1403719507.842 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-06-25T18:05:07.842Z,1403719507.842 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-06-25T18:05:16.758Z,1403719516.758 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201406251500: published 50 modes in rows, 61 hours in columns
2014-06-25T18:05:16.761Z,1403719516.761 [HFRadarCompactModelForecaster](IMPORTANT): expansionCoefficients_[0][48] = 6.845451 for 2014-06-25T15:00:00.000Z
2014-06-25T18:05:16.769Z,1403719516.769 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 0.
2014-06-25T18:05:16.771Z,1403719516.771 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2014-06-25T18:05:16.771Z,1403719516.771 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-06-25T18:05:16.773Z,1403719516.773 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2014-06-25T18:05:16.773Z,1403719516.773 [HFRCMVirtualSurfaceDrifter](DEBUG): Initializing HFRCMVirtualSurfaceDrifter component.
2014-06-25T18:05:16.774Z,1403719516.774 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-06-25T18:05:16.774Z,1403719516.774 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-06-25T18:05:16.774Z,1403719516.774 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-06-25T18:05:16.775Z,1403719516.775 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-06-25T18:05:16.775Z,1403719516.775 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-06-25T18:05:16.776Z,1403719516.776 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-06-25T18:05:16.776Z,1403719516.776 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-06-25T18:05:18.379Z,1403719518.379 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-06-25T18:05:18.416Z,1403719518.416 [InternalSim](DEBUG):  InternalSim initializing...
2014-06-25T18:05:18.704Z,1403719518.704 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-06-25T18:05:18.753Z,1403719518.753 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-06-25T18:05:18.776Z,1403719518.776 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2014-06-25T18:05:18.777Z,1403719518.777 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-06-25T18:05:18.856Z,1403719518.856 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2014-06-25T18:05:18.858Z,1403719518.858 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2014-06-25T18:05:18.877Z,1403719518.877 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-06-25T18:05:18.902Z,1403719518.902 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2014-06-25T18:05:18.913Z,1403719518.913 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-06-25T18:05:18.919Z,1403719518.919 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-06-25T18:05:18.961Z,1403719518.961 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-06-25T18:05:18.967Z,1403719518.967 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="NeedGPS"><True/></DefineArg>

    <Aggregate Id="GPS">

        <When>
            <Arg Name="NeedGPS"/>
        </When>

<!-- Run at high speed -->

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

<!-- Get to the surface -->

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

<!-- Get GPS fix -->

        <ReadDatum Id="Read_GPS">
            <Universal:latitude_fix/>
        </ReadDatum>

        <Assign><Sequence/><Arg Name="NeedGPS"/><False/></Assign>

    </Aggregate>

    <Aggregate Id="Iridium">

        <Sequence/>

<!-- Run at high speed -->

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

<!-- Get to the surface -->

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

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="CallIridium">

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

        <Assign><Sequence/><Arg Name="NeedGPS"/><True/></Assign>

        <Call RefId="Iridium"/>

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2014-06-25T18:05:18.978Z,1403719518.978 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,PAR_Licor,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-06-25T18:05:19.253Z,1403719519.253 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-06-25T18:05:19.256Z,1403719519.256 [Radio_Freewave](INFO): Powering up
2014-06-25T18:05:19.463Z,1403719519.463 [DVL_micro](INFO): Initializing
2014-06-25T18:05:19.497Z,1403719519.497 [NAL9602](INFO): Powering up NAL9602
2014-06-25T18:05:20.067Z,1403719520.067 [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-06-25T18:05:26.666Z,1403719526.666 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-06-25T18:05:26.673Z,1403719526.673 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-06-25T18:05:26.699Z,1403719526.699 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-06-25T18:05:26.705Z,1403719526.705 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-06-25T18:05:26.710Z,1403719526.710 [MassServo](DEBUG): Initializing EZServoServo.
2014-06-25T18:05:26.717Z,1403719526.717 [MassServo](DEBUG): Initializing MassServo.
2014-06-25T18:05:26.722Z,1403719526.722 [RudderServo](DEBUG): Initializing EZServoServo.
2014-06-25T18:05:26.747Z,1403719526.747 [RudderServo](DEBUG): Initializing RudderServo.
2014-06-25T18:05:26.765Z,1403719526.765 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-06-25T18:05:26.773Z,1403719526.773 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-06-25T18:05:27.659Z,1403719527.659 [SBIT](IMPORTANT): Beginning Startup BIT
2014-06-25T18:05:27.662Z,1403719527.662 [CBIT](IMPORTANT): Beginning GF scan
2014-06-25T18:05:28.983Z,1403719528.983 [Radio_Freewave](INFO): Powering down
2014-06-25T18:05:30.001Z,1403719530.001 [Radio_Freewave](INFO): Powering up
2014-06-25T18:05:30.259Z,1403719530.259 [NAL9602](INFO): NAL9602 initialized
2014-06-25T18:05:30.812Z,1403719530.812 [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-06-25T18:05:55.317Z,1403719555.317 [CBIT](IMPORTANT): No ground fault detected
2014-06-25T18:06:21.606Z,1403719581.606 [SBIT](IMPORTANT): SBIT PASSED
2014-06-25T18:06:22.013Z,1403719582.013 [MissionManager](IMPORTANT): Started mission Startup
2014-06-25T18:06:22.013Z,1403719582.013 [Startup] Running Loop=1
2014-06-25T18:06:22.013Z,1403719582.013 [Startup](INFO): Aggregate::initialize Startup
2014-06-25T18:06:22.013Z,1403719582.013 [Startup:A.GoToSurface] Running Loop=1
2014-06-25T18:06:22.013Z,1403719582.013 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-06-25T18:06:22.014Z,1403719582.014 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-06-25T18:06:22.015Z,1403719582.015 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-06-25T18:06:22.015Z,1403719582.015 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-06-25T18:06:22.021Z,1403719582.021 [Startup:StartupSatComms] Running Loop=1
2014-06-25T18:06:22.021Z,1403719582.021 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-06-25T18:06:22.021Z,1403719582.021 [Startup:StartupSatComms:A] Running Loop=1
2014-06-25T18:06:22.458Z,1403719582.458 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-06-25T18:07:22.122Z,1403719642.122 [Startup:StartupSatComms:A](INFO): Timed out from 2014-06-25T18:06:22.0Z
2014-06-25T18:07:22.122Z,1403719642.122 [Startup:StartupSatComms:A] Stopped
2014-06-25T18:07:22.122Z,1403719642.122 [Startup:StartupSatComms:B] Running Loop=1
2014-06-25T18:07:22.618Z,1403719642.618 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-06-25T18:08:22.178Z,1403719702.178 [Startup:StartupSatComms:B](INFO): Timed out from 2014-06-25T18:07:22.1Z
2014-06-25T18:08:22.179Z,1403719702.179 [Startup:StartupSatComms:B] Stopped
2014-06-25T18:08:22.179Z,1403719702.179 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2014-06-25T18:08:22.179Z,1403719702.179 [Startup:StartupSatComms] Stopped
2014-06-25T18:08:22.179Z,1403719702.179 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-06-25T18:08:22.180Z,1403719702.180 [Startup](INFO): Completed Startup
2014-06-25T18:08:22.180Z,1403719702.180 [Startup] Stopped
2014-06-25T18:08:22.180Z,1403719702.180 [Startup](INFO): Aggregate::uninitialize Startup
2014-06-25T18:08:22.180Z,1403719702.180 [Startup:A.GoToSurface] Stopped
2014-06-25T18:08:22.180Z,1403719702.180 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-06-25T18:08:22.699Z,1403719702.699 [MissionManager](IMPORTANT): Started mission Default
2014-06-25T18:08:22.699Z,1403719702.699 [Default] Running Loop=1
2014-06-25T18:08:22.700Z,1403719702.700 [Default](INFO): Aggregate::initialize Default
2014-06-25T18:08:22.700Z,1403719702.700 [Default:Iridium] Running Loop=1
2014-06-25T18:08:22.700Z,1403719702.700 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2014-06-25T18:08:22.700Z,1403719702.700 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-06-25T18:08:22.700Z,1403719702.700 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2014-06-25T18:08:22.700Z,1403719702.700 [Default:Iridium:B.GoToSurface] Running Loop=1
2014-06-25T18:08:22.700Z,1403719702.700 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-06-25T18:08:22.701Z,1403719702.701 [Default:Iridium:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-06-25T18:08:22.701Z,1403719702.701 [Default:Iridium:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-06-25T18:08:22.705Z,1403719702.705 [Default:Iridium:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-06-25T18:08:22.706Z,1403719702.706 [Default:CallIridium] Running Loop=1
2014-06-25T18:08:22.706Z,1403719702.706 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2014-06-25T18:08:22.706Z,1403719702.706 [Default:CallIridium:A] Running Loop=1
2014-06-25T18:08:22.708Z,1403719702.708 [Default:CallIridium:A] Stopped
2014-06-25T18:08:22.708Z,1403719702.708 [Default:CallIridium:B] Running Loop=1
2014-06-25T18:08:22.708Z,1403719702.708 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2014-06-25T18:08:22.722Z,1403719702.722 [Default:Iridium:Read_Iridium] Running Loop=1
2014-06-25T18:08:22.722Z,1403719702.722 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-06-25T18:08:22.731Z,1403719702.731 [Default:GPS] Running Loop=1
2014-06-25T18:08:22.732Z,1403719702.732 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2014-06-25T18:08:22.732Z,1403719702.732 [Default:GPS:A.SetSpeed] Running Loop=1
2014-06-25T18:08:22.732Z,1403719702.732 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2014-06-25T18:08:22.732Z,1403719702.732 [Default:GPS:B.GoToSurface] Running Loop=1
2014-06-25T18:08:22.732Z,1403719702.732 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-06-25T18:08:22.732Z,1403719702.732 [Default:GPS:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-06-25T18:08:22.733Z,1403719702.733 [Default:GPS:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-06-25T18:08:22.737Z,1403719702.737 [Default:GPS:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-06-25T18:08:22.751Z,1403719702.751 [Default:GPS:Read_GPS] Running Loop=1
2014-06-25T18:08:22.751Z,1403719702.751 [Default:GPS:A.SetSpeed] Running Loop=1
2014-06-25T18:08:23.182Z,1403719703.182 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-06-25T18:08:23.186Z,1403719703.186 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-06-25T18:08:32.252Z,1403719712.252 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:08:32.252Z,1403719712.252 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:08:32.252Z,1403719712.252 [DVL_micro](ERROR): Data Fault
2014-06-25T18:08:32.330Z,1403719712.330 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:08:32.696Z,1403719712.696 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:08:33.107Z,1403719713.107 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:08:33.107Z,1403719713.107 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:08:33.107Z,1403719713.107 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:08:33.529Z,1403719713.529 [DVL_micro](INFO): Initializing
2014-06-25T18:08:33.581Z,1403719713.581 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:08:33.582Z,1403719713.582 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:11:38.407Z,1403719898.407 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:11:38.407Z,1403719898.407 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:11:38.407Z,1403719898.407 [DVL_micro](ERROR): Data Fault
2014-06-25T18:11:38.486Z,1403719898.486 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:11:38.843Z,1403719898.843 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:11:39.276Z,1403719899.276 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:11:39.276Z,1403719899.276 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:11:39.276Z,1403719899.276 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:11:39.711Z,1403719899.711 [DVL_micro](INFO): Initializing
2014-06-25T18:11:39.792Z,1403719899.792 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:11:39.792Z,1403719899.792 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:14:44.704Z,1403720084.704 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:14:44.704Z,1403720084.704 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:14:44.704Z,1403720084.704 [DVL_micro](ERROR): Data Fault
2014-06-25T18:14:44.756Z,1403720084.756 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:14:45.133Z,1403720085.133 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:14:45.585Z,1403720085.585 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:14:45.585Z,1403720085.585 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:14:45.585Z,1403720085.585 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:14:46.020Z,1403720086.020 [DVL_micro](INFO): Initializing
2014-06-25T18:14:46.089Z,1403720086.089 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:14:46.089Z,1403720086.089 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:15:33.375Z,1403720133.375 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T18:15:33.375Z,1403720133.375 [NAL9602] Data Fault, FailCount= 1
2014-06-25T18:15:33.376Z,1403720133.376 [NAL9602](ERROR): Data Fault
2014-06-25T18:15:33.435Z,1403720133.435 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T18:15:33.821Z,1403720133.821 [NAL9602](INFO): Powering down
2014-06-25T18:15:34.698Z,1403720134.698 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T18:15:34.698Z,1403720134.698 [NAL9602] No Fault, FailCount= 1
2014-06-25T18:15:38.933Z,1403720138.933 [NAL9602](INFO): Powering up NAL9602
2014-06-25T18:15:49.634Z,1403720149.634 [NAL9602](INFO): NAL9602 initialized
2014-06-25T18:17:50.258Z,1403720270.258 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:17:50.258Z,1403720270.258 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:17:50.258Z,1403720270.258 [DVL_micro](ERROR): Data Fault
2014-06-25T18:17:50.315Z,1403720270.315 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:17:50.676Z,1403720270.676 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:17:51.125Z,1403720271.125 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:17:51.125Z,1403720271.125 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:17:51.125Z,1403720271.125 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:17:51.546Z,1403720271.546 [DVL_micro](INFO): Initializing
2014-06-25T18:17:52.089Z,1403720272.089 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:17:52.089Z,1403720272.089 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:19:48.833Z,1403720388.833 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:19:51.053Z,1403720391.053 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:19:53.757Z,1403720393.757 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:19:56.037Z,1403720396.037 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:19:58.248Z,1403720398.248 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:00.925Z,1403720400.925 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:03.212Z,1403720403.212 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:05.935Z,1403720405.935 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:08.188Z,1403720408.188 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:10.418Z,1403720410.418 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:13.063Z,1403720413.063 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:15.723Z,1403720415.723 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:17.946Z,1403720417.946 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:20.596Z,1403720420.596 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:22.843Z,1403720422.843 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:25.542Z,1403720425.542 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:27.778Z,1403720427.778 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:30.025Z,1403720430.025 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:33.231Z,1403720433.231 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:35.013Z,1403720435.013 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:38.091Z,1403720438.091 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:39.912Z,1403720439.912 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:42.477Z,1403720442.477 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:44.800Z,1403720444.800 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:47.371Z,1403720447.371 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:49.680Z,1403720449.680 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:51.863Z,1403720451.863 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:54.623Z,1403720454.623 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:56.316Z,1403720456.316 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:20:56.316Z,1403720456.316 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:20:56.316Z,1403720456.316 [DVL_micro](ERROR): Data Fault
2014-06-25T18:20:56.387Z,1403720456.387 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:20:56.759Z,1403720456.759 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:20:56.777Z,1403720456.777 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:20:57.183Z,1403720457.183 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:20:57.183Z,1403720457.183 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:20:57.183Z,1403720457.183 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:20:57.620Z,1403720457.620 [DVL_micro](INFO): Initializing
2014-06-25T18:20:58.167Z,1403720458.167 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:20:58.167Z,1403720458.167 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:20:59.471Z,1403720459.471 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:21:01.614Z,1403720461.614 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:21:04.332Z,1403720464.332 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:21:06.534Z,1403720466.534 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:21:09.254Z,1403720469.254 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:21:11.446Z,1403720471.446 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:21:14.106Z,1403720474.106 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:21:16.344Z,1403720476.344 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:21:19.014Z,1403720479.014 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:21:21.258Z,1403720481.258 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:21:23.941Z,1403720483.941 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:21:26.212Z,1403720486.212 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T18:24:02.144Z,1403720642.144 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:24:02.144Z,1403720642.144 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:24:02.144Z,1403720642.144 [DVL_micro](ERROR): Data Fault
2014-06-25T18:24:02.231Z,1403720642.231 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:24:02.605Z,1403720642.605 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:24:03.029Z,1403720643.029 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:24:03.029Z,1403720643.029 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:24:03.029Z,1403720643.029 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:24:03.448Z,1403720643.448 [DVL_micro](INFO): Initializing
2014-06-25T18:24:03.499Z,1403720643.499 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:24:03.499Z,1403720643.499 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:25:54.158Z,1403720754.158 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T18:25:54.158Z,1403720754.158 [NAL9602] Data Fault, FailCount= 2
2014-06-25T18:25:54.158Z,1403720754.158 [NAL9602](ERROR): Data Fault
2014-06-25T18:25:54.212Z,1403720754.212 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T18:25:54.575Z,1403720754.575 [NAL9602](INFO): Powering down
2014-06-25T18:25:55.451Z,1403720755.451 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T18:25:55.451Z,1403720755.451 [NAL9602] No Fault, FailCount= 2
2014-06-25T18:25:59.711Z,1403720759.711 [NAL9602](INFO): Powering up NAL9602
2014-06-25T18:26:10.561Z,1403720770.561 [NAL9602](INFO): NAL9602 initialized
2014-06-25T18:27:07.780Z,1403720827.780 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:27:07.780Z,1403720827.780 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:27:07.780Z,1403720827.780 [DVL_micro](ERROR): Data Fault
2014-06-25T18:27:07.814Z,1403720827.814 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:27:08.221Z,1403720828.221 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:27:08.649Z,1403720828.649 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:27:08.649Z,1403720828.649 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:27:08.649Z,1403720828.649 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:27:09.077Z,1403720829.077 [DVL_micro](INFO): Initializing
2014-06-25T18:27:09.149Z,1403720829.149 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:27:09.149Z,1403720829.149 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:28:46.131Z,1403720926.131 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:28:49.291Z,1403720929.291 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:28:51.063Z,1403720931.063 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:28:53.685Z,1403720933.685 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:28:55.806Z,1403720935.806 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:28:58.491Z,1403720938.491 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:00.705Z,1403720940.705 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:03.278Z,1403720943.278 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:05.962Z,1403720945.962 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:07.717Z,1403720947.717 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:10.469Z,1403720950.469 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:12.656Z,1403720952.656 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:15.369Z,1403720955.369 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:17.580Z,1403720957.580 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:20.282Z,1403720960.282 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:22.457Z,1403720962.457 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:25.200Z,1403720965.200 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:26.979Z,1403720966.979 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:29.563Z,1403720969.563 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:31.890Z,1403720971.890 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:34.496Z,1403720974.496 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:36.825Z,1403720976.825 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:39.042Z,1403720979.042 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:41.381Z,1403720981.381 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:43.989Z,1403720983.989 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:46.353Z,1403720986.353 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:48.956Z,1403720988.956 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:51.236Z,1403720991.236 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:53.857Z,1403720993.857 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:56.220Z,1403720996.220 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:29:58.790Z,1403720998.790 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:30:01.085Z,1403721001.085 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:30:03.283Z,1403721003.283 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:30:05.661Z,1403721005.661 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:30:08.374Z,1403721008.374 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:30:10.611Z,1403721010.611 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:30:13.311Z,1403721013.311 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:30:13.350Z,1403721013.350 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:30:13.350Z,1403721013.350 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:30:13.350Z,1403721013.350 [DVL_micro](ERROR): Data Fault
2014-06-25T18:30:13.907Z,1403721013.907 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:30:14.277Z,1403721014.277 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:30:14.709Z,1403721014.709 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:30:14.709Z,1403721014.709 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:30:14.709Z,1403721014.709 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:30:15.120Z,1403721015.120 [DVL_micro](INFO): Initializing
2014-06-25T18:30:15.176Z,1403721015.176 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:30:15.176Z,1403721015.176 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:30:15.570Z,1403721015.570 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:30:17.786Z,1403721017.786 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T18:33:20.661Z,1403721200.661 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:33:20.661Z,1403721200.661 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:33:20.661Z,1403721200.661 [DVL_micro](ERROR): Data Fault
2014-06-25T18:33:20.695Z,1403721200.695 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:33:21.087Z,1403721201.087 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:33:21.527Z,1403721201.527 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:33:21.527Z,1403721201.527 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:33:21.527Z,1403721201.527 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:33:21.954Z,1403721201.954 [DVL_micro](INFO): Initializing
2014-06-25T18:33:22.001Z,1403721202.001 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:33:22.001Z,1403721202.001 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:35:15.761Z,1403721315.761 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-06-25T18:35:15.761Z,1403721315.761 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -363 347 280 297 3 3 3 3 -858.2 66.7 151.2 2 804.9 305.0 151.2 2 -858 6604 305 151 2 -5.47 -0.24 205.2 -3.0 23.1 0.005 35.0 1489 107
2014-06-25T18:36:14.817Z,1403721374.817 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T18:36:14.817Z,1403721374.817 [NAL9602] Data Fault, FailCount= 3
2014-06-25T18:36:14.817Z,1403721374.817 [NAL9602](ERROR): Data Fault
2014-06-25T18:36:14.868Z,1403721374.868 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T18:36:14.868Z,1403721374.868 [CBIT](CRITICAL): Data Fault in component: NAL9602
2014-06-25T18:36:15.253Z,1403721375.253 [NAL9602](INFO): Powering down
2014-06-25T18:36:15.313Z,1403721375.313 [CommandLine](FAULT): Scheduling is paused
2014-06-25T18:36:15.680Z,1403721375.680 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:36:15.680Z,1403721375.680 [NAL9602] Hardware Fault, FailCount= 3
2014-06-25T18:36:15.680Z,1403721375.680 [NAL9602](ERROR): Hardware Fault
2014-06-25T18:36:26.360Z,1403721386.360 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:36:26.360Z,1403721386.360 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:36:26.360Z,1403721386.360 [DVL_micro](ERROR): Data Fault
2014-06-25T18:36:26.392Z,1403721386.392 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:36:26.778Z,1403721386.778 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:36:27.187Z,1403721387.187 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:36:27.187Z,1403721387.187 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:36:27.187Z,1403721387.187 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:36:27.675Z,1403721387.675 [DVL_micro](INFO): Initializing
2014-06-25T18:36:27.707Z,1403721387.707 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:36:27.708Z,1403721387.708 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:38:15.110Z,1403721495.110 [CBIT](INFO): Clearing failed count for component NAL9602
2014-06-25T18:38:15.110Z,1403721495.110 [NAL9602] No Fault, FailCount= 3
2014-06-25T18:38:15.543Z,1403721495.543 [NAL9602](INFO): Powering up NAL9602
2014-06-25T18:38:26.142Z,1403721506.142 [NAL9602](INFO): NAL9602 initialized
2014-06-25T18:39:32.126Z,1403721572.126 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:39:32.126Z,1403721572.126 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:39:32.126Z,1403721572.126 [DVL_micro](ERROR): Data Fault
2014-06-25T18:39:32.217Z,1403721572.217 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:39:32.603Z,1403721572.603 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:39:33.099Z,1403721573.099 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:39:33.100Z,1403721573.100 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:39:33.100Z,1403721573.100 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:39:33.550Z,1403721573.550 [DVL_micro](INFO): Initializing
2014-06-25T18:39:33.603Z,1403721573.603 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:39:33.603Z,1403721573.603 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:42:38.417Z,1403721758.417 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:42:38.417Z,1403721758.417 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:42:38.417Z,1403721758.417 [DVL_micro](ERROR): Data Fault
2014-06-25T18:42:38.485Z,1403721758.485 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:42:38.851Z,1403721758.851 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:42:39.260Z,1403721759.260 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:42:39.260Z,1403721759.260 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:42:39.260Z,1403721759.260 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:42:39.835Z,1403721759.835 [DVL_micro](INFO): Initializing
2014-06-25T18:42:39.904Z,1403721759.904 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:42:39.904Z,1403721759.904 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:45:11.030Z,1403721911.030 [NAL9602](INFO): SBD MO Status=2, MOMSN=36478, MT Status=2, MTMSN=0
2014-06-25T18:45:11.030Z,1403721911.030 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2014-06-25T18:45:44.472Z,1403721944.472 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:45:44.472Z,1403721944.472 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:45:44.472Z,1403721944.472 [DVL_micro](ERROR): Data Fault
2014-06-25T18:45:44.531Z,1403721944.531 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:45:44.880Z,1403721944.880 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:45:45.375Z,1403721945.375 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:45:45.375Z,1403721945.375 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:45:45.375Z,1403721945.375 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:45:45.795Z,1403721945.795 [DVL_micro](INFO): Initializing
2014-06-25T18:45:45.852Z,1403721945.852 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:45:45.852Z,1403721945.852 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:48:04.846Z,1403722084.846 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-06-25T18:48:04.846Z,1403722084.846 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 -195 -318 -250 -253 3 3 3 3 73.4 -86.8 -274.0 2 -102.8 48.5 -274.0 2 73 -86 -274 2 -102 48 -274 2 -5.41 -0.32 204.5 -3.0 23.3 0.005 35.0 1489 74
2014-06-25T18:48:29.355Z,1403722109.355 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T18:48:29.355Z,1403722109.355 [NAL9602] Data Fault, FailCount= 1
2014-06-25T18:48:29.355Z,1403722109.355 [NAL9602](ERROR): Data Fault
2014-06-25T18:48:29.416Z,1403722109.416 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T18:48:29.810Z,1403722109.810 [NAL9602](INFO): Powering down
2014-06-25T18:48:30.765Z,1403722110.765 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T18:48:30.765Z,1403722110.765 [NAL9602] No Fault, FailCount= 1
2014-06-25T18:48:34.956Z,1403722114.956 [NAL9602](INFO): Powering up NAL9602
2014-06-25T18:48:45.770Z,1403722125.770 [NAL9602](INFO): NAL9602 initialized
2014-06-25T18:48:50.576Z,1403722130.576 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:48:50.576Z,1403722130.576 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:48:50.576Z,1403722130.576 [DVL_micro](ERROR): Data Fault
2014-06-25T18:48:50.667Z,1403722130.667 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:48:51.085Z,1403722131.085 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:48:51.534Z,1403722131.534 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:48:51.534Z,1403722131.534 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:48:51.534Z,1403722131.534 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:48:51.968Z,1403722131.968 [DVL_micro](INFO): Initializing
2014-06-25T18:48:52.051Z,1403722132.051 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:48:52.051Z,1403722132.051 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:51:56.484Z,1403722316.484 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:51:56.484Z,1403722316.484 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:51:56.484Z,1403722316.484 [DVL_micro](ERROR): Data Fault
2014-06-25T18:51:56.562Z,1403722316.562 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:51:56.912Z,1403722316.912 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:51:57.398Z,1403722317.398 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:51:57.398Z,1403722317.398 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:51:57.398Z,1403722317.398 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:51:57.836Z,1403722317.836 [DVL_micro](INFO): Initializing
2014-06-25T18:51:57.902Z,1403722317.902 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:51:57.902Z,1403722317.902 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:55:02.332Z,1403722502.332 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:55:02.332Z,1403722502.332 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:55:02.332Z,1403722502.332 [DVL_micro](ERROR): Data Fault
2014-06-25T18:55:02.480Z,1403722502.480 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:55:02.857Z,1403722502.857 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:55:03.421Z,1403722503.421 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:55:03.421Z,1403722503.421 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:55:03.421Z,1403722503.421 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:55:03.852Z,1403722503.852 [DVL_micro](INFO): Initializing
2014-06-25T18:55:03.933Z,1403722503.933 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:55:03.933Z,1403722503.933 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:58:07.899Z,1403722687.899 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T18:58:07.899Z,1403722687.899 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T18:58:07.900Z,1403722687.900 [DVL_micro](ERROR): Data Fault
2014-06-25T18:58:07.956Z,1403722687.956 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T18:58:08.337Z,1403722688.337 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T18:58:08.785Z,1403722688.785 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T18:58:08.785Z,1403722688.785 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T18:58:08.785Z,1403722688.785 [DVL_micro](ERROR): Hardware Fault
2014-06-25T18:58:09.240Z,1403722689.240 [DVL_micro](INFO): Initializing
2014-06-25T18:58:09.294Z,1403722689.294 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T18:58:09.294Z,1403722689.294 [DVL_micro] No Fault, FailCount= 1
2014-06-25T18:58:47.165Z,1403722727.165 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T18:58:47.165Z,1403722727.165 [NAL9602] Data Fault, FailCount= 2
2014-06-25T18:58:47.165Z,1403722727.165 [NAL9602](ERROR): Data Fault
2014-06-25T18:58:47.225Z,1403722727.225 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T18:58:47.605Z,1403722727.605 [NAL9602](INFO): Powering down
2014-06-25T18:58:48.472Z,1403722728.472 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T18:58:48.472Z,1403722728.472 [NAL9602] No Fault, FailCount= 2
2014-06-25T18:58:52.638Z,1403722732.638 [NAL9602](INFO): Powering up NAL9602
2014-06-25T18:59:03.444Z,1403722743.444 [NAL9602](INFO): NAL9602 initialized
2014-06-25T19:01:13.539Z,1403722873.539 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:01:13.539Z,1403722873.539 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:01:13.539Z,1403722873.539 [DVL_micro](ERROR): Data Fault
2014-06-25T19:01:13.572Z,1403722873.572 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:01:13.956Z,1403722873.956 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:01:14.412Z,1403722874.412 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:01:14.413Z,1403722874.413 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:01:14.413Z,1403722874.413 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:01:14.896Z,1403722874.896 [DVL_micro](INFO): Initializing
2014-06-25T19:01:14.964Z,1403722874.964 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:01:14.964Z,1403722874.964 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:04:18.886Z,1403723058.886 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:04:18.887Z,1403723058.887 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:04:18.887Z,1403723058.887 [DVL_micro](ERROR): Data Fault
2014-06-25T19:04:18.944Z,1403723058.944 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:04:19.312Z,1403723059.312 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:04:19.721Z,1403723059.721 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:04:19.721Z,1403723059.721 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:04:19.721Z,1403723059.721 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:04:20.159Z,1403723060.159 [DVL_micro](INFO): Initializing
2014-06-25T19:04:20.720Z,1403723060.720 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:04:20.720Z,1403723060.720 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:07:25.155Z,1403723245.155 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:07:25.155Z,1403723245.155 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:07:25.155Z,1403723245.155 [DVL_micro](ERROR): Data Fault
2014-06-25T19:07:25.212Z,1403723245.212 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:07:25.592Z,1403723245.592 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:07:26.061Z,1403723246.061 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:07:26.061Z,1403723246.061 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:07:26.061Z,1403723246.061 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:07:26.586Z,1403723246.586 [DVL_micro](INFO): Initializing
2014-06-25T19:07:26.643Z,1403723246.643 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:07:26.643Z,1403723246.643 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:09:07.443Z,1403723347.443 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T19:09:07.443Z,1403723347.443 [NAL9602] Data Fault, FailCount= 3
2014-06-25T19:09:07.443Z,1403723347.443 [NAL9602](ERROR): Data Fault
2014-06-25T19:09:07.496Z,1403723347.496 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T19:09:07.497Z,1403723347.497 [CBIT](CRITICAL): Data Fault in component: NAL9602
2014-06-25T19:09:07.881Z,1403723347.881 [NAL9602](INFO): Powering down
2014-06-25T19:10:30.603Z,1403723430.603 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:10:30.603Z,1403723430.603 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:10:30.603Z,1403723430.603 [DVL_micro](ERROR): Data Fault
2014-06-25T19:10:30.655Z,1403723430.655 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:10:31.030Z,1403723431.030 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:10:31.440Z,1403723431.440 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:10:31.440Z,1403723431.440 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:10:31.440Z,1403723431.440 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:10:31.855Z,1403723431.855 [DVL_micro](INFO): Initializing
2014-06-25T19:10:31.905Z,1403723431.905 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:10:31.905Z,1403723431.905 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:11:07.506Z,1403723467.506 [CBIT](INFO): Clearing failed count for component NAL9602
2014-06-25T19:11:07.506Z,1403723467.506 [NAL9602] No Fault, FailCount= 3
2014-06-25T19:11:07.886Z,1403723467.886 [NAL9602](INFO): Powering up NAL9602
2014-06-25T19:11:18.572Z,1403723478.572 [NAL9602](INFO): NAL9602 initialized
2014-06-25T19:13:36.179Z,1403723616.179 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:13:36.179Z,1403723616.179 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:13:36.179Z,1403723616.179 [DVL_micro](ERROR): Data Fault
2014-06-25T19:13:36.734Z,1403723616.734 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:13:37.117Z,1403723617.117 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:13:37.605Z,1403723617.605 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:13:37.605Z,1403723617.605 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:13:37.605Z,1403723617.605 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:13:38.033Z,1403723618.033 [DVL_micro](INFO): Initializing
2014-06-25T19:13:38.100Z,1403723618.100 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:13:38.100Z,1403723618.100 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:16:42.516Z,1403723802.516 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:16:42.516Z,1403723802.516 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:16:42.516Z,1403723802.516 [DVL_micro](ERROR): Data Fault
2014-06-25T19:16:42.603Z,1403723802.603 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:16:43.012Z,1403723803.012 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:16:43.477Z,1403723803.477 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:16:43.478Z,1403723803.478 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:16:43.478Z,1403723803.478 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:16:43.916Z,1403723803.916 [DVL_micro](INFO): Initializing
2014-06-25T19:16:44.001Z,1403723804.001 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:16:44.001Z,1403723804.001 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:19:48.488Z,1403723988.488 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:19:48.489Z,1403723988.489 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:19:48.489Z,1403723988.489 [DVL_micro](ERROR): Data Fault
2014-06-25T19:19:48.554Z,1403723988.554 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:19:48.909Z,1403723988.909 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:19:49.409Z,1403723989.409 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:19:49.409Z,1403723989.409 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:19:49.409Z,1403723989.409 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:19:50.358Z,1403723990.358 [DVL_micro](INFO): Initializing
2014-06-25T19:19:50.416Z,1403723990.416 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:19:50.417Z,1403723990.417 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:21:22.327Z,1403724082.327 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T19:21:22.327Z,1403724082.327 [NAL9602] Data Fault, FailCount= 1
2014-06-25T19:21:22.327Z,1403724082.327 [NAL9602](ERROR): Data Fault
2014-06-25T19:21:22.360Z,1403724082.360 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T19:21:22.768Z,1403724082.768 [NAL9602](INFO): Powering down
2014-06-25T19:21:23.654Z,1403724083.654 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T19:21:23.654Z,1403724083.654 [NAL9602] No Fault, FailCount= 1
2014-06-25T19:21:27.851Z,1403724087.851 [NAL9602](INFO): Powering up NAL9602
2014-06-25T19:21:38.669Z,1403724098.669 [NAL9602](INFO): NAL9602 initialized
2014-06-25T19:22:54.729Z,1403724174.729 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:22:54.729Z,1403724174.729 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:22:54.730Z,1403724174.730 [DVL_micro](ERROR): Data Fault
2014-06-25T19:22:54.804Z,1403724174.804 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:22:55.158Z,1403724175.158 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:22:55.578Z,1403724175.578 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:22:55.578Z,1403724175.578 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:22:55.578Z,1403724175.578 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:22:55.991Z,1403724175.991 [DVL_micro](INFO): Initializing
2014-06-25T19:22:56.056Z,1403724176.056 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:22:56.056Z,1403724176.056 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:26:00.060Z,1403724360.060 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:26:00.061Z,1403724360.061 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:26:00.061Z,1403724360.061 [DVL_micro](ERROR): Data Fault
2014-06-25T19:26:00.138Z,1403724360.138 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:26:00.505Z,1403724360.505 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:26:00.988Z,1403724360.988 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:26:00.988Z,1403724360.988 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:26:00.988Z,1403724360.988 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:26:01.465Z,1403724361.465 [DVL_micro](INFO): Initializing
2014-06-25T19:26:01.553Z,1403724361.553 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:26:01.553Z,1403724361.553 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:28:03.129Z,1403724483.129 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:05.453Z,1403724485.453 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:08.029Z,1403724488.029 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:10.408Z,1403724490.408 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:12.998Z,1403724492.998 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:15.320Z,1403724495.320 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:17.516Z,1403724497.516 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:20.279Z,1403724500.279 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:22.420Z,1403724502.420 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:25.145Z,1403724505.145 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:27.343Z,1403724507.343 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:30.084Z,1403724510.084 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:32.263Z,1403724512.263 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:34.950Z,1403724514.950 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:37.210Z,1403724517.210 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:39.486Z,1403724519.486 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:42.114Z,1403724522.114 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:44.349Z,1403724524.349 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:47.037Z,1403724527.037 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:49.265Z,1403724529.265 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:28:51.933Z,1403724531.933 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2014-06-25T19:29:05.720Z,1403724545.720 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:29:05.720Z,1403724545.720 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:29:05.720Z,1403724545.720 [DVL_micro](ERROR): Data Fault
2014-06-25T19:29:05.804Z,1403724545.804 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:29:06.213Z,1403724546.213 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:29:07.146Z,1403724547.146 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:29:07.146Z,1403724547.146 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:29:07.146Z,1403724547.146 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:29:07.605Z,1403724547.605 [DVL_micro](INFO): Initializing
2014-06-25T19:29:07.652Z,1403724547.652 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:29:07.652Z,1403724547.652 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:31:41.552Z,1403724701.552 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T19:31:41.552Z,1403724701.552 [NAL9602] Data Fault, FailCount= 2
2014-06-25T19:31:41.552Z,1403724701.552 [NAL9602](ERROR): Data Fault
2014-06-25T19:31:41.618Z,1403724701.618 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T19:31:41.972Z,1403724701.972 [NAL9602](INFO): Powering down
2014-06-25T19:31:42.864Z,1403724702.864 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T19:31:42.864Z,1403724702.864 [NAL9602] No Fault, FailCount= 2
2014-06-25T19:31:47.067Z,1403724707.067 [NAL9602](INFO): Powering up NAL9602
2014-06-25T19:31:57.753Z,1403724717.753 [NAL9602](INFO): NAL9602 initialized
2014-06-25T19:32:11.816Z,1403724731.816 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:32:11.816Z,1403724731.816 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:32:11.816Z,1403724731.816 [DVL_micro](ERROR): Data Fault
2014-06-25T19:32:12.353Z,1403724732.353 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:32:12.854Z,1403724732.854 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:32:13.297Z,1403724733.297 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:32:13.297Z,1403724733.297 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:32:13.297Z,1403724733.297 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:32:13.713Z,1403724733.713 [DVL_micro](INFO): Initializing
2014-06-25T19:32:13.777Z,1403724733.777 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:32:13.802Z,1403724733.802 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:34:06.047Z,1403724846.047 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-06-25T19:34:06.047Z,1403724846.047 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -161 -145 -175 -213 3 3 3 3 18.7 90.8 -187.2 2 21.1 -90.3 -187.2 2 18 90 -187 2 21 -90 -187 2 -5.44 -0.27 204.8 -3.0 23.8 0.005 3564
2014-06-25T19:35:18.262Z,1403724918.262 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:35:18.262Z,1403724918.262 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:35:18.262Z,1403724918.262 [DVL_micro](ERROR): Data Fault
2014-06-25T19:35:18.337Z,1403724918.337 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:35:18.686Z,1403724918.686 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:35:19.098Z,1403724919.098 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:35:19.099Z,1403724919.099 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:35:19.099Z,1403724919.099 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:35:19.527Z,1403724919.527 [DVL_micro](INFO): Initializing
2014-06-25T19:35:19.562Z,1403724919.562 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:35:19.562Z,1403724919.562 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:38:23.921Z,1403725103.921 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:38:23.921Z,1403725103.921 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:38:23.922Z,1403725103.922 [DVL_micro](ERROR): Data Fault
2014-06-25T19:38:24.001Z,1403725104.001 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:38:24.355Z,1403725104.355 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:38:24.794Z,1403725104.794 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:38:24.794Z,1403725104.794 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:38:24.795Z,1403725104.795 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:38:25.210Z,1403725105.210 [DVL_micro](INFO): Initializing
2014-06-25T19:38:25.267Z,1403725105.267 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:38:25.267Z,1403725105.267 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:40:21.562Z,1403725221.562 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-06-25T19:40:21.562Z,1403725221.562 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 -109 -135 -45 -204 3 3 3 3 -85.4 92.1 -132.9 2 116.4 -47.2 -132.9 2 -85 92 -132 2 116 -47 -132 2 -5.46 -0.29 205.1 -3.0 23.8 0.005 35.0 1489 119
2014-06-25T19:41:29.600Z,1403725289.600 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:41:29.600Z,1403725289.600 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:41:29.600Z,1403725289.600 [DVL_micro](ERROR): Data Fault
2014-06-25T19:41:29.678Z,1403725289.678 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:41:30.042Z,1403725290.042 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:41:30.466Z,1403725290.466 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:41:30.466Z,1403725290.466 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:41:30.466Z,1403725290.466 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:41:30.882Z,1403725290.882 [DVL_micro](INFO): Initializing
2014-06-25T19:41:30.931Z,1403725290.931 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:41:30.931Z,1403725290.931 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:42:03.242Z,1403725323.242 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T19:42:03.242Z,1403725323.242 [NAL9602] Data Fault, FailCount= 3
2014-06-25T19:42:03.242Z,1403725323.242 [NAL9602](ERROR): Data Fault
2014-06-25T19:42:03.303Z,1403725323.303 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T19:42:03.303Z,1403725323.303 [CBIT](CRITICAL): Data Fault in component: NAL9602
2014-06-25T19:42:03.663Z,1403725323.663 [NAL9602](INFO): Powering down
2014-06-25T19:44:03.586Z,1403725443.586 [CBIT](INFO): Clearing failed count for component NAL9602
2014-06-25T19:44:03.586Z,1403725443.586 [NAL9602] No Fault, FailCount= 3
2014-06-25T19:44:03.957Z,1403725443.957 [NAL9602](INFO): Powering up NAL9602
2014-06-25T19:44:14.728Z,1403725454.728 [NAL9602](INFO): NAL9602 initialized
2014-06-25T19:44:35.198Z,1403725475.198 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:44:35.198Z,1403725475.198 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:44:35.198Z,1403725475.198 [DVL_micro](ERROR): Data Fault
2014-06-25T19:44:35.277Z,1403725475.277 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:44:35.622Z,1403725475.622 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:44:36.057Z,1403725476.057 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:44:36.057Z,1403725476.057 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:44:36.057Z,1403725476.057 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:44:36.498Z,1403725476.498 [DVL_micro](INFO): Initializing
2014-06-25T19:44:36.531Z,1403725476.531 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:44:36.531Z,1403725476.531 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:47:40.701Z,1403725660.701 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:47:40.701Z,1403725660.701 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:47:40.701Z,1403725660.701 [DVL_micro](ERROR): Data Fault
2014-06-25T19:47:40.733Z,1403725660.733 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:47:41.117Z,1403725661.117 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:47:41.534Z,1403725661.534 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:47:41.534Z,1403725661.534 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:47:41.534Z,1403725661.534 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:47:41.977Z,1403725661.977 [DVL_micro](INFO): Initializing
2014-06-25T19:47:42.037Z,1403725662.037 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:47:42.038Z,1403725662.038 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:50:46.212Z,1403725846.212 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:50:46.212Z,1403725846.212 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:50:46.212Z,1403725846.212 [DVL_micro](ERROR): Data Fault
2014-06-25T19:50:46.299Z,1403725846.299 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:50:46.702Z,1403725846.702 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:50:47.109Z,1403725847.109 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:50:47.109Z,1403725847.109 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:50:47.109Z,1403725847.109 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:50:47.534Z,1403725847.534 [DVL_micro](INFO): Initializing
2014-06-25T19:50:47.569Z,1403725847.569 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:50:47.569Z,1403725847.569 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:53:52.397Z,1403726032.397 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:53:52.397Z,1403726032.397 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:53:52.397Z,1403726032.397 [DVL_micro](ERROR): Data Fault
2014-06-25T19:53:52.957Z,1403726032.957 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:53:53.310Z,1403726033.310 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:53:53.795Z,1403726033.795 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:53:53.795Z,1403726033.795 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:53:53.795Z,1403726033.795 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:53:54.218Z,1403726034.218 [DVL_micro](INFO): Initializing
2014-06-25T19:53:54.278Z,1403726034.278 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:53:54.278Z,1403726034.278 [DVL_micro] No Fault, FailCount= 1
2014-06-25T19:54:19.147Z,1403726059.147 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T19:54:19.147Z,1403726059.147 [NAL9602] Data Fault, FailCount= 1
2014-06-25T19:54:19.147Z,1403726059.147 [NAL9602](ERROR): Data Fault
2014-06-25T19:54:19.207Z,1403726059.207 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T19:54:19.580Z,1403726059.580 [NAL9602](INFO): Powering down
2014-06-25T19:54:20.510Z,1403726060.510 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T19:54:20.510Z,1403726060.510 [NAL9602] No Fault, FailCount= 1
2014-06-25T19:54:24.752Z,1403726064.752 [NAL9602](INFO): Powering up NAL9602
2014-06-25T19:54:35.646Z,1403726075.646 [NAL9602](INFO): NAL9602 initialized
2014-06-25T19:56:58.942Z,1403726218.942 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T19:56:58.942Z,1403726218.942 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T19:56:58.942Z,1403726218.942 [DVL_micro](ERROR): Data Fault
2014-06-25T19:56:58.997Z,1403726218.997 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T19:56:59.391Z,1403726219.391 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T19:56:59.815Z,1403726219.815 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T19:56:59.815Z,1403726219.815 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T19:56:59.815Z,1403726219.815 [DVL_micro](ERROR): Hardware Fault
2014-06-25T19:57:00.242Z,1403726220.242 [DVL_micro](INFO): Initializing
2014-06-25T19:57:00.296Z,1403726220.296 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T19:57:00.296Z,1403726220.296 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:00:04.615Z,1403726404.615 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:00:04.615Z,1403726404.615 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:00:04.615Z,1403726404.615 [DVL_micro](ERROR): Data Fault
2014-06-25T20:00:04.699Z,1403726404.699 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:00:05.059Z,1403726405.059 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:00:05.467Z,1403726405.467 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:00:05.467Z,1403726405.467 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:00:05.467Z,1403726405.467 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:00:05.904Z,1403726405.904 [DVL_micro](INFO): Initializing
2014-06-25T20:00:05.971Z,1403726405.971 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:00:05.971Z,1403726405.971 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:02:10.968Z,1403726530.968 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:122 Calculated:100
2014-06-25T20:02:10.968Z,1403726530.968 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -244 -90 -218 -214 3 3 3 3 -34.-206.5 2 101.4 -135.3 -206.5 2 -34 165 -206 2 101 -135 -206 2 -5.45 -0.31 205.0 -3.0 24.0 0.005 35.0 1489 122
2014-06-25T20:03:09.929Z,1403726589.929 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:03:09.930Z,1403726589.930 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:03:09.930Z,1403726589.930 [DVL_micro](ERROR): Data Fault
2014-06-25T20:03:10.010Z,1403726590.010 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:03:10.358Z,1403726590.358 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:03:11.400Z,1403726591.400 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:03:11.400Z,1403726591.400 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:03:11.400Z,1403726591.400 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:03:11.841Z,1403726591.841 [DVL_micro](INFO): Initializing
2014-06-25T20:03:11.894Z,1403726591.894 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:03:11.894Z,1403726591.894 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:04:38.143Z,1403726678.143 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T20:04:38.143Z,1403726678.143 [NAL9602] Data Fault, FailCount= 2
2014-06-25T20:04:38.143Z,1403726678.143 [NAL9602](ERROR): Data Fault
2014-06-25T20:04:38.207Z,1403726678.207 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T20:04:38.631Z,1403726678.631 [NAL9602](INFO): Powering down
2014-06-25T20:04:39.496Z,1403726679.496 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T20:04:39.496Z,1403726679.496 [NAL9602] No Fault, FailCount= 2
2014-06-25T20:04:43.751Z,1403726683.751 [NAL9602](INFO): Powering up NAL9602
2014-06-25T20:04:54.518Z,1403726694.518 [NAL9602](INFO): NAL9602 initialized
2014-06-25T20:06:16.090Z,1403726776.090 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:06:16.090Z,1403726776.090 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:06:16.091Z,1403726776.091 [DVL_micro](ERROR): Data Fault
2014-06-25T20:06:16.172Z,1403726776.172 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:06:16.590Z,1403726776.590 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:06:17.512Z,1403726777.512 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:06:17.512Z,1403726777.512 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:06:17.512Z,1403726777.512 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:06:17.964Z,1403726777.964 [DVL_micro](INFO): Initializing
2014-06-25T20:06:18.018Z,1403726778.018 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:06:18.018Z,1403726778.018 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:08:22.984Z,1403726902.984 [Default:Iridium:Read_Iridium](INFO): Timed out from 2014-06-25T18:08:22.7Z
2014-06-25T20:08:22.984Z,1403726902.984 [Default:Iridium:Read_Iridium:A_Timeout] Running Loop=1
2014-06-25T20:08:22.984Z,1403726902.984 [Default:Iridium:Read_Iridium:A_Timeout](INFO): Aggregate::initialize Default:Iridium:Read_Iridium:A_Timeout
2014-06-25T20:08:22.984Z,1403726902.984 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute] Running Loop=1
2014-06-25T20:08:22.985Z,1403726902.985 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](INFO): Executing command Burn 300
2014-06-25T20:08:22.991Z,1403726902.991 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute] Stopped
2014-06-25T20:08:22.991Z,1403726902.991 [Default:Iridium:Read_Iridium:A_Timeout:B] Running Loop=1
2014-06-25T20:08:23.049Z,1403726903.049 [CommandLine](IMPORTANT): got command burn 300.000000
2014-06-25T20:08:23.431Z,1403726903.431 [Default:Iridium:Read_Iridium:A_Timeout:B](CRITICAL): Dropped drop weight due to communications timeout
2014-06-25T20:08:23.433Z,1403726903.433 [Default:Iridium:Read_Iridium:A_Timeout:B] Stopped
2014-06-25T20:08:23.433Z,1403726903.433 [Default:Iridium:Read_Iridium:A_Timeout](INFO): Completed Default:Iridium:Read_Iridium:A_Timeout
2014-06-25T20:08:23.434Z,1403726903.434 [Default:Iridium:Read_Iridium] Stopped
2014-06-25T20:08:23.435Z,1403726903.435 [Default:Iridium](INFO): Completed Default:Iridium
2014-06-25T20:08:23.435Z,1403726903.435 [Default:Iridium] Stopped
2014-06-25T20:08:23.435Z,1403726903.435 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2014-06-25T20:08:23.435Z,1403726903.435 [Default:Iridium:A.SetSpeed] Stopped
2014-06-25T20:08:23.435Z,1403726903.435 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2014-06-25T20:08:23.435Z,1403726903.435 [Default:Iridium:B.GoToSurface] Stopped
2014-06-25T20:08:23.435Z,1403726903.435 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-06-25T20:08:23.435Z,1403726903.435 [Default:WaitAtTheSurface] Running Loop=1
2014-06-25T20:08:23.435Z,1403726903.435 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2014-06-25T20:08:23.435Z,1403726903.435 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Running Loop=1
2014-06-25T20:08:23.435Z,1403726903.435 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Initialize.
2014-06-25T20:08:23.923Z,1403726903.923 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Running Loop=1
2014-06-25T20:08:23.932Z,1403726903.932 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2014-06-25T20:08:23.933Z,1403726903.933 [Default:CallIridium:B] Stopped
2014-06-25T20:08:23.933Z,1403726903.933 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2014-06-25T20:08:23.933Z,1403726903.933 [Default:CallIridium](INFO): Completed Default:CallIridium
2014-06-25T20:08:23.933Z,1403726903.933 [Default:CallIridium] Stopped
2014-06-25T20:08:23.933Z,1403726903.933 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2014-06-25T20:08:24.348Z,1403726904.348 [Default:CallIridium] Running Loop=1
2014-06-25T20:08:24.349Z,1403726904.349 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2014-06-25T20:08:24.349Z,1403726904.349 [Default:CallIridium:A] Running Loop=1
2014-06-25T20:08:24.349Z,1403726904.349 [Default:CallIridium:A] Stopped
2014-06-25T20:08:24.349Z,1403726904.349 [Default:CallIridium:B] Running Loop=1
2014-06-25T20:08:24.349Z,1403726904.349 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2014-06-25T20:08:24.757Z,1403726904.757 [Default:Iridium] Running Loop=1
2014-06-25T20:08:24.757Z,1403726904.757 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2014-06-25T20:08:24.758Z,1403726904.758 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-06-25T20:08:24.758Z,1403726904.758 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2014-06-25T20:08:24.758Z,1403726904.758 [Default:Iridium:B.GoToSurface] Running Loop=1
2014-06-25T20:08:24.758Z,1403726904.758 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-06-25T20:08:24.758Z,1403726904.758 [Default:Iridium:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-06-25T20:08:24.758Z,1403726904.758 [Default:Iridium:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-06-25T20:08:24.759Z,1403726904.759 [Default:Iridium:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-06-25T20:08:24.760Z,1403726904.760 [Default:Iridium:Read_Iridium] Running Loop=1
2014-06-25T20:09:05.194Z,1403726945.194 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:87 Calculated:82
2014-06-25T20:09:05.194Z,1403726945.194 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0-138 -22 0 3 3 3 3 -116.1 -184.2 -72.5 2 27.4 216.0 -72.5 2 -116 -184 -72 2 27 216 -72 2 -5.44 -0.26 205.0 -3.0 24.0 0.005 35.0 1489 87
2014-06-25T20:09:22.616Z,1403726962.616 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:09:22.616Z,1403726962.616 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:09:22.616Z,1403726962.616 [DVL_micro](ERROR): Data Fault
2014-06-25T20:09:22.692Z,1403726962.692 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:09:23.040Z,1403726963.040 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:09:23.477Z,1403726963.477 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:09:23.477Z,1403726963.477 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:09:23.477Z,1403726963.477 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:09:24.405Z,1403726964.405 [DVL_micro](INFO): Initializing
2014-06-25T20:09:24.511Z,1403726964.511 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:09:24.511Z,1403726964.511 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:09:59.870Z,1403726999.870 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-06-25T20:09:59.870Z,1403726999.870 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 -108 -129 -136 -134 3 3 3 3 37.4 6.7 -136.7 2 -31.1 -21.8 -136.7 2 37 6 -136 2 -31 -21 -136 2 -5.44 -0.34 204.8 -3.0 24.0 0.005 35.0 1489 78
2014-06-25T20:12:28.551Z,1403727148.551 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:12:28.551Z,1403727148.551 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:12:28.551Z,1403727148.551 [DVL_micro](ERROR): Data Fault
2014-06-25T20:12:28.643Z,1403727148.643 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:12:29.064Z,1403727149.064 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:12:29.515Z,1403727149.515 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:12:29.516Z,1403727149.516 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:12:29.516Z,1403727149.516 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:12:29.948Z,1403727149.948 [DVL_micro](INFO): Initializing
2014-06-25T20:12:30.033Z,1403727150.033 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:12:30.033Z,1403727150.033 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:12:49.818Z,1403727169.818 [NAL9602](INFO): SBD MO Status=2, MOMSN=36478, MT Status=2, MTMSN=0
2014-06-25T20:12:49.819Z,1403727169.819 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2014-06-25T20:13:23.070Z,1403727203.070 [CommandLine](IMPORTANT): Deactivating dropweight wire
2014-06-25T20:14:59.194Z,1403727299.194 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T20:14:59.194Z,1403727299.194 [NAL9602] Data Fault, FailCount= 3
2014-06-25T20:14:59.195Z,1403727299.195 [NAL9602](ERROR): Data Fault
2014-06-25T20:14:59.260Z,1403727299.260 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T20:14:59.260Z,1403727299.260 [CBIT](CRITICAL): Data Fault in component: NAL9602
2014-06-25T20:14:59.614Z,1403727299.614 [NAL9602](INFO): Powering down
2014-06-25T20:15:33.815Z,1403727333.815 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:15:33.815Z,1403727333.815 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:15:33.815Z,1403727333.815 [DVL_micro](ERROR): Data Fault
2014-06-25T20:15:33.876Z,1403727333.876 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:15:34.243Z,1403727334.243 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:15:34.664Z,1403727334.664 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:15:34.664Z,1403727334.664 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:15:34.664Z,1403727334.664 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:15:35.087Z,1403727335.087 [DVL_micro](INFO): Initializing
2014-06-25T20:15:35.137Z,1403727335.137 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:15:35.137Z,1403727335.137 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:16:59.546Z,1403727419.546 [CBIT](INFO): Clearing failed count for component NAL9602
2014-06-25T20:16:59.547Z,1403727419.547 [NAL9602] No Fault, FailCount= 3
2014-06-25T20:16:59.929Z,1403727419.929 [NAL9602](INFO): Powering up NAL9602
2014-06-25T20:17:10.675Z,1403727430.675 [NAL9602](INFO): NAL9602 initialized
2014-06-25T20:18:38.946Z,1403727518.946 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:18:38.946Z,1403727518.946 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:18:38.946Z,1403727518.946 [DVL_micro](ERROR): Data Fault
2014-06-25T20:18:39.010Z,1403727519.010 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:18:39.387Z,1403727519.387 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:18:40.323Z,1403727520.323 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:18:40.324Z,1403727520.324 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:18:40.324Z,1403727520.324 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:18:40.768Z,1403727520.768 [DVL_micro](INFO): Initializing
2014-06-25T20:18:40.801Z,1403727520.801 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:18:40.801Z,1403727520.801 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:20:38.692Z,1403727638.692 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-06-25T20:20:38.693Z,1403727638.693 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -73 -26 -90 -77 3 3 3 3 22.7 68.1 -71.7 2 8.5 -71.3 -71.7 2 22 68 -71 2 8 -71 -71 2 -5.47 -0.30 205.2 -3.0 24.1 0. 1489 65
2014-06-25T20:21:44.758Z,1403727704.758 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:21:44.758Z,1403727704.758 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:21:44.758Z,1403727704.758 [DVL_micro](ERROR): Data Fault
2014-06-25T20:21:44.814Z,1403727704.814 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:21:45.179Z,1403727705.179 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:21:45.647Z,1403727705.647 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:21:45.648Z,1403727705.648 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:21:45.648Z,1403727705.648 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:21:46.579Z,1403727706.579 [DVL_micro](INFO): Initializing
2014-06-25T20:21:46.661Z,1403727706.661 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:21:46.661Z,1403727706.661 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:24:51.207Z,1403727891.207 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:24:51.207Z,1403727891.207 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:24:51.207Z,1403727891.207 [DVL_micro](ERROR): Data Fault
2014-06-25T20:24:51.276Z,1403727891.276 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:24:51.660Z,1403727891.660 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:24:52.678Z,1403727892.678 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:24:52.678Z,1403727892.678 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:24:52.678Z,1403727892.678 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:24:53.120Z,1403727893.120 [DVL_micro](INFO): Initializing
2014-06-25T20:24:53.174Z,1403727893.174 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:24:53.174Z,1403727893.174 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:25:22.583Z,1403727922.583 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:24.394Z,1403727924.394 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:27.065Z,1403727927.065 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:28.958Z,1403727928.958 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:32.069Z,1403727932.069 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:33.924Z,1403727933.924 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:36.471Z,1403727936.471 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:38.763Z,1403727938.763 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:40.976Z,1403727940.976 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:43.215Z,1403727943.215 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:45.890Z,1403727945.890 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:48.122Z,1403727948.122 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:50.795Z,1403727950.795 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:53.034Z,1403727953.034 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:55.260Z,1403727955.260 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:25:57.933Z,1403727957.933 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:00.173Z,1403727960.173 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:02.825Z,1403727962.825 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:05.070Z,1403727965.070 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:07.288Z,1403727967.288 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:10.006Z,1403727970.006 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:12.239Z,1403727972.239 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:14.443Z,1403727974.443 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:17.155Z,1403727977.155 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:19.374Z,1403727979.374 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:21.673Z,1403727981.673 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:24.270Z,1403727984.270 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:26.613Z,1403727986.613 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:29.243Z,1403727989.243 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:31.574Z,1403727991.574 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:33.798Z,1403727993.798 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:36.080Z,1403727996.080 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:38.656Z,1403727998.656 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:40.924Z,1403728000.924 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:43.183Z,1403728003.183 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:45.853Z,1403728005.853 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:48.063Z,1403728008.063 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:50.747Z,1403728010.747 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:52.959Z,1403728012.959 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:55.181Z,1403728015.181 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:26:57.469Z,1403728017.469 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:27:00.106Z,1403728020.106 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:27:02.382Z,1403728022.382 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2014-06-25T20:27:14.898Z,1403728034.898 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T20:27:14.898Z,1403728034.898 [NAL9602] Data Fault, FailCount= 1
2014-06-25T20:27:14.898Z,1403728034.898 [NAL9602](ERROR): Data Fault
2014-06-25T20:27:14.962Z,1403728034.962 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T20:27:15.351Z,1403728035.351 [NAL9602](INFO): Powering down
2014-06-25T20:27:16.216Z,1403728036.216 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T20:27:16.216Z,1403728036.216 [NAL9602] No Fault, FailCount= 1
2014-06-25T20:27:20.447Z,1403728040.447 [NAL9602](INFO): Powering up NAL9602
2014-06-25T20:27:31.120Z,1403728051.120 [NAL9602](INFO): NAL9602 initialized
2014-06-25T20:27:57.884Z,1403728077.884 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:27:57.885Z,1403728077.885 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:27:57.885Z,1403728077.885 [DVL_micro](ERROR): Data Fault
2014-06-25T20:27:57.964Z,1403728077.964 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:27:58.317Z,1403728078.317 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:27:58.790Z,1403728078.790 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:27:58.790Z,1403728078.790 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:27:58.790Z,1403728078.790 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:27:59.244Z,1403728079.244 [DVL_micro](INFO): Initializing
2014-06-25T20:27:59.788Z,1403728079.788 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:27:59.788Z,1403728079.788 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:31:04.330Z,1403728264.330 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:31:04.330Z,1403728264.330 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:31:04.330Z,1403728264.330 [DVL_micro](ERROR): Data Fault
2014-06-25T20:31:04.421Z,1403728264.421 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:31:04.818Z,1403728264.818 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:31:05.234Z,1403728265.234 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:31:05.234Z,1403728265.234 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:31:05.235Z,1403728265.235 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:31:05.670Z,1403728265.670 [DVL_micro](INFO): Initializing
2014-06-25T20:31:05.740Z,1403728265.740 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:31:05.740Z,1403728265.740 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:31:59.352Z,1403728319.352 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:124 Calculated:98
2014-06-25T20:31:59.353Z,1403728319.353 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -160 -81 -23 -77 3 3 3 3 -182.8 -5.3 -92.0 2 16 -92.0 2 -182 -5 -92 2 163 82 -92 2 -5.50 -0.31 205.2 -3.0 24.1 0.005 35.0 1489 124
2014-06-25T20:34:10.490Z,1403728450.490 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:34:10.490Z,1403728450.490 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:34:10.490Z,1403728450.490 [DVL_micro](ERROR): Data Fault
2014-06-25T20:34:10.573Z,1403728450.573 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:34:10.912Z,1403728450.912 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:34:11.351Z,1403728451.351 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:34:11.351Z,1403728451.351 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:34:11.351Z,1403728451.351 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:34:11.770Z,1403728451.770 [DVL_micro](INFO): Initializing
2014-06-25T20:34:11.826Z,1403728451.826 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:34:11.826Z,1403728451.826 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:37:16.545Z,1403728636.545 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:37:16.545Z,1403728636.545 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:37:16.545Z,1403728636.545 [DVL_micro](ERROR): Data Fault
2014-06-25T20:37:17.102Z,1403728637.102 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:37:17.456Z,1403728637.456 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:37:17.899Z,1403728637.899 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:37:17.899Z,1403728637.899 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:37:17.899Z,1403728637.899 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:37:18.319Z,1403728638.319 [DVL_micro](INFO): Initializing
2014-06-25T20:37:18.369Z,1403728638.369 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:37:18.369Z,1403728638.369 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:37:33.369Z,1403728653.369 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T20:37:33.369Z,1403728653.369 [NAL9602] Data Fault, FailCount= 2
2014-06-25T20:37:33.369Z,1403728653.369 [NAL9602](ERROR): Data Fault
2014-06-25T20:37:33.435Z,1403728653.435 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T20:37:33.780Z,1403728653.780 [NAL9602](INFO): Powering down
2014-06-25T20:37:34.681Z,1403728654.681 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T20:37:34.681Z,1403728654.681 [NAL9602] No Fault, FailCount= 2
2014-06-25T20:37:38.890Z,1403728658.890 [NAL9602](INFO): Powering up NAL9602
2014-06-25T20:37:49.616Z,1403728669.616 [NAL9602](INFO): NAL9602 initialized
2014-06-25T20:39:55.900Z,1403728795.900 [NAL9602](INFO): SBD MO Status=2, MOMSN=36478, MT Status=2, MTMSN=0
2014-06-25T20:39:55.901Z,1403728795.901 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2014-06-25T20:40:23.180Z,1403728823.180 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:40:23.180Z,1403728823.180 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:40:23.180Z,1403728823.180 [DVL_micro](ERROR): Data Fault
2014-06-25T20:40:23.252Z,1403728823.252 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:40:23.593Z,1403728823.593 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:40:24.049Z,1403728824.049 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:40:24.049Z,1403728824.049 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:40:24.049Z,1403728824.049 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:40:24.475Z,1403728824.475 [DVL_micro](INFO): Initializing
2014-06-25T20:40:24.509Z,1403728824.509 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:40:24.509Z,1403728824.509 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:41:22.633Z,1403728882.633 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-06-25T20:41:22.633Z,1403728882.633 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -49 -50 49 -83 -130.8 40.0 -35.1 2 135.5 18.3 -35.1 2 -130 40 -35 2 135 18 -35 2 -5.45 -0.37 204.7 -3.0 24.2 0.005 35.0 1489 100
2014-06-25T20:41:30.779Z,1403728890.779 [NAL9602](INFO): SBD MO Status=2, MOMSN=36478, MT Status=2, MTMSN=0
2014-06-25T20:41:30.779Z,1403728890.779 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2014-06-25T20:43:28.471Z,1403729008.471 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:43:28.471Z,1403729008.471 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:43:28.471Z,1403729008.471 [DVL_micro](ERROR): Data Fault
2014-06-25T20:43:28.543Z,1403729008.543 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:43:28.908Z,1403729008.908 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:43:29.332Z,1403729009.332 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:43:29.332Z,1403729009.332 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:43:29.332Z,1403729009.332 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:43:29.746Z,1403729009.746 [DVL_micro](INFO): Initializing
2014-06-25T20:43:29.794Z,1403729009.794 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:43:29.794Z,1403729009.794 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:45:07.347Z,1403729107.347 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 21 of 46
2014-06-25T20:45:07.347Z,1403729107.347 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -19 -6.9 -3.0 24.2 0.005 35.0 1489 124
2014-06-25T20:46:34.111Z,1403729194.111 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:46:34.111Z,1403729194.111 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:46:34.111Z,1403729194.111 [DVL_micro](ERROR): Data Fault
2014-06-25T20:46:34.194Z,1403729194.194 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:46:34.544Z,1403729194.544 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:46:34.968Z,1403729194.968 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:46:34.968Z,1403729194.968 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:46:34.968Z,1403729194.968 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:46:35.387Z,1403729195.387 [DVL_micro](INFO): Initializing
2014-06-25T20:46:35.444Z,1403729195.444 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:46:35.445Z,1403729195.445 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:47:50.478Z,1403729270.478 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T20:47:50.478Z,1403729270.478 [NAL9602] Data Fault, FailCount= 3
2014-06-25T20:47:50.478Z,1403729270.478 [NAL9602](ERROR): Data Fault
2014-06-25T20:47:50.511Z,1403729270.511 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T20:47:50.512Z,1403729270.512 [CBIT](CRITICAL): Data Fault in component: NAL9602
2014-06-25T20:47:50.891Z,1403729270.891 [NAL9602](INFO): Powering down
2014-06-25T20:49:39.422Z,1403729379.422 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:49:39.422Z,1403729379.422 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:49:39.422Z,1403729379.422 [DVL_micro](ERROR): Data Fault
2014-06-25T20:49:39.480Z,1403729379.480 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:49:39.835Z,1403729379.835 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:49:40.238Z,1403729380.238 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:49:40.238Z,1403729380.238 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:49:40.238Z,1403729380.238 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:49:40.664Z,1403729380.664 [DVL_micro](INFO): Initializing
2014-06-25T20:49:40.696Z,1403729380.696 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:49:40.696Z,1403729380.696 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:49:50.860Z,1403729390.860 [CBIT](INFO): Clearing failed count for component NAL9602
2014-06-25T20:49:50.860Z,1403729390.860 [NAL9602] No Fault, FailCount= 3
2014-06-25T20:49:51.229Z,1403729391.229 [NAL9602](INFO): Powering up NAL9602
2014-06-25T20:50:02.008Z,1403729402.008 [NAL9602](INFO): NAL9602 initialized
2014-06-25T20:50:03.029Z,1403729403.029 [NAL9602](ERROR): getFix uart error: serial timeout
2014-06-25T20:51:53.885Z,1403729513.885 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-06-25T20:51:53.886Z,1403729513.886 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -58 -64 -119 -102 3 3 3 3 81.4 50.7 -92.5 2 -52.2 -80.4 -92.5 2 8 2 -52 -80 -92 2 -5.48 -0.31 205.1 -3.0 24.2 0.005 35.0 1489 70
2014-06-25T20:52:44.943Z,1403729564.943 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:52:44.943Z,1403729564.943 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:52:44.943Z,1403729564.943 [DVL_micro](ERROR): Data Fault
2014-06-25T20:52:45.012Z,1403729565.012 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:52:45.370Z,1403729565.370 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:52:45.791Z,1403729565.791 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:52:45.791Z,1403729565.791 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:52:45.791Z,1403729565.791 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:52:46.214Z,1403729566.214 [DVL_micro](INFO): Initializing
2014-06-25T20:52:46.270Z,1403729566.270 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:52:46.270Z,1403729566.270 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:55:50.305Z,1403729750.305 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:55:50.305Z,1403729750.305 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:55:50.305Z,1403729750.305 [DVL_micro](ERROR): Data Fault
2014-06-25T20:55:50.359Z,1403729750.359 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:55:50.826Z,1403729750.826 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:55:51.265Z,1403729751.265 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:55:51.265Z,1403729751.265 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:55:51.265Z,1403729751.265 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:55:51.722Z,1403729751.722 [DVL_micro](INFO): Initializing
2014-06-25T20:55:51.785Z,1403729751.785 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:55:51.785Z,1403729751.785 [DVL_micro] No Fault, FailCount= 1
2014-06-25T20:58:55.624Z,1403729935.624 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T20:58:55.624Z,1403729935.624 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T20:58:55.624Z,1403729935.624 [DVL_micro](ERROR): Data Fault
2014-06-25T20:58:55.657Z,1403729935.657 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T20:58:56.037Z,1403729936.037 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T20:58:56.510Z,1403729936.510 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T20:58:56.510Z,1403729936.510 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T20:58:56.510Z,1403729936.510 [DVL_micro](ERROR): Hardware Fault
2014-06-25T20:58:57.005Z,1403729937.005 [DVL_micro](INFO): Initializing
2014-06-25T20:58:57.061Z,1403729937.061 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T20:58:57.061Z,1403729937.061 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:00:05.943Z,1403730005.943 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T21:00:05.943Z,1403730005.943 [NAL9602] Data Fault, FailCount= 1
2014-06-25T21:00:05.943Z,1403730005.943 [NAL9602](ERROR): Data Fault
2014-06-25T21:00:06.019Z,1403730006.019 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T21:00:06.375Z,1403730006.375 [NAL9602](INFO): Powering down
2014-06-25T21:00:07.258Z,1403730007.258 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T21:00:07.258Z,1403730007.258 [NAL9602] No Fault, FailCount= 1
2014-06-25T21:00:11.495Z,1403730011.495 [NAL9602](INFO): Powering up NAL9602
2014-06-25T21:00:22.269Z,1403730022.269 [NAL9602](INFO): NAL9602 initialized
2014-06-25T21:02:01.625Z,1403730121.625 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:02:01.625Z,1403730121.625 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:02:01.625Z,1403730121.625 [DVL_micro](ERROR): Data Fault
2014-06-25T21:02:01.659Z,1403730121.659 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:02:02.042Z,1403730122.042 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:02:02.482Z,1403730122.482 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:02:02.482Z,1403730122.482 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:02:02.482Z,1403730122.482 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:02:02.982Z,1403730122.982 [DVL_micro](INFO): Initializing
2014-06-25T21:02:03.050Z,1403730123.050 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:02:03.050Z,1403730123.050 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:05:07.341Z,1403730307.341 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:05:07.341Z,1403730307.341 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:05:07.341Z,1403730307.341 [DVL_micro](ERROR): Data Fault
2014-06-25T21:05:07.446Z,1403730307.446 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:05:07.866Z,1403730307.866 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:05:08.326Z,1403730308.326 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:05:08.326Z,1403730308.326 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:05:08.326Z,1403730308.326 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:05:08.795Z,1403730308.795 [DVL_micro](INFO): Initializing
2014-06-25T21:05:08.875Z,1403730308.875 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:05:08.875Z,1403730308.875 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:08:13.498Z,1403730493.498 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:08:13.498Z,1403730493.498 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:08:13.498Z,1403730493.498 [DVL_micro](ERROR): Data Fault
2014-06-25T21:08:13.562Z,1403730493.562 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:08:13.942Z,1403730493.942 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:08:14.375Z,1403730494.375 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:08:14.375Z,1403730494.375 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:08:14.375Z,1403730494.375 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:08:14.839Z,1403730494.839 [DVL_micro](INFO): Initializing
2014-06-25T21:08:14.899Z,1403730494.899 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:08:14.899Z,1403730494.899 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:10:26.228Z,1403730626.228 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T21:10:26.228Z,1403730626.228 [NAL9602] Data Fault, FailCount= 2
2014-06-25T21:10:26.228Z,1403730626.228 [NAL9602](ERROR): Data Fault
2014-06-25T21:10:26.303Z,1403730626.303 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T21:10:26.694Z,1403730626.694 [NAL9602](INFO): Powering down
2014-06-25T21:10:27.570Z,1403730627.570 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T21:10:27.570Z,1403730627.570 [NAL9602] No Fault, FailCount= 2
2014-06-25T21:10:31.773Z,1403730631.773 [NAL9602](INFO): Powering up NAL9602
2014-06-25T21:10:42.531Z,1403730642.531 [NAL9602](INFO): NAL9602 initialized
2014-06-25T21:11:18.961Z,1403730678.961 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:11:18.961Z,1403730678.961 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:11:18.961Z,1403730678.961 [DVL_micro](ERROR): Data Fault
2014-06-25T21:11:19.027Z,1403730679.027 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:11:19.370Z,1403730679.370 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:11:19.782Z,1403730679.782 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:11:19.782Z,1403730679.782 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:11:19.782Z,1403730679.782 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:11:20.225Z,1403730680.225 [DVL_micro](INFO): Initializing
2014-06-25T21:11:20.773Z,1403730680.773 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:11:20.773Z,1403730680.773 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:14:25.154Z,1403730865.154 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:14:25.154Z,1403730865.154 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:14:25.154Z,1403730865.154 [DVL_micro](ERROR): Data Fault
2014-06-25T21:14:25.231Z,1403730865.231 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:14:25.590Z,1403730865.590 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:14:26.019Z,1403730866.019 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:14:26.019Z,1403730866.019 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:14:26.019Z,1403730866.019 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:14:26.454Z,1403730866.454 [DVL_micro](INFO): Initializing
2014-06-25T21:14:26.488Z,1403730866.488 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:14:26.488Z,1403730866.488 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:16:48.308Z,1403731008.308 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-06-25T21:16:48.309Z,1403731008.309 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -67 71 -50 -42 3 3 3 3 -22.7 150.8 -23.8 2 83.4 -127.7 -23.8 2 -22 150 -23 2 83 -127 -23 2 -5.41 -0.25 204.6 -3..005 35.0 1489 104
2014-06-25T21:17:31.078Z,1403731051.078 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:17:31.078Z,1403731051.078 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:17:31.078Z,1403731051.078 [DVL_micro](ERROR): Data Fault
2014-06-25T21:17:31.169Z,1403731051.169 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:17:31.555Z,1403731051.555 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:17:31.991Z,1403731051.991 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:17:31.991Z,1403731051.991 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:17:31.991Z,1403731051.991 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:17:32.428Z,1403731052.428 [DVL_micro](INFO): Initializing
2014-06-25T21:17:32.461Z,1403731052.461 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:17:32.462Z,1403731052.462 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:20:37.114Z,1403731237.114 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:20:37.114Z,1403731237.114 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:20:37.114Z,1403731237.114 [DVL_micro](ERROR): Data Fault
2014-06-25T21:20:37.195Z,1403731237.195 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:20:37.551Z,1403731237.551 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:20:37.966Z,1403731237.966 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:20:37.966Z,1403731237.966 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:20:37.966Z,1403731237.966 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:20:38.432Z,1403731238.432 [DVL_micro](INFO): Initializing
2014-06-25T21:20:38.465Z,1403731238.465 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:20:38.465Z,1403731238.465 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:20:46.017Z,1403731246.017 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T21:20:46.017Z,1403731246.017 [NAL9602] Data Fault, FailCount= 3
2014-06-25T21:20:46.017Z,1403731246.017 [NAL9602](ERROR): Data Fault
2014-06-25T21:20:46.081Z,1403731246.081 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T21:20:46.082Z,1403731246.082 [CBIT](CRITICAL): Data Fault in component: NAL9602
2014-06-25T21:20:46.509Z,1403731246.509 [NAL9602](INFO): Powering down
2014-06-25T21:20:54.895Z,1403731254.895 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 29 of 46
2014-06-25T21:20:54.895Z,1403731254.895 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -96 33 83 -54 3 3 3 3 -238.9 116.1 -9.2 2 265.6 -4.7 -9.2 2 --9 2 265 -4 -9 2 -5.46 -0.34 204.9 -3.0 24.3 0.005 35.0 1489 73
2014-06-25T21:22:46.279Z,1403731366.279 [CBIT](INFO): Clearing failed count for component NAL9602
2014-06-25T21:22:46.279Z,1403731366.279 [NAL9602] No Fault, FailCount= 3
2014-06-25T21:22:46.660Z,1403731366.660 [NAL9602](INFO): Powering up NAL9602
2014-06-25T21:22:57.450Z,1403731377.450 [NAL9602](INFO): NAL9602 initialized
2014-06-25T21:23:42.814Z,1403731422.814 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:23:42.814Z,1403731422.814 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:23:42.814Z,1403731422.814 [DVL_micro](ERROR): Data Fault
2014-06-25T21:23:42.848Z,1403731422.848 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:23:43.231Z,1403731423.231 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:23:43.662Z,1403731423.662 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:23:43.662Z,1403731423.662 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:23:43.662Z,1403731423.662 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:23:44.096Z,1403731424.096 [DVL_micro](INFO): Initializing
2014-06-25T21:23:44.176Z,1403731424.176 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:23:44.176Z,1403731424.176 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:24:37.330Z,1403731477.330 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:24:39.217Z,1403731479.217 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:24:42.286Z,1403731482.286 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:24:44.101Z,1403731484.101 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:24:47.206Z,1403731487.206 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:24:49.092Z,1403731489.092 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:24:51.668Z,1403731491.668 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:24:53.970Z,1403731493.970 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:24:56.160Z,1403731496.160 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:24:58.929Z,1403731498.929 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:25:01.082Z,1403731501.082 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:25:03.502Z,1403731503.502 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:25:06.105Z,1403731506.105 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:25:08.351Z,1403731508.351 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:25:10.790Z,1403731510.790 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:25:13.364Z,1403731513.364 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:25:15.942Z,1403731515.942 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:25:18.157Z,1403731518.157 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:25:20.412Z,1403731520.412 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:25:23.082Z,1403731523.082 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-06-25T21:26:48.450Z,1403731608.450 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:26:48.450Z,1403731608.450 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:26:48.450Z,1403731608.450 [DVL_micro](ERROR): Data Fault
2014-06-25T21:26:48.539Z,1403731608.539 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:26:48.891Z,1403731608.891 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:26:49.307Z,1403731609.307 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:26:49.307Z,1403731609.307 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:26:49.307Z,1403731609.307 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:26:49.726Z,1403731609.726 [DVL_micro](INFO): Initializing
2014-06-25T21:26:49.787Z,1403731609.787 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:26:49.787Z,1403731609.787 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:27:02.646Z,1403731622.646 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:104 Calculated:117
2014-06-25T21:27:02.646Z,1403731622.646 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -41 -33 -18 88 3 3 3 3 -30.7 -161.5 -1.1 2 9.7 -1.1 2 -30 -161 -1 2 -39 159 -1 2 -5.43 -0.34 204.5 -3.0 24.3 0.005 35.0 1489 104
2014-06-25T21:28:26.567Z,1403731706.567 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-06-25T21:28:26.567Z,1403731706.567 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -45 -3 9 -34 3 3 3 3 -72.1 41.4 -19.7 2 82.8 -7.8 -19.7 2 -72 41 -19 2 82 -7 -19 2 -5.41 -0.31 20424.3 0.005 35.0 1489 111
2014-06-25T21:29:53.898Z,1403731793.898 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:29:53.898Z,1403731793.898 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:29:53.898Z,1403731793.898 [DVL_micro](ERROR): Data Fault
2014-06-25T21:29:53.977Z,1403731793.977 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:29:54.362Z,1403731794.362 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:29:54.791Z,1403731794.791 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:29:54.791Z,1403731794.791 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:29:54.791Z,1403731794.791 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:29:55.230Z,1403731795.230 [DVL_micro](INFO): Initializing
2014-06-25T21:29:55.285Z,1403731795.285 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:29:55.285Z,1403731795.285 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:33:00.082Z,1403731980.082 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:33:00.082Z,1403731980.082 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:33:00.082Z,1403731980.082 [DVL_micro](ERROR): Data Fault
2014-06-25T21:33:00.084Z,1403731980.084 [NAL9602](FAULT): GPS failed to acquire within timeout.
2014-06-25T21:33:00.084Z,1403731980.084 [NAL9602] Data Fault, FailCount= 1
2014-06-25T21:33:00.084Z,1403731980.084 [NAL9602](ERROR): Data Fault
2014-06-25T21:33:00.145Z,1403731980.145 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:33:00.145Z,1403731980.145 [CBIT](ERROR): Data Fault in component: NAL9602
2014-06-25T21:33:00.499Z,1403731980.499 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:33:00.500Z,1403731980.500 [NAL9602](INFO): Powering down
2014-06-25T21:33:00.911Z,1403731980.911 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:33:00.911Z,1403731980.911 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:33:00.911Z,1403731980.911 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:33:01.350Z,1403731981.350 [DVL_micro](INFO): Initializing
2014-06-25T21:33:01.405Z,1403731981.405 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:33:01.405Z,1403731981.405 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:33:01.406Z,1403731981.406 [CBIT](INFO): Clearing failed state for component NAL9602
2014-06-25T21:33:01.406Z,1403731981.406 [NAL9602] No Fault, FailCount= 1
2014-06-25T21:33:05.615Z,1403731985.615 [NAL9602](INFO): Powering up NAL9602
2014-06-25T21:33:16.302Z,1403731996.302 [NAL9602](INFO): NAL9602 initialized
2014-06-25T21:34:23.461Z,1403732063.461 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-06-25T21:34:23.461Z,1403732063.461 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -14 24 103 -46 3 3 4 3 -156.2 93.4 18.0 2 181.0 -18.6 18.0 2 -156 93 18 2 18 2 -5.48 -0.31 205.0 -3.0 24.3 0.005 35.0 1489 114
2014-06-25T21:36:05.269Z,1403732165.269 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:36:05.269Z,1403732165.269 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:36:05.269Z,1403732165.269 [DVL_micro](ERROR): Data Fault
2014-06-25T21:36:05.335Z,1403732165.335 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:36:05.728Z,1403732165.728 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:36:06.170Z,1403732166.170 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:36:06.171Z,1403732166.171 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:36:06.171Z,1403732166.171 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:36:07.104Z,1403732167.104 [DVL_micro](INFO): Initializing
2014-06-25T21:36:07.177Z,1403732167.177 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:36:07.177Z,1403732167.177 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:39:11.430Z,1403732351.430 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2014-06-25T21:39:11.430Z,1403732351.430 [DVL_micro] Data Fault, FailCount= 1
2014-06-25T21:39:11.430Z,1403732351.430 [DVL_micro](ERROR): Data Fault
2014-06-25T21:39:11.463Z,1403732351.463 [CBIT](ERROR): Data Fault in component: DVL_micro
2014-06-25T21:39:11.851Z,1403732351.851 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:39:12.288Z,1403732352.288 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-06-25T21:39:12.288Z,1403732352.288 [DVL_micro] Hardware Fault, FailCount= 1
2014-06-25T21:39:12.288Z,1403732352.288 [DVL_micro](ERROR): Hardware Fault
2014-06-25T21:39:12.714Z,1403732352.714 [DVL_micro](INFO): Initializing
2014-06-25T21:39:12.775Z,1403732352.775 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-06-25T21:39:12.775Z,1403732352.775 [DVL_micro] No Fault, FailCount= 1
2014-06-25T21:40:22.440Z,1403732422.440 [CommandLine](IMPORTANT): got command restart application
2014-06-25T21:40:23.455Z,1403732423.455 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-25T21:40:23.455Z,1403732423.455 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:23.640Z,1403732423.640 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-06-25T21:40:23.640Z,1403732423.640 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:23.642Z,1403732423.642 [CommandLine](INFO): Join timeout helper Thread ID is 1878
2014-06-25T21:40:23.643Z,1403732423.643 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-06-25T21:40:23.643Z,1403732423.643 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:23.644Z,1403732423.644 [NavChartDb](INFO): Join timeout helper Thread ID is 1879
2014-06-25T21:40:23.688Z,1403732423.688 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-25T21:40:23.689Z,1403732423.689 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:23.704Z,1403732423.704 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-06-25T21:40:23.704Z,1403732423.704 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:23.705Z,1403732423.705 [Radio_Freewave](INFO): Join timeout helper Thread ID is 1880
2014-06-25T21:40:23.781Z,1403732423.781 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-25T21:40:23.922Z,1403732423.922 [Radio_Freewave](INFO): Powering down
2014-06-25T21:40:24.325Z,1403732424.325 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.334Z,1403732424.334 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-06-25T21:40:24.334Z,1403732424.334 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.334Z,1403732424.334 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1882
2014-06-25T21:40:24.401Z,1403732424.401 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-25T21:40:24.402Z,1403732424.402 [WetLabsBB2FL](INFO): Powering down
2014-06-25T21:40:24.402Z,1403732424.402 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.405Z,1403732424.405 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-06-25T21:40:24.405Z,1403732424.405 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.406Z,1403732424.406 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1883
2014-06-25T21:40:24.434Z,1403732424.434 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-25T21:40:24.435Z,1403732424.435 [CTD_NeilBrown](INFO): Powering down
2014-06-25T21:40:24.436Z,1403732424.436 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.439Z,1403732424.439 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-06-25T21:40:24.439Z,1403732424.439 [logger ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.439Z,1403732424.439 [logger](INFO): Join timeout helper Thread ID is 1884
2014-06-25T21:40:24.481Z,1403732424.481 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-25T21:40:24.482Z,1403732424.482 [logger ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.498Z,1403732424.498 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-06-25T21:40:24.498Z,1403732424.498 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.498Z,1403732424.498 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-06-25T21:40:24.498Z,1403732424.498 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.499Z,1403732424.499 [controlThread](INFO): Join timeout helper Thread ID is 1885
2014-06-25T21:40:24.812Z,1403732424.812 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-25T21:40:24.812Z,1403732424.812 [controlThread](DEBUG): Uninitializing ControlThread
2014-06-25T21:40:24.813Z,1403732424.813 [AHRS_sp3003D](INFO): Powering down
2014-06-25T21:40:24.814Z,1403732424.814 [DVL_micro](INFO): uninitialize:Powering down
2014-06-25T21:40:24.815Z,1403732424.815 [NAL9602](INFO): Powering down
2014-06-25T21:40:24.817Z,1403732424.817 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-06-25T21:40:24.820Z,1403732424.820 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-06-25T21:40:24.820Z,1403732424.820 [Default] Stopped
2014-06-25T21:40:24.820Z,1403732424.820 [Default](INFO): Aggregate::uninitialize Default
2014-06-25T21:40:24.820Z,1403732424.820 [Default:GPS] Stopped
2014-06-25T21:40:24.821Z,1403732424.821 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2014-06-25T21:40:24.821Z,1403732424.821 [Default:GPS:A.SetSpeed] Stopped
2014-06-25T21:40:24.821Z,1403732424.821 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2014-06-25T21:40:24.821Z,1403732424.821 [Default:GPS:B.GoToSurface] Stopped
2014-06-25T21:40:24.821Z,1403732424.821 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-06-25T21:40:24.821Z,1403732424.821 [Default:GPS:Read_GPS] Stopped
2014-06-25T21:40:24.821Z,1403732424.821 [Default:Iridium] Stopped
2014-06-25T21:40:24.821Z,1403732424.821 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2014-06-25T21:40:24.821Z,1403732424.821 [Default:Iridium:A.SetSpeed] Stopped
2014-06-25T21:40:24.821Z,1403732424.821 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2014-06-25T21:40:24.821Z,1403732424.821 [Default:Iridium:B.GoToSurface] Stopped
2014-06-25T21:40:24.821Z,1403732424.821 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-06-25T21:40:24.822Z,1403732424.822 [Default:Iridium:Read_Iridium] Stopped
2014-06-25T21:40:24.822Z,1403732424.822 [Default:CallIridium] Stopped
2014-06-25T21:40:24.822Z,1403732424.822 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2014-06-25T21:40:24.822Z,1403732424.822 [Default:CallIridium:B] Stopped
2014-06-25T21:40:24.822Z,1403732424.822 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2014-06-25T21:40:24.822Z,1403732424.822 [Default:WaitAtTheSurface] Stopped
2014-06-25T21:40:24.822Z,1403732424.822 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface
2014-06-25T21:40:24.822Z,1403732424.822 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Stopped
2014-06-25T21:40:24.823Z,1403732424.823 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Uninitialize.
2014-06-25T21:40:24.827Z,1403732424.827 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-06-25T21:40:24.828Z,1403732424.828 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-06-25T21:40:24.828Z,1403732424.828 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-06-25T21:40:24.828Z,1403732424.828 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-06-25T21:40:24.828Z,1403732424.828 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-06-25T21:40:24.829Z,1403732424.829 [BuoyancyServo](INFO): Powering down
2014-06-25T21:40:24.842Z,1403732424.842 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-06-25T21:40:24.842Z,1403732424.842 [ElevatorServo](INFO): Powering down
2014-06-25T21:40:24.843Z,1403732424.843 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-06-25T21:40:24.843Z,1403732424.843 [MassServo](INFO): Powering down
2014-06-25T21:40:24.844Z,1403732424.844 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-06-25T21:40:24.844Z,1403732424.844 [RudderServo](INFO): Powering down
2014-06-25T21:40:24.845Z,1403732424.845 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-06-25T21:40:24.845Z,1403732424.845 [ThrusterServo](INFO): Powering down
2014-06-25T21:40:24.846Z,1403732424.846 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-06-25T21:40:24.847Z,1403732424.847 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-06-25T21:40:24.847Z,1403732424.847 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-06-25T21:40:24.891Z,1403732424.891 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.977Z,1403732424.977 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.981Z,1403732424.981 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:24.987Z,1403732424.987 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:25.034Z,1403732425.034 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-06-25T21:40:25.075Z,1403732425.075 [logger ThreadHandler](INFO): Thread cancelled.