2014-12-19T20:54:09.299Z,1419022449.299 [Supervisor](DEBUG): Initializing supervisor.
2014-12-19T20:54:09.301Z,1419022449.301 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-12-19T20:54:09.302Z,1419022449.302 [SyncHandler](INFO): Protected caller Thread ID is 8910
2014-12-19T20:54:09.302Z,1419022449.302 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-12-19T20:54:09.303Z,1419022449.303 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-12-19T20:54:09.304Z,1419022449.304 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8911
2014-12-19T20:54:09.321Z,1419022449.321 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-12-19T20:54:09.332Z,1419022449.332 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-12-19T20:54:09.333Z,1419022449.333 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-12-19T20:54:09.333Z,1419022449.333 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8912
2014-12-19T20:54:09.334Z,1419022449.334 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-12-19T20:54:09.335Z,1419022449.335 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-12-19T20:54:09.336Z,1419022449.336 [logger ThreadHandler](INFO): Protected caller Thread ID is 8913
2014-12-19T20:54:09.338Z,1419022449.338 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-12-19T20:54:09.338Z,1419022449.338 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-12-19T20:54:09.343Z,1419022449.343 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-12-19T20:54:09.844Z,1419022449.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-12-19T20:54:09.845Z,1419022449.845 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-12-19T20:54:10.001Z,1419022450.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-12-19T20:54:10.002Z,1419022450.002 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-12-19T20:54:10.110Z,1419022450.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-12-19T20:54:10.112Z,1419022450.112 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-12-19T20:54:10.235Z,1419022450.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-12-19T20:54:10.236Z,1419022450.236 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-12-19T20:54:10.322Z,1419022450.322 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-12-19T20:54:10.463Z,1419022450.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-12-19T20:54:10.465Z,1419022450.465 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-12-19T20:54:10.552Z,1419022450.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-12-19T20:54:10.553Z,1419022450.553 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-12-19T20:54:10.750Z,1419022450.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-12-19T20:54:10.751Z,1419022450.751 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-12-19T20:54:11.084Z,1419022451.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-12-19T20:54:11.085Z,1419022451.085 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-12-19T20:54:11.387Z,1419022451.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-12-19T20:54:11.389Z,1419022451.389 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-12-19T20:54:11.885Z,1419022451.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-12-19T20:54:11.886Z,1419022451.886 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-12-19T20:54:12.097Z,1419022452.097 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-12-19T20:54:12.099Z,1419022452.099 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-12-19T20:54:12.211Z,1419022452.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-12-19T20:54:12.278Z,1419022452.278 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-12-19T20:54:12.700Z,1419022452.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-12-19T20:54:12.700Z,1419022452.700 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-12-19T20:54:12.813Z,1419022452.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-12-19T20:54:12.815Z,1419022452.815 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-12-19T20:54:12.819Z,1419022452.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-12-19T20:54:12.923Z,1419022452.923 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-12-19T20:54:13.041Z,1419022453.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-12-19T20:54:13.138Z,1419022453.138 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-12-19T20:54:13.247Z,1419022453.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-12-19T20:54:13.346Z,1419022453.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-12-19T20:54:13.473Z,1419022453.473 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-12-19T20:54:13.627Z,1419022453.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-12-19T20:54:13.728Z,1419022453.728 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-12-19T20:54:13.814Z,1419022453.814 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-12-19T20:54:13.895Z,1419022453.895 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-12-19T20:54:13.991Z,1419022453.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-12-19T20:54:14.102Z,1419022454.102 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2014-12-19T20:54:14.103Z,1419022454.103 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-12-19T20:54:14.122Z,1419022454.122 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-12-19T20:54:14.196Z,1419022454.196 [VerticalControl](DEBUG): Construct VerticalControl.
2014-12-19T20:54:14.313Z,1419022454.313 [VerticalControl] Loaded
2014-12-19T20:54:14.314Z,1419022454.314 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-12-19T20:54:14.314Z,1419022454.314 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-12-19T20:54:14.390Z,1419022454.390 [HorizontalControl] Loaded
2014-12-19T20:54:14.390Z,1419022454.390 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-12-19T20:54:14.392Z,1419022454.392 [SpeedControl](DEBUG): Construct SpeedControl.
2014-12-19T20:54:14.398Z,1419022454.398 [SpeedControl] Loaded
2014-12-19T20:54:14.398Z,1419022454.398 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-12-19T20:54:14.399Z,1419022454.399 [LoopControl](DEBUG): Construct LoopControl.
2014-12-19T20:54:14.399Z,1419022454.399 [LoopControl] Loaded
2014-12-19T20:54:14.400Z,1419022454.400 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-12-19T20:54:14.400Z,1419022454.400 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-12-19T20:54:14.402Z,1419022454.402 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-12-19T20:54:14.525Z,1419022454.525 [SBIT](DEBUG): Construct Startup Built In Test.
2014-12-19T20:54:14.536Z,1419022454.536 [SBIT] Loaded
2014-12-19T20:54:14.536Z,1419022454.536 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-12-19T20:54:14.537Z,1419022454.537 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-12-19T20:54:14.565Z,1419022454.565 [IBIT] Loaded
2014-12-19T20:54:14.565Z,1419022454.565 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-12-19T20:54:14.567Z,1419022454.567 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-12-19T20:54:14.678Z,1419022454.678 [CBIT] Loaded
2014-12-19T20:54:14.679Z,1419022454.679 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-12-19T20:54:14.679Z,1419022454.679 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-12-19T20:54:14.680Z,1419022454.680 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-12-19T20:54:14.708Z,1419022454.708 [DepthRateCalculator] Loaded
2014-12-19T20:54:14.710Z,1419022454.710 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-12-19T20:54:14.715Z,1419022454.715 [PitchRateCalculator] Loaded
2014-12-19T20:54:14.716Z,1419022454.716 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-12-19T20:54:14.727Z,1419022454.727 [SpeedCalculator] Loaded
2014-12-19T20:54:14.727Z,1419022454.727 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-12-19T20:54:14.743Z,1419022454.743 [TempGradientCalculator] Loaded
2014-12-19T20:54:14.743Z,1419022454.743 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-12-19T20:54:14.748Z,1419022454.748 [YawRateCalculator] Loaded
2014-12-19T20:54:14.748Z,1419022454.748 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-12-19T20:54:14.749Z,1419022454.749 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-12-19T20:54:14.749Z,1419022454.749 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-12-19T20:54:19.966Z,1419022459.966 [HFRadarModelCalc] Loaded
2014-12-19T20:54:19.967Z,1419022459.967 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2014-12-19T20:54:23.530Z,1419022463.530 [HFRadarCompactModelForecaster] Loaded
2014-12-19T20:54:23.531Z,1419022463.531 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2014-12-19T20:54:24.501Z,1419022464.501 [HFRCMSpaceInterpolator] Loaded
2014-12-19T20:54:24.501Z,1419022464.501 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2014-12-19T20:54:24.503Z,1419022464.503 [HFRCMTimeInterpolator] Loaded
2014-12-19T20:54:24.503Z,1419022464.503 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2014-12-19T20:54:25.520Z,1419022465.520 [HFRCMReconstructedInterpolator] Loaded
2014-12-19T20:54:25.521Z,1419022465.521 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-12-19T20:54:25.532Z,1419022465.532 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2014-12-19T20:54:25.532Z,1419022465.532 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2014-12-19T20:54:25.558Z,1419022465.558 [HFRCMVirtualSurfaceDrifter] Loaded
2014-12-19T20:54:25.559Z,1419022465.559 [ComponentRegistry](DEBUG): SyncComponent "HFRCMVirtualSurfaceDrifter" handled in the control thread.
2014-12-19T20:54:25.559Z,1419022465.559 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-12-19T20:54:25.560Z,1419022465.560 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-12-19T20:54:25.831Z,1419022465.831 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-12-19T20:54:25.832Z,1419022465.832 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-12-19T20:54:25.927Z,1419022465.927 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-12-19T20:54:25.927Z,1419022465.927 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-12-19T20:54:25.971Z,1419022465.971 [DeadReckonUsingSpeedCalculator] Loaded
2014-12-19T20:54:25.971Z,1419022465.971 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2014-12-19T20:54:26.015Z,1419022466.015 [DeadReckonWithRespectToWater] Loaded
2014-12-19T20:54:26.015Z,1419022466.015 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-12-19T20:54:26.060Z,1419022466.060 [DeadReckonWithRespectToSeafloor] Loaded
2014-12-19T20:54:26.061Z,1419022466.061 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-12-19T20:54:26.109Z,1419022466.109 [DeadReckonUsingDVLWaterTrack] Loaded
2014-12-19T20:54:26.110Z,1419022466.110 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-12-19T20:54:26.155Z,1419022466.155 [DeadReckonUsingCompactModelForecast] Loaded
2014-12-19T20:54:26.155Z,1419022466.155 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-12-19T20:54:26.171Z,1419022466.171 [NavChart] Loaded
2014-12-19T20:54:26.172Z,1419022466.172 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-12-19T20:54:26.176Z,1419022466.176 [UniversalFixResidualReporter] Loaded
2014-12-19T20:54:26.177Z,1419022466.177 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-12-19T20:54:26.177Z,1419022466.177 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-12-19T20:54:26.178Z,1419022466.178 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-12-19T20:54:26.186Z,1419022466.186 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-12-19T20:54:26.187Z,1419022466.187 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-12-19T20:54:26.380Z,1419022466.380 [Aanderaa_O2] Loaded
2014-12-19T20:54:26.380Z,1419022466.380 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-12-19T20:54:26.415Z,1419022466.415 [CTD_NeilBrown] Loaded
2014-12-19T20:54:26.416Z,1419022466.416 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-12-19T20:54:26.417Z,1419022466.417 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407C84E0
2014-12-19T20:54:26.417Z,1419022466.417 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8995
2014-12-19T20:54:26.432Z,1419022466.432 [PAR_Licor] Loaded
2014-12-19T20:54:26.432Z,1419022466.432 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-12-19T20:54:26.466Z,1419022466.466 [WetLabsBB2FL] Loaded
2014-12-19T20:54:26.466Z,1419022466.466 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-12-19T20:54:26.468Z,1419022466.468 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407F84E0
2014-12-19T20:54:26.468Z,1419022466.468 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8996
2014-12-19T20:54:26.468Z,1419022466.468 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-12-19T20:54:26.469Z,1419022466.469 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-12-19T20:54:27.196Z,1419022467.196 [AHRS_sp3003D] Loaded
2014-12-19T20:54:27.196Z,1419022467.196 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-12-19T20:54:27.210Z,1419022467.210 [Depth_Keller] Loaded
2014-12-19T20:54:27.210Z,1419022467.210 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-12-19T20:54:27.215Z,1419022467.215 [DropWeight] Loaded
2014-12-19T20:54:27.215Z,1419022467.215 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-12-19T20:54:27.353Z,1419022467.353 [DVL_micro] Loaded
2014-12-19T20:54:27.354Z,1419022467.354 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-12-19T20:54:27.449Z,1419022467.449 [NAL9602] Loaded
2014-12-19T20:54:27.449Z,1419022467.449 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-12-19T20:54:27.489Z,1419022467.489 [Onboard] Loaded
2014-12-19T20:54:27.489Z,1419022467.489 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-12-19T20:54:27.496Z,1419022467.496 [Radio_Surface] Loaded
2014-12-19T20:54:27.496Z,1419022467.496 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2014-12-19T20:54:27.497Z,1419022467.497 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A424E0
2014-12-19T20:54:27.498Z,1419022467.498 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8997
2014-12-19T20:54:27.622Z,1419022467.622 [DAT] Loaded
2014-12-19T20:54:27.622Z,1419022467.622 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2014-12-19T20:54:27.623Z,1419022467.623 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-12-19T20:54:27.624Z,1419022467.624 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-12-19T20:54:27.746Z,1419022467.746 [BuoyancyServo] Loaded
2014-12-19T20:54:27.746Z,1419022467.746 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-12-19T20:54:27.758Z,1419022467.758 [ElevatorServo] Loaded
2014-12-19T20:54:27.758Z,1419022467.758 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-12-19T20:54:27.769Z,1419022467.769 [MassServo] Loaded
2014-12-19T20:54:27.770Z,1419022467.770 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-12-19T20:54:27.781Z,1419022467.781 [RudderServo] Loaded
2014-12-19T20:54:27.781Z,1419022467.781 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-12-19T20:54:27.792Z,1419022467.792 [ThrusterServo] Loaded
2014-12-19T20:54:27.793Z,1419022467.793 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-12-19T20:54:27.793Z,1419022467.793 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-12-19T20:54:27.794Z,1419022467.794 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-12-19T20:54:27.976Z,1419022467.976 [InternalSim] Loaded
2014-12-19T20:54:27.976Z,1419022467.976 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-12-19T20:54:27.976Z,1419022467.976 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-12-19T20:54:27.977Z,1419022467.977 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-12-19T20:54:28.017Z,1419022468.017 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-12-19T20:54:28.020Z,1419022468.020 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-12-19T20:54:28.020Z,1419022468.020 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-12-19T20:54:28.027Z,1419022468.027 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-12-19T20:54:28.028Z,1419022468.028 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF34E0
2014-12-19T20:54:28.028Z,1419022468.028 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8998
2014-12-19T20:54:28.033Z,1419022468.033 [Supervisor](INFO): Main Thread ID is 8909
2014-12-19T20:54:28.033Z,1419022468.033 [Supervisor](DEBUG): Running supervisor.
2014-12-19T20:54:28.034Z,1419022468.034 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8999
2014-12-19T20:54:28.037Z,1419022468.037 [controlThread ThreadHandler](INFO): Handler Thread ID is 9000
2014-12-19T20:54:28.038Z,1419022468.038 [controlThread](DEBUG): Initializing ControlThread
2014-12-19T20:54:28.039Z,1419022468.039 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-12-19T20:54:28.040Z,1419022468.040 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-12-19T20:54:28.041Z,1419022468.041 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-12-19T20:54:28.042Z,1419022468.042 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-12-19T20:54:28.042Z,1419022468.042 [SBIT](INFO): Initialize SBIT Component.
2014-12-19T20:54:28.042Z,1419022468.042 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11762
2014-12-19T20:54:28.043Z,1419022468.043 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-12-19T20:54:28.043Z,1419022468.043 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-12-19T20:54:28.044Z,1419022468.044 [IBIT](INFO): Initialize IBIT Component.
2014-12-19T20:54:28.044Z,1419022468.044 [CBIT](DEBUG): Initialize CBIT Component.
2014-12-19T20:54:28.045Z,1419022468.045 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-12-19T20:54:28.046Z,1419022468.046 [logger ThreadHandler](INFO): Handler Thread ID is 9001
2014-12-19T20:54:28.064Z,1419022468.064 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 9002
2014-12-19T20:54:28.064Z,1419022468.064 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-12-19T20:54:28.068Z,1419022468.068 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-12-19T20:54:28.068Z,1419022468.068 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-12-19T20:54:28.068Z,1419022468.068 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-12-19T20:54:28.068Z,1419022468.068 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-12-19T20:54:28.069Z,1419022468.069 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-12-19T20:54:28.088Z,1419022468.088 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-12-19T20:54:28.136Z,1419022468.136 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9004
2014-12-19T20:54:28.137Z,1419022468.137 [WetLabsBB2FL](INFO): Powering down
2014-12-19T20:54:28.164Z,1419022468.164 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9005
2014-12-19T20:54:28.172Z,1419022468.172 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2014-12-19T20:54:28.184Z,1419022468.184 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9006
2014-12-19T20:54:28.190Z,1419022468.190 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-12-19T20:54:28.192Z,1419022468.192 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-12-19T20:54:28.192Z,1419022468.192 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-12-19T20:54:28.192Z,1419022468.192 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-12-19T20:54:28.192Z,1419022468.192 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-12-19T20:54:28.193Z,1419022468.193 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-12-19T20:54:28.193Z,1419022468.193 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-12-19T20:54:28.193Z,1419022468.193 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-12-19T20:54:28.193Z,1419022468.193 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-12-19T20:54:36.984Z,1419022476.984 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201412191700 published
2014-12-19T20:54:37.003Z,1419022477.003 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 0.
2014-12-19T20:54:37.004Z,1419022477.004 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2014-12-19T20:54:37.005Z,1419022477.005 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-12-19T20:54:37.006Z,1419022477.006 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2014-12-19T20:54:37.007Z,1419022477.007 [HFRCMVirtualSurfaceDrifter](DEBUG): Initializing HFRCMVirtualSurfaceDrifter component.
2014-12-19T20:54:37.007Z,1419022477.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-12-19T20:54:37.007Z,1419022477.007 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2014-12-19T20:54:37.008Z,1419022477.008 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-12-19T20:54:37.008Z,1419022477.008 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-12-19T20:54:37.009Z,1419022477.009 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-12-19T20:54:37.025Z,1419022477.025 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-12-19T20:54:37.025Z,1419022477.025 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-12-19T20:54:37.026Z,1419022477.026 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2014-12-19T20:54:37.040Z,1419022477.040 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2014-12-19T20:54:37.040Z,1419022477.040 [DropWeight] Hardware Fault, FailCount= 1
2014-12-19T20:54:37.040Z,1419022477.040 [DropWeight](ERROR): Hardware Fault
2014-12-19T20:54:37.051Z,1419022477.051 [InternalSim](DEBUG):  InternalSim initializing...
2014-12-19T20:54:37.358Z,1419022477.358 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-12-19T20:54:37.405Z,1419022477.405 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-12-19T20:54:37.430Z,1419022477.430 [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-12-19T20:54:37.431Z,1419022477.431 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-12-19T20:54:37.527Z,1419022477.527 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-12-19T20:54:37.530Z,1419022477.530 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-12-19T20:54:37.551Z,1419022477.551 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-12-19T20:54:37.575Z,1419022477.575 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-12-19T20:54:37.581Z,1419022477.581 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-12-19T20:54:37.605Z,1419022477.605 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-12-19T20:54:37.631Z,1419022477.631 [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-12-19T20:54:37.641Z,1419022477.641 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,Aanderaa_O2,PAR_Licor,AHRS_sp3003D,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-12-19T20:54:37.932Z,1419022477.932 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-12-19T20:54:37.954Z,1419022477.954 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2014-12-19T20:54:38.003Z,1419022478.003 [DVL_micro](INFO): Initializing
2014-12-19T20:54:38.025Z,1419022478.025 [NAL9602](INFO): Powering up NAL9602
2014-12-19T20:54:38.094Z,1419022478.094 [DAT](INFO): Powering up
2014-12-19T20:54:38.094Z,1419022478.094 [DAT](DEBUG): Initializing DAT.
2014-12-19T20:54:45.070Z,1419022485.070 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:45.077Z,1419022485.077 [DeadReckonUsingSpeedCalculator](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:45.110Z,1419022485.110 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:45.130Z,1419022485.130 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:45.137Z,1419022485.137 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:45.159Z,1419022485.159 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:45.256Z,1419022485.256 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-12-19T20:54:45.262Z,1419022485.262 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-12-19T20:54:45.284Z,1419022485.284 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-12-19T20:54:45.290Z,1419022485.290 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-12-19T20:54:45.316Z,1419022485.316 [MassServo](DEBUG): Initializing EZServoServo.
2014-12-19T20:54:45.322Z,1419022485.322 [MassServo](DEBUG): Initializing MassServo.
2014-12-19T20:54:45.328Z,1419022485.328 [RudderServo](DEBUG): Initializing EZServoServo.
2014-12-19T20:54:45.334Z,1419022485.334 [RudderServo](DEBUG): Initializing RudderServo.
2014-12-19T20:54:45.340Z,1419022485.340 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-12-19T20:54:45.346Z,1419022485.346 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-12-19T20:54:45.382Z,1419022485.382 [CommandLine](FAULT): Scheduling is paused
2014-12-19T20:54:45.384Z,1419022485.384 [CBIT](ERROR): Hardware Fault in component: DropWeight
2014-12-19T20:54:45.385Z,1419022485.385 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2014-12-19T20:54:46.039Z,1419022486.039 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:46.040Z,1419022486.040 [DeadReckonUsingSpeedCalculator](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:46.041Z,1419022486.041 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:46.042Z,1419022486.042 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:46.043Z,1419022486.043 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:46.043Z,1419022486.043 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-12-19T20:54:46.356Z,1419022486.356 [SBIT](IMPORTANT): Beginning Startup BIT
2014-12-19T20:54:46.359Z,1419022486.359 [CBIT](IMPORTANT): Beginning GF scan
2014-12-19T20:54:48.050Z,1419022488.050 [Aanderaa_O2](INFO): Powering down
2014-12-19T20:54:49.645Z,1419022489.645 [NAL9602](INFO): NAL9602 initialized
2014-12-19T20:54:50.389Z,1419022490.389 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:51.093Z,1419022491.093 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:51.692Z,1419022491.692 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:52.242Z,1419022492.242 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:52.960Z,1419022492.960 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:53.529Z,1419022493.529 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:53.981Z,1419022493.981 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:54.434Z,1419022494.434 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:54.903Z,1419022494.903 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:55.367Z,1419022495.367 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:55.836Z,1419022495.836 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:56.361Z,1419022496.361 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:56.821Z,1419022496.821 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:57.294Z,1419022497.294 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:57.743Z,1419022497.743 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:58.224Z,1419022498.224 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:58.249Z,1419022498.249 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2014-12-19T20:54:58.249Z,1419022498.249 [DAT] Communications Fault, FailCount= 1
2014-12-19T20:54:58.249Z,1419022498.249 [DAT](ERROR): Communications Fault
2014-12-19T20:54:58.435Z,1419022498.435 [CBIT](ERROR): Communications Fault in component: DAT
2014-12-19T20:54:58.781Z,1419022498.781 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:58.821Z,1419022498.821 [DAT](INFO): Powering down
2014-12-19T20:54:59.486Z,1419022499.486 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:54:59.635Z,1419022499.635 [SBIT](FAULT): Mass: EXPECTED:0.004000 ACTUAL:0.000747
2014-12-19T20:54:59.999Z,1419022499.999 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:00.161Z,1419022500.161 [CBIT](INFO): Clearing failed state for component DAT
2014-12-19T20:55:00.161Z,1419022500.161 [DAT] No Fault, FailCount= 1
2014-12-19T20:55:00.527Z,1419022500.527 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:01.072Z,1419022501.072 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:01.557Z,1419022501.557 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:02.045Z,1419022502.045 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:02.099Z,1419022502.099 [DAT](INFO): Powering up
2014-12-19T20:55:02.099Z,1419022502.099 [DAT](DEBUG): Initializing DAT.
2014-12-19T20:55:02.727Z,1419022502.727 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:03.304Z,1419022503.304 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:03.821Z,1419022503.821 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:04.357Z,1419022504.357 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:04.882Z,1419022504.882 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:05.383Z,1419022505.383 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:05.912Z,1419022505.912 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:06.488Z,1419022506.488 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:07.009Z,1419022507.009 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:07.554Z,1419022507.554 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:08.167Z,1419022508.167 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:08.952Z,1419022508.952 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:09.385Z,1419022509.385 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:09.890Z,1419022509.890 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:10.483Z,1419022510.483 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:10.944Z,1419022510.944 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:11.392Z,1419022511.392 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:11.901Z,1419022511.901 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:12.358Z,1419022512.358 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:12.836Z,1419022512.836 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:13.419Z,1419022513.419 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:13.535Z,1419022513.535 [CBIT](FAULT): Chan 1 High side GF detected
mA:
CHAN 5 (24V): 0.209191
CHAN 4 (Batt): 0.123583
CHAN 2 (12V): 0.082165
CHAN 1 (5V): 0.021522
CHAN 0 (3.3V): 0.010522
OPEN: 0.028217
Full Scale Calc: 0.392
2014-12-19T20:55:13.916Z,1419022513.916 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:14.425Z,1419022514.425 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:14.861Z,1419022514.861 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:15.302Z,1419022515.302 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:15.339Z,1419022515.339 [DAT](DEBUG): 
2014-12-19T20:55:15.341Z,1419022515.341 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2014-12-19T20:55:15.341Z,1419022515.341 [DAT](DEBUG): MF Frequency Band
2014-12-19T20:55:15.342Z,1419022515.342 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2014-12-19T20:55:15.807Z,1419022515.807 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:15.841Z,1419022515.841 [DAT](DEBUG): Dec 19 2014 21:55:05 
2014-12-19T20:55:16.252Z,1419022516.252 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:16.765Z,1419022516.765 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:16.806Z,1419022516.806 [DAT](INFO): entering command mode
2014-12-19T20:55:17.273Z,1419022517.273 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:17.298Z,1419022517.298 [DAT](DEBUG): checking for command mode acknowledgment
2014-12-19T20:55:17.299Z,1419022517.299 [DAT](DEBUG): read user prompt 1: user:1>
2014-12-19T20:55:17.299Z,1419022517.299 [DAT](INFO): command mode acknowledged
2014-12-19T20:55:17.483Z,1419022517.483 [CommandLine](IMPORTANT): got command quit
2014-12-19T20:55:17.738Z,1419022517.738 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:17.745Z,1419022517.745 [DAT](INFO): setting local address to 2
2014-12-19T20:55:18.195Z,1419022518.195 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:18.214Z,1419022518.214 [DAT](DEBUG): checking for local address setting acknowledgment
2014-12-19T20:55:18.215Z,1419022518.215 [DAT](INFO): set local address to 2
2014-12-19T20:55:18.488Z,1419022518.488 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-12-19T20:55:18.489Z,1419022518.489 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:18.545Z,1419022518.545 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-12-19T20:55:18.545Z,1419022518.545 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:18.546Z,1419022518.546 [CommandLine](INFO): Join timeout helper Thread ID is 9019
2014-12-19T20:55:18.547Z,1419022518.547 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-12-19T20:55:18.547Z,1419022518.547 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:18.547Z,1419022518.547 [NavChartDb](INFO): Join timeout helper Thread ID is 9020
2014-12-19T20:55:18.614Z,1419022518.614 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:19.052Z,1419022519.052 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:19.505Z,1419022519.505 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:19.966Z,1419022519.966 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-12-19T20:55:19.967Z,1419022519.967 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:19.972Z,1419022519.972 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:19.984Z,1419022519.984 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2014-12-19T20:55:19.984Z,1419022519.984 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:19.984Z,1419022519.984 [Radio_Surface](INFO): Join timeout helper Thread ID is 9021
2014-12-19T20:55:20.072Z,1419022520.072 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2014-12-19T20:55:20.072Z,1419022520.072 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:20.076Z,1419022520.076 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-12-19T20:55:20.076Z,1419022520.076 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:20.076Z,1419022520.076 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9022
2014-12-19T20:55:20.396Z,1419022520.396 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:20.441Z,1419022520.441 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-12-19T20:55:20.441Z,1419022520.441 [WetLabsBB2FL](INFO): Powering down
2014-12-19T20:55:20.442Z,1419022520.442 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:20.445Z,1419022520.445 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-12-19T20:55:20.445Z,1419022520.445 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:20.445Z,1419022520.445 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9023
2014-12-19T20:55:20.449Z,1419022520.449 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-12-19T20:55:20.449Z,1419022520.449 [CTD_NeilBrown](INFO): Powering down
2014-12-19T20:55:20.450Z,1419022520.450 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:20.466Z,1419022520.466 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-12-19T20:55:20.466Z,1419022520.466 [logger ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:20.466Z,1419022520.466 [logger](INFO): Join timeout helper Thread ID is 9024
2014-12-19T20:55:20.481Z,1419022520.481 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-12-19T20:55:20.481Z,1419022520.481 [logger ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:20.501Z,1419022520.501 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-12-19T20:55:20.502Z,1419022520.502 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:20.502Z,1419022520.502 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-12-19T20:55:20.502Z,1419022520.502 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:20.502Z,1419022520.502 [controlThread](INFO): Join timeout helper Thread ID is 9025
2014-12-19T20:55:20.807Z,1419022520.807 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-12-19T20:55:20.838Z,1419022520.838 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-12-19T20:55:20.838Z,1419022520.838 [controlThread](DEBUG): Uninitializing ControlThread
2014-12-19T20:55:20.839Z,1419022520.839 [Aanderaa_O2](INFO): Powering down
2014-12-19T20:55:20.840Z,1419022520.840 [AHRS_sp3003D](INFO): Powering down
2014-12-19T20:55:20.998Z,1419022520.998 [DVL_micro](INFO): uninitialize:Powering down
2014-12-19T20:55:20.999Z,1419022520.999 [NAL9602](INFO): Powering down
2014-12-19T20:55:20.000Z,1419022521.000 [DAT](INFO): Powering down
2014-12-19T20:55:21.118Z,1419022521.118 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-12-19T20:55:21.121Z,1419022521.121 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-12-19T20:55:21.125Z,1419022521.125 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-12-19T20:55:21.125Z,1419022521.125 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-12-19T20:55:21.126Z,1419022521.126 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-12-19T20:55:21.126Z,1419022521.126 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-12-19T20:55:21.126Z,1419022521.126 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-12-19T20:55:21.126Z,1419022521.126 [BuoyancyServo](INFO): Powering down
2014-12-19T20:55:21.138Z,1419022521.138 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-12-19T20:55:21.138Z,1419022521.138 [ElevatorServo](INFO): Powering down
2014-12-19T20:55:21.139Z,1419022521.139 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-12-19T20:55:21.139Z,1419022521.139 [MassServo](INFO): Powering down
2014-12-19T20:55:21.140Z,1419022521.140 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-12-19T20:55:21.140Z,1419022521.140 [RudderServo](INFO): Powering down
2014-12-19T20:55:21.140Z,1419022521.140 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-12-19T20:55:21.141Z,1419022521.141 [ThrusterServo](INFO): Powering down
2014-12-19T20:55:21.142Z,1419022521.142 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-12-19T20:55:21.142Z,1419022521.142 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-12-19T20:55:21.142Z,1419022521.142 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-12-19T20:55:21.174Z,1419022521.174 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:21.245Z,1419022521.245 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:21.249Z,1419022521.249 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:21.258Z,1419022521.258 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:21.297Z,1419022521.297 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-12-19T20:55:21.334Z,1419022521.334 [logger ThreadHandler](INFO): Thread cancelled.