2014-11-13T22:52:26.586Z,1415919146.586 [Supervisor](DEBUG): Initializing supervisor.
2014-11-13T22:52:26.589Z,1415919146.589 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-11-13T22:52:26.589Z,1415919146.589 [SyncHandler](INFO): Protected caller Thread ID is 5010
2014-11-13T22:52:26.590Z,1415919146.590 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-11-13T22:52:26.591Z,1415919146.591 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-11-13T22:52:26.591Z,1415919146.591 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5011
2014-11-13T22:52:26.594Z,1415919146.594 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-11-13T22:52:26.606Z,1415919146.606 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-11-13T22:52:26.607Z,1415919146.607 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-11-13T22:52:26.607Z,1415919146.607 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5012
2014-11-13T22:52:26.608Z,1415919146.608 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-11-13T22:52:26.609Z,1415919146.609 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-11-13T22:52:26.610Z,1415919146.610 [logger ThreadHandler](INFO): Protected caller Thread ID is 5013
2014-11-13T22:52:26.612Z,1415919146.612 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-11-13T22:52:26.612Z,1415919146.612 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-11-13T22:52:26.614Z,1415919146.614 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-11-13T22:52:26.943Z,1415919146.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-11-13T22:52:26.943Z,1415919146.943 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-11-13T22:52:27.103Z,1415919147.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-11-13T22:52:27.103Z,1415919147.103 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-11-13T22:52:27.213Z,1415919147.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-11-13T22:52:27.213Z,1415919147.213 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-11-13T22:52:27.337Z,1415919147.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-11-13T22:52:27.338Z,1415919147.338 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-11-13T22:52:27.424Z,1415919147.424 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-11-13T22:52:27.583Z,1415919147.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-11-13T22:52:27.584Z,1415919147.584 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-11-13T22:52:27.671Z,1415919147.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-11-13T22:52:27.671Z,1415919147.671 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-11-13T22:52:27.880Z,1415919147.880 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-11-13T22:52:27.881Z,1415919147.881 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-11-13T22:52:28.288Z,1415919148.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-11-13T22:52:28.289Z,1415919148.289 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-11-13T22:52:28.605Z,1415919148.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-11-13T22:52:28.606Z,1415919148.606 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-11-13T22:52:29.106Z,1415919149.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-11-13T22:52:29.107Z,1415919149.107 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-11-13T22:52:29.294Z,1415919149.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-11-13T22:52:29.294Z,1415919149.294 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-11-13T22:52:29.401Z,1415919149.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-11-13T22:52:29.401Z,1415919149.401 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-11-13T22:52:29.827Z,1415919149.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-11-13T22:52:29.828Z,1415919149.828 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-11-13T22:52:29.938Z,1415919149.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-11-13T22:52:29.940Z,1415919149.940 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-11-13T22:52:29.941Z,1415919149.941 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-11-13T22:52:30.047Z,1415919150.047 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-11-13T22:52:30.167Z,1415919150.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-11-13T22:52:30.267Z,1415919150.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-11-13T22:52:30.378Z,1415919150.378 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-11-13T22:52:30.476Z,1415919150.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-11-13T22:52:30.607Z,1415919150.607 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-11-13T22:52:30.764Z,1415919150.764 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-11-13T22:52:30.865Z,1415919150.865 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-11-13T22:52:30.957Z,1415919150.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-11-13T22:52:31.039Z,1415919151.039 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-11-13T22:52:31.136Z,1415919151.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-11-13T22:52:31.250Z,1415919151.250 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2014-11-13T22:52:31.250Z,1415919151.250 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2014-11-13T22:52:31.251Z,1415919151.251 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-11-13T22:52:31.301Z,1415919151.301 [VerticalControl](DEBUG): Construct VerticalControl.
2014-11-13T22:52:31.420Z,1415919151.420 [VerticalControl] Loaded
2014-11-13T22:52:31.420Z,1415919151.420 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-11-13T22:52:31.421Z,1415919151.421 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-11-13T22:52:31.495Z,1415919151.495 [HorizontalControl] Loaded
2014-11-13T22:52:31.495Z,1415919151.495 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-11-13T22:52:31.496Z,1415919151.496 [SpeedControl](DEBUG): Construct SpeedControl.
2014-11-13T22:52:31.501Z,1415919151.501 [SpeedControl] Loaded
2014-11-13T22:52:31.502Z,1415919151.502 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-11-13T22:52:31.502Z,1415919151.502 [LoopControl](DEBUG): Construct LoopControl.
2014-11-13T22:52:31.503Z,1415919151.503 [LoopControl] Loaded
2014-11-13T22:52:31.503Z,1415919151.503 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-11-13T22:52:31.504Z,1415919151.504 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-11-13T22:52:31.504Z,1415919151.504 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-11-13T22:52:31.601Z,1415919151.601 [SBIT](DEBUG): Construct Startup Built In Test.
2014-11-13T22:52:31.613Z,1415919151.613 [SBIT] Loaded
2014-11-13T22:52:31.613Z,1415919151.613 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-11-13T22:52:31.614Z,1415919151.614 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-11-13T22:52:31.644Z,1415919151.644 [IBIT] Loaded
2014-11-13T22:52:31.644Z,1415919151.644 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-11-13T22:52:31.646Z,1415919151.646 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-11-13T22:52:31.768Z,1415919151.768 [CBIT] Loaded
2014-11-13T22:52:31.768Z,1415919151.768 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-11-13T22:52:31.769Z,1415919151.769 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-11-13T22:52:31.769Z,1415919151.769 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-11-13T22:52:31.788Z,1415919151.788 [DepthRateCalculator] Loaded
2014-11-13T22:52:31.788Z,1415919151.788 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-11-13T22:52:31.794Z,1415919151.794 [PitchRateCalculator] Loaded
2014-11-13T22:52:31.795Z,1415919151.795 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-11-13T22:52:31.806Z,1415919151.806 [SpeedCalculator] Loaded
2014-11-13T22:52:31.806Z,1415919151.806 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-11-13T22:52:31.823Z,1415919151.823 [TempGradientCalculator] Loaded
2014-11-13T22:52:31.823Z,1415919151.823 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-11-13T22:52:31.829Z,1415919151.829 [YawRateCalculator] Loaded
2014-11-13T22:52:31.829Z,1415919151.829 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-11-13T22:52:31.829Z,1415919151.829 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-11-13T22:52:31.830Z,1415919151.830 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-11-13T22:52:31.880Z,1415919151.880 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-11-13T22:52:31.881Z,1415919151.881 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-11-13T22:52:32.119Z,1415919152.119 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-11-13T22:52:32.120Z,1415919152.120 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-11-13T22:52:32.202Z,1415919152.202 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-11-13T22:52:32.202Z,1415919152.202 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-11-13T22:52:32.249Z,1415919152.249 [DeadReckonUsingSpeedCalculator] Loaded
2014-11-13T22:52:32.249Z,1415919152.249 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2014-11-13T22:52:32.295Z,1415919152.295 [DeadReckonWithRespectToWater] Loaded
2014-11-13T22:52:32.296Z,1415919152.296 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-11-13T22:52:32.343Z,1415919152.343 [DeadReckonWithRespectToSeafloor] Loaded
2014-11-13T22:52:32.343Z,1415919152.343 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-11-13T22:52:32.395Z,1415919152.395 [DeadReckonUsingDVLWaterTrack] Loaded
2014-11-13T22:52:32.396Z,1415919152.396 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-11-13T22:52:32.445Z,1415919152.445 [DeadReckonUsingCompactModelForecast] Loaded
2014-11-13T22:52:32.445Z,1415919152.445 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-11-13T22:52:32.462Z,1415919152.462 [NavChart] Loaded
2014-11-13T22:52:32.462Z,1415919152.462 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-11-13T22:52:32.467Z,1415919152.467 [UniversalFixResidualReporter] Loaded
2014-11-13T22:52:32.467Z,1415919152.467 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-11-13T22:52:32.468Z,1415919152.468 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-11-13T22:52:32.468Z,1415919152.468 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-11-13T22:52:32.474Z,1415919152.474 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-11-13T22:52:32.475Z,1415919152.475 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-11-13T22:52:32.662Z,1415919152.662 [Aanderaa_O2] Loaded
2014-11-13T22:52:32.662Z,1415919152.662 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-11-13T22:52:32.699Z,1415919152.699 [CTD_NeilBrown] Loaded
2014-11-13T22:52:32.700Z,1415919152.700 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-11-13T22:52:32.701Z,1415919152.701 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4056B4E0
2014-11-13T22:52:32.701Z,1415919152.701 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5096
2014-11-13T22:52:32.717Z,1415919152.717 [PAR_Licor] Loaded
2014-11-13T22:52:32.717Z,1415919152.717 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-11-13T22:52:32.753Z,1415919152.753 [WetLabsBB2FL] Loaded
2014-11-13T22:52:32.753Z,1415919152.753 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-11-13T22:52:32.754Z,1415919152.754 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4059B4E0
2014-11-13T22:52:32.755Z,1415919152.755 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5097
2014-11-13T22:52:32.755Z,1415919152.755 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-11-13T22:52:32.756Z,1415919152.756 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-11-13T22:52:33.070Z,1415919153.070 [AHRS_sp3003D] Loaded
2014-11-13T22:52:33.070Z,1415919153.070 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-11-13T22:52:33.337Z,1415919153.337 [Batt_Ocean_Server] Loaded
2014-11-13T22:52:33.337Z,1415919153.337 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-11-13T22:52:33.352Z,1415919153.352 [Depth_Keller] Loaded
2014-11-13T22:52:33.352Z,1415919153.352 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-11-13T22:52:33.358Z,1415919153.358 [DropWeight] Loaded
2014-11-13T22:52:33.358Z,1415919153.358 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-11-13T22:52:33.502Z,1415919153.502 [DVL_micro] Loaded
2014-11-13T22:52:33.503Z,1415919153.503 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-11-13T22:52:33.601Z,1415919153.601 [NAL9602] Loaded
2014-11-13T22:52:33.601Z,1415919153.601 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-11-13T22:52:33.666Z,1415919153.666 [Onboard] Loaded
2014-11-13T22:52:33.666Z,1415919153.666 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-11-13T22:52:33.674Z,1415919153.674 [Radio_Freewave] Loaded
2014-11-13T22:52:33.674Z,1415919153.674 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-11-13T22:52:33.675Z,1415919153.675 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 407EA4E0
2014-11-13T22:52:33.676Z,1415919153.676 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 5099
2014-11-13T22:52:33.682Z,1415919153.682 [Radio_Surface] Loaded
2014-11-13T22:52:33.683Z,1415919153.683 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2014-11-13T22:52:33.684Z,1415919153.684 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4081A4E0
2014-11-13T22:52:33.684Z,1415919153.684 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5100
2014-11-13T22:52:33.813Z,1415919153.813 [DAT] Loaded
2014-11-13T22:52:33.814Z,1415919153.814 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2014-11-13T22:52:33.814Z,1415919153.814 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-11-13T22:52:33.815Z,1415919153.815 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-11-13T22:52:33.918Z,1415919153.918 [BuoyancyServo] Loaded
2014-11-13T22:52:33.918Z,1415919153.918 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-11-13T22:52:33.930Z,1415919153.930 [ElevatorServo] Loaded
2014-11-13T22:52:33.931Z,1415919153.931 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-11-13T22:52:33.942Z,1415919153.942 [MassServo] Loaded
2014-11-13T22:52:33.943Z,1415919153.943 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-11-13T22:52:33.955Z,1415919153.955 [RudderServo] Loaded
2014-11-13T22:52:33.955Z,1415919153.955 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-11-13T22:52:33.967Z,1415919153.967 [ThrusterServo] Loaded
2014-11-13T22:52:33.967Z,1415919153.967 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-11-13T22:52:33.967Z,1415919153.967 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-11-13T22:52:33.968Z,1415919153.968 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-11-13T22:52:34.206Z,1415919154.206 [ExternalSim] Loaded
2014-11-13T22:52:34.206Z,1415919154.206 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2014-11-13T22:52:34.207Z,1415919154.207 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-11-13T22:52:34.208Z,1415919154.208 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-11-13T22:52:34.232Z,1415919154.232 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-11-13T22:52:34.234Z,1415919154.234 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-11-13T22:52:34.235Z,1415919154.235 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-11-13T22:52:34.242Z,1415919154.242 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-11-13T22:52:34.243Z,1415919154.243 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408CB4E0
2014-11-13T22:52:34.244Z,1415919154.244 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5101
2014-11-13T22:52:34.249Z,1415919154.249 [Supervisor](INFO): Main Thread ID is 5009
2014-11-13T22:52:34.249Z,1415919154.249 [Supervisor](DEBUG): Running supervisor.
2014-11-13T22:52:34.250Z,1415919154.250 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5102
2014-11-13T22:52:34.253Z,1415919154.253 [controlThread ThreadHandler](INFO): Handler Thread ID is 5103
2014-11-13T22:52:34.254Z,1415919154.254 [controlThread](DEBUG): Initializing ControlThread
2014-11-13T22:52:34.255Z,1415919154.255 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-11-13T22:52:34.257Z,1415919154.257 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-11-13T22:52:34.257Z,1415919154.257 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-11-13T22:52:34.258Z,1415919154.258 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-11-13T22:52:34.258Z,1415919154.258 [SBIT](INFO): Initialize SBIT Component.
2014-11-13T22:52:34.259Z,1415919154.259 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11881
2014-11-13T22:52:34.259Z,1415919154.259 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-11-13T22:52:34.259Z,1415919154.259 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-11-13T22:52:34.260Z,1415919154.260 [IBIT](INFO): Initialize IBIT Component.
2014-11-13T22:52:34.261Z,1415919154.261 [CBIT](DEBUG): Initialize CBIT Component.
2014-11-13T22:52:34.261Z,1415919154.261 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2014-11-13T22:52:34.261Z,1415919154.261 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-11-13T22:52:34.262Z,1415919154.262 [logger ThreadHandler](INFO): Handler Thread ID is 5104
2014-11-13T22:52:34.280Z,1415919154.280 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5105
2014-11-13T22:52:34.281Z,1415919154.281 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-11-13T22:52:34.284Z,1415919154.284 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-11-13T22:52:34.284Z,1415919154.284 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-11-13T22:52:34.285Z,1415919154.285 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-11-13T22:52:34.285Z,1415919154.285 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-11-13T22:52:34.285Z,1415919154.285 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-11-13T22:52:34.286Z,1415919154.286 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-11-13T22:52:34.286Z,1415919154.286 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2014-11-13T22:52:34.287Z,1415919154.287 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-11-13T22:52:34.287Z,1415919154.287 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-11-13T22:52:34.287Z,1415919154.287 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-11-13T22:52:34.288Z,1415919154.288 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-11-13T22:52:34.288Z,1415919154.288 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-11-13T22:52:34.289Z,1415919154.289 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2014-11-13T22:52:34.294Z,1415919154.294 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-11-13T22:52:34.303Z,1415919154.303 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5107
2014-11-13T22:52:34.309Z,1415919154.309 [WetLabsBB2FL](INFO): Powering down
2014-11-13T22:52:34.332Z,1415919154.332 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 5108
2014-11-13T22:52:34.372Z,1415919154.372 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5110
2014-11-13T22:52:34.381Z,1415919154.381 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5111
2014-11-13T22:52:34.383Z,1415919154.383 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-11-13T22:52:34.385Z,1415919154.385 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-11-13T22:52:34.385Z,1415919154.385 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-11-13T22:52:34.385Z,1415919154.385 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-11-13T22:52:34.385Z,1415919154.385 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-11-13T22:52:34.386Z,1415919154.386 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-11-13T22:52:34.386Z,1415919154.386 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-11-13T22:52:34.386Z,1415919154.386 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-11-13T22:52:34.386Z,1415919154.386 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-11-13T22:52:34.605Z,1415919154.605 [Radio_Freewave](INFO): Powering up
2014-11-13T22:52:35.411Z,1415919155.411 [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-11-13T22:52:36.245Z,1415919156.245 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-11-13T22:52:36.279Z,1415919156.279 [ExternalSim](INFO): ExternalSim initializing...
2014-11-13T22:52:36.349Z,1415919156.349 [ExternalSim](ERROR): Error making socket connection to simulation server at tethyssim.shore.mbari.org: Could not connect due to error: No route to host
2014-11-13T22:52:36.439Z,1415919156.439 [ExternalSim](DEBUG): beaconLat = 36.80340
2014-11-13T22:52:36.440Z,1415919156.440 [ExternalSim](DEBUG): beaconLon = -121.82230
2014-11-13T22:52:36.440Z,1415919156.440 [ExternalSim](DEBUG): beaconDepth = 25.00
2014-11-13T22:52:36.463Z,1415919156.463 [ExternalSim](DEBUG): Simulator initialized
2014-11-13T22:52:36.467Z,1415919156.467 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-11-13T22:52:36.490Z,1415919156.490 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-11-13T22:52:36.523Z,1415919156.523 [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-11-13T22:52:36.540Z,1415919156.540 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-11-13T22:52:36.630Z,1415919156.630 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-11-13T22:52:36.634Z,1415919156.634 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-11-13T22:52:36.654Z,1415919156.654 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-11-13T22:52:36.667Z,1415919156.667 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-11-13T22:52:36.689Z,1415919156.689 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-11-13T22:52:36.753Z,1415919156.753 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-11-13T22:52:36.783Z,1415919156.783 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="TimeMissionWasStarted"><Units:hour/><Value>0</Value></DefineArg>

    <DefineArg Name="ElapsedSinceMissionWasStarted"><Units:hour/><Value>0</Value></DefineArg>

    <Aggregate Id="StartClock">

        <Sequence/>

        <Assign><Arg Name="TimeMissionWasStarted"/><Universal:time/></Assign>

    </Aggregate>

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

    <Aggregate Id="CheckIn">

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

<!-- TODO: Do we really want this When operating on platform_communications? or on something else like time_fix? -->

<!-- Run application at high speed -->

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

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
<!-- NOTE: Original Default.xml didn't have a timeout on the GPS read, so theoretically could have hung indefinitely there... -->
            <Universal:latitude_fix/>
        </ReadDatum>

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="UpdateAndReportMinutesSinceMissionStarted">

        <When>
            <Elapsed><Arg Name="ElapsedSinceMissionWasStarted"/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
        </When>

        <Assign><Arg Name="ElapsedSinceMissionWasStarted"/><Universal:time/><Sub><Arg Name="TimeMissionWasStarted"/></Sub></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceMissionWasStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2014-11-13T22:52:36.799Z,1415919156.799 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,Aanderaa_O2,PAR_Licor,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-11-13T22:52:36.976Z,1415919156.976 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-11-13T22:52:37.029Z,1415919157.029 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2014-11-13T22:52:37.073Z,1415919157.073 [DVL_micro](INFO): Initializing
2014-11-13T22:52:37.228Z,1415919157.228 [DAT](INFO): Powering up
2014-11-13T22:52:37.229Z,1415919157.229 [DAT](DEBUG): Initializing DAT.
2014-11-13T22:52:37.258Z,1415919157.258 [DepthRateCalculator](ERROR): Depth measurement is not active
2014-11-13T22:52:37.334Z,1415919157.334 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:52:37.345Z,1415919157.345 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:37.561Z,1415919157.561 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:52:37.569Z,1415919157.569 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-11-13T22:52:37.592Z,1415919157.592 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:52:37.602Z,1415919157.602 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-11-13T22:52:37.626Z,1415919157.626 [MassServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:52:37.633Z,1415919157.633 [MassServo](DEBUG): Initializing MassServo.
2014-11-13T22:52:37.640Z,1415919157.640 [RudderServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:52:37.645Z,1415919157.645 [RudderServo](DEBUG): Initializing RudderServo.
2014-11-13T22:52:37.663Z,1415919157.663 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:52:37.669Z,1415919157.669 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-11-13T22:52:38.051Z,1415919158.051 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:52:38.052Z,1415919158.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:38.403Z,1415919158.403 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2014-11-13T22:52:38.403Z,1415919158.403 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2014-11-13T22:52:38.403Z,1415919158.403 [BuoyancyServo] Communications Fault, FailCount= 1
2014-11-13T22:52:38.403Z,1415919158.403 [BuoyancyServo](ERROR): Communications Fault
2014-11-13T22:52:38.625Z,1415919158.625 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2014-11-13T22:52:39.110Z,1415919159.110 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:52:39.112Z,1415919159.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:39.152Z,1415919159.152 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-11-13T22:52:39.153Z,1415919159.153 [BuoyancyServo](INFO): Powering down
2014-11-13T22:52:39.720Z,1415919159.720 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:52:39.721Z,1415919159.721 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:40.272Z,1415919160.272 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:52:40.274Z,1415919160.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:40.547Z,1415919160.547 [Aanderaa_O2](INFO): Powering down
2014-11-13T22:52:41.087Z,1415919161.087 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:52:41.088Z,1415919161.088 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:41.650Z,1415919161.650 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:52:41.659Z,1415919161.659 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:41.814Z,1415919161.814 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2014-11-13T22:52:41.814Z,1415919161.814 [BuoyancyServo] No Fault, FailCount= 1
2014-11-13T22:52:42.181Z,1415919162.181 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:42.209Z,1415919162.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:52:42.210Z,1415919162.210 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:42.245Z,1415919162.245 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:52:42.366Z,1415919162.366 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-11-13T22:52:42.951Z,1415919162.951 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:42.995Z,1415919162.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:43.586Z,1415919163.586 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:43.880Z,1415919163.880 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:44.285Z,1415919164.285 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:44.563Z,1415919164.563 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:45.019Z,1415919165.019 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:45.286Z,1415919165.286 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:45.776Z,1415919165.776 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:45.813Z,1415919165.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:46.228Z,1415919166.228 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:46.256Z,1415919166.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:46.861Z,1415919166.861 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:46.960Z,1415919166.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:47.486Z,1415919167.486 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:47.525Z,1415919167.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:47.955Z,1415919167.955 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:48.081Z,1415919168.081 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:48.492Z,1415919168.492 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:48.542Z,1415919168.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:48.941Z,1415919168.941 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:49.154Z,1415919169.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:49.550Z,1415919169.550 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:49.588Z,1415919169.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:50.102Z,1415919170.102 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:50.130Z,1415919170.130 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:50.171Z,1415919170.171 [SBIT](IMPORTANT): Beginning Startup BIT
2014-11-13T22:52:50.182Z,1415919170.182 [CBIT](IMPORTANT): Beginning GF scan
2014-11-13T22:52:50.571Z,1415919170.571 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:50.630Z,1415919170.630 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:51.072Z,1415919171.072 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:51.171Z,1415919171.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:51.717Z,1415919171.717 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:51.748Z,1415919171.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:52.193Z,1415919172.193 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:52.249Z,1415919172.249 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:53.087Z,1415919173.087 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:53.126Z,1415919173.126 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:53.609Z,1415919173.609 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:53.635Z,1415919173.635 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:54.145Z,1415919174.145 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:54.183Z,1415919174.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:54.738Z,1415919174.738 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:54.824Z,1415919174.824 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:55.251Z,1415919175.251 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:55.286Z,1415919175.286 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:55.759Z,1415919175.759 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:55.792Z,1415919175.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:56.273Z,1415919176.273 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:56.302Z,1415919176.302 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:56.953Z,1415919176.953 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:56.990Z,1415919176.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:57.402Z,1415919177.402 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:57.432Z,1415919177.432 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2014-11-13T22:52:57.432Z,1415919177.432 [DAT] Communications Fault, FailCount= 1
2014-11-13T22:52:57.433Z,1415919177.433 [DAT](ERROR): Communications Fault
2014-11-13T22:52:57.456Z,1415919177.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:57.556Z,1415919177.556 [CBIT](ERROR): Communications Fault in component: DAT
2014-11-13T22:52:57.899Z,1415919177.899 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:57.947Z,1415919177.947 [DAT](INFO): Powering down
2014-11-13T22:52:58.153Z,1415919178.153 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:58.559Z,1415919178.559 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:58.602Z,1415919178.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:59.008Z,1415919179.008 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:59.041Z,1415919179.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:59.110Z,1415919179.110 [CBIT](INFO): Clearing failed state for component DAT
2014-11-13T22:52:59.110Z,1415919179.110 [DAT] No Fault, FailCount= 1
2014-11-13T22:52:59.457Z,1415919179.457 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:59.495Z,1415919179.495 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:52:59.898Z,1415919179.898 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:52:59.917Z,1415919179.917 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:00.434Z,1415919180.434 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:00.459Z,1415919180.459 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:00.883Z,1415919180.883 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:00.910Z,1415919180.910 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:01.308Z,1415919181.308 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:01.364Z,1415919181.364 [DAT](INFO): Powering up
2014-11-13T22:53:01.365Z,1415919181.365 [DAT](DEBUG): Initializing DAT.
2014-11-13T22:53:01.431Z,1415919181.431 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:01.953Z,1415919181.953 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:02.004Z,1415919182.004 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:02.409Z,1415919182.409 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:02.449Z,1415919182.449 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:02.862Z,1415919182.862 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:02.901Z,1415919182.901 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:03.392Z,1415919183.392 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:03.443Z,1415919183.443 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:03.824Z,1415919183.824 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:03.855Z,1415919183.855 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:04.248Z,1415919184.248 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:04.295Z,1415919184.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:04.681Z,1415919184.681 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:04.792Z,1415919184.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:05.270Z,1415919185.270 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:05.316Z,1415919185.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:06.083Z,1415919186.083 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:06.186Z,1415919186.186 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:06.828Z,1415919186.828 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:06.869Z,1415919186.869 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:07.325Z,1415919187.325 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:07.368Z,1415919187.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:07.826Z,1415919187.826 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:07.932Z,1415919187.932 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:08.396Z,1415919188.396 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:08.450Z,1415919188.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:08.908Z,1415919188.908 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:08.942Z,1415919188.942 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:09.408Z,1415919189.408 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:09.451Z,1415919189.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:09.922Z,1415919189.922 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:09.968Z,1415919189.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:10.478Z,1415919190.478 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:10.502Z,1415919190.502 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:10.966Z,1415919190.966 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:11.048Z,1415919191.048 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:11.513Z,1415919191.513 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:11.548Z,1415919191.548 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:12.177Z,1415919192.177 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:12.244Z,1415919192.244 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:12.886Z,1415919192.886 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:12.928Z,1415919192.928 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:13.334Z,1415919193.334 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:13.373Z,1415919193.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:13.771Z,1415919193.771 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:13.797Z,1415919193.797 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:14.224Z,1415919194.224 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:14.296Z,1415919194.296 [DAT](DEBUG): 
2014-11-13T22:53:14.297Z,1415919194.297 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2014-11-13T22:53:14.298Z,1415919194.298 [DAT](DEBUG): MF Frequency Band
2014-11-13T22:53:14.299Z,1415919194.299 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2014-11-13T22:53:14.315Z,1415919194.315 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:14.728Z,1415919194.728 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:14.738Z,1415919194.738 [DAT](DEBUG): Nov 13 2014 23:52:48 
2014-11-13T22:53:14.752Z,1415919194.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:15.209Z,1415919195.209 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:15.246Z,1415919195.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:15.654Z,1415919195.654 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:15.682Z,1415919195.682 [DAT](INFO): entering command mode
2014-11-13T22:53:15.733Z,1415919195.733 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:16.131Z,1415919196.131 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:16.143Z,1415919196.143 [DAT](DEBUG): checking for command mode acknowledgment
2014-11-13T22:53:16.144Z,1415919196.144 [DAT](DEBUG): read user prompt 1: user:1>
2014-11-13T22:53:16.144Z,1415919196.144 [DAT](INFO): command mode acknowledged
2014-11-13T22:53:16.159Z,1415919196.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:16.603Z,1415919196.603 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:16.630Z,1415919196.630 [DAT](INFO): setting local address to 2
2014-11-13T22:53:16.651Z,1415919196.651 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:16.746Z,1415919196.746 [CBIT](IMPORTANT): No ground fault detected
2014-11-13T22:53:17.196Z,1415919197.196 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:17.241Z,1415919197.241 [DAT](DEBUG): checking for local address setting acknowledgment
2014-11-13T22:53:17.242Z,1415919197.242 [DAT](INFO): set local address to 2
2014-11-13T22:53:17.259Z,1415919197.259 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:17.783Z,1415919197.783 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:17.857Z,1415919197.857 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:18.246Z,1415919198.246 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:18.301Z,1415919198.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:18.671Z,1415919198.671 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:18.702Z,1415919198.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:19.107Z,1415919199.107 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:19.142Z,1415919199.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:19.529Z,1415919199.529 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:19.554Z,1415919199.554 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:19.949Z,1415919199.949 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:19.987Z,1415919199.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:20.401Z,1415919200.401 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:20.422Z,1415919200.422 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:20.826Z,1415919200.826 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:20.940Z,1415919200.940 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:21.339Z,1415919201.339 [Batt_Ocean_Server](ERROR): Battery controller B communication timeout. Re-initializing
2014-11-13T22:53:21.343Z,1415919201.343 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:21.401Z,1415919201.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:21.759Z,1415919201.759 [Batt_Ocean_Server] Communications Fault, FailCount= 1
2014-11-13T22:53:21.759Z,1415919201.759 [Batt_Ocean_Server](ERROR): Communications Fault
2014-11-13T22:53:22.852Z,1415919202.852 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout
2014-11-13T22:53:22.852Z,1415919202.852 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing
2014-11-13T22:53:22.856Z,1415919202.856 [CommandLine](IMPORTANT): got command failComponent
2014-11-13T22:53:22.856Z,1415919202.856 [CommandLine](IMPORTANT): Failed components:
2014-11-13T22:53:22.856Z,1415919202.856 [CommandLine](IMPORTANT): Batt_Ocean_Server: Communications Fault
2014-11-13T22:53:22.861Z,1415919202.861 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:22.936Z,1415919202.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:22.984Z,1415919202.984 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server
2014-11-13T22:53:23.322Z,1415919203.322 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:23.373Z,1415919203.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:23.413Z,1415919203.413 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server
2014-11-13T22:53:23.413Z,1415919203.413 [Batt_Ocean_Server] No Fault, FailCount= 1
2014-11-13T22:53:25.745Z,1415919205.745 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-11-13T22:53:25.774Z,1415919205.774 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:25.852Z,1415919205.852 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:26.234Z,1415919206.234 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:26.275Z,1415919206.275 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:26.671Z,1415919206.671 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:26.714Z,1415919206.714 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:27.088Z,1415919207.088 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:27.137Z,1415919207.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:27.544Z,1415919207.544 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:27.571Z,1415919207.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:27.977Z,1415919207.977 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:28.039Z,1415919208.039 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:28.434Z,1415919208.434 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:28.473Z,1415919208.473 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:28.752Z,1415919208.752 [CommandLine](IMPORTANT): got command quit
2014-11-13T22:53:28.858Z,1415919208.858 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:28.920Z,1415919208.920 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:29.310Z,1415919209.310 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:29.324Z,1415919209.324 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:29.720Z,1415919209.720 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:29.732Z,1415919209.732 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:29.761Z,1415919209.761 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:53:29.761Z,1415919209.761 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:29.946Z,1415919209.946 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-11-13T22:53:29.946Z,1415919209.946 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:29.954Z,1415919209.954 [CommandLine](INFO): Join timeout helper Thread ID is 5138
2014-11-13T22:53:29.955Z,1415919209.955 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-11-13T22:53:29.955Z,1415919209.955 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:29.955Z,1415919209.955 [NavChartDb](INFO): Join timeout helper Thread ID is 5139
2014-11-13T22:53:30.051Z,1415919210.051 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:53:30.052Z,1415919210.052 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:30.054Z,1415919210.054 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2014-11-13T22:53:30.054Z,1415919210.054 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:30.055Z,1415919210.055 [Radio_Surface](INFO): Join timeout helper Thread ID is 5140
2014-11-13T22:53:30.135Z,1415919210.135 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:30.148Z,1415919210.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:30.439Z,1415919210.439 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:53:30.439Z,1415919210.439 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:30.459Z,1415919210.459 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-11-13T22:53:30.459Z,1415919210.459 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:30.459Z,1415919210.459 [Radio_Freewave](INFO): Join timeout helper Thread ID is 5141
2014-11-13T22:53:30.586Z,1415919210.586 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:30.598Z,1415919210.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:30.639Z,1415919210.639 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:53:30.748Z,1415919210.748 [Radio_Freewave](INFO): Powering down
2014-11-13T22:53:31.052Z,1415919211.052 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:31.086Z,1415919211.086 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:31.152Z,1415919211.152 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:31.164Z,1415919211.164 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-11-13T22:53:31.164Z,1415919211.164 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:31.164Z,1415919211.164 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5143
2014-11-13T22:53:31.429Z,1415919211.429 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:53:31.429Z,1415919211.429 [WetLabsBB2FL](INFO): Powering down
2014-11-13T22:53:31.430Z,1415919211.430 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:31.445Z,1415919211.445 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-11-13T22:53:31.445Z,1415919211.445 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:31.446Z,1415919211.446 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5144
2014-11-13T22:53:31.476Z,1415919211.476 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:31.489Z,1415919211.489 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:31.513Z,1415919211.513 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:53:31.513Z,1415919211.513 [CTD_NeilBrown](INFO): Powering down
2014-11-13T22:53:31.514Z,1415919211.514 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:31.515Z,1415919211.515 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-11-13T22:53:31.515Z,1415919211.515 [logger ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:31.516Z,1415919211.516 [logger](INFO): Join timeout helper Thread ID is 5148
2014-11-13T22:53:31.559Z,1415919211.559 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:53:31.559Z,1415919211.559 [logger ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:31.577Z,1415919211.577 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-11-13T22:53:31.577Z,1415919211.577 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:31.578Z,1415919211.578 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-11-13T22:53:31.578Z,1415919211.578 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:31.578Z,1415919211.578 [controlThread](INFO): Join timeout helper Thread ID is 5149
2014-11-13T22:53:31.883Z,1415919211.883 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-11-13T22:53:31.914Z,1415919211.914 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:53:31.931Z,1415919211.931 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:53:31.931Z,1415919211.931 [controlThread](DEBUG): Uninitializing ControlThread
2014-11-13T22:53:31.932Z,1415919211.932 [Aanderaa_O2](INFO): Powering down
2014-11-13T22:53:31.933Z,1415919211.933 [AHRS_sp3003D](INFO): Powering down
2014-11-13T22:53:32.093Z,1415919212.093 [DVL_micro](INFO): uninitialize:Powering down
2014-11-13T22:53:32.094Z,1415919212.094 [NAL9602](INFO): Powering down
2014-11-13T22:53:32.095Z,1415919212.095 [DAT](INFO): Powering down
2014-11-13T22:53:32.214Z,1415919212.214 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-11-13T22:53:32.216Z,1415919212.216 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-11-13T22:53:32.221Z,1415919212.221 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-11-13T22:53:32.221Z,1415919212.221 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-11-13T22:53:32.222Z,1415919212.222 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-11-13T22:53:32.222Z,1415919212.222 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-11-13T22:53:32.222Z,1415919212.222 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-11-13T22:53:32.222Z,1415919212.222 [BuoyancyServo](INFO): Powering down
2014-11-13T22:53:32.234Z,1415919212.234 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-11-13T22:53:32.234Z,1415919212.234 [ElevatorServo](INFO): Powering down
2014-11-13T22:53:32.235Z,1415919212.235 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-11-13T22:53:32.235Z,1415919212.235 [MassServo](INFO): Powering down
2014-11-13T22:53:32.235Z,1415919212.235 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-11-13T22:53:32.236Z,1415919212.236 [RudderServo](INFO): Powering down
2014-11-13T22:53:32.236Z,1415919212.236 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-11-13T22:53:32.237Z,1415919212.237 [ThrusterServo](INFO): Powering down
2014-11-13T22:53:32.238Z,1415919212.238 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-11-13T22:53:32.238Z,1415919212.238 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-11-13T22:53:32.238Z,1415919212.238 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-11-13T22:53:32.282Z,1415919212.282 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:32.334Z,1415919212.334 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:32.338Z,1415919212.338 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:32.348Z,1415919212.348 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:32.349Z,1415919212.349 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:32.754Z,1415919212.754 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:53:32.798Z,1415919212.798 [logger ThreadHandler](INFO): Thread cancelled.