2014-09-17T19:12:48.255Z,1410981168.255 [Supervisor](DEBUG): Initializing supervisor.
2014-09-17T19:12:48.258Z,1410981168.258 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-09-17T19:12:48.259Z,1410981168.259 [SyncHandler](INFO): Protected caller Thread ID is 1813
2014-09-17T19:12:48.259Z,1410981168.259 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-09-17T19:12:48.260Z,1410981168.260 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-09-17T19:12:48.261Z,1410981168.261 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1814
2014-09-17T19:12:48.265Z,1410981168.265 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-09-17T19:12:48.278Z,1410981168.278 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-09-17T19:12:48.279Z,1410981168.279 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-09-17T19:12:48.279Z,1410981168.279 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1815
2014-09-17T19:12:48.280Z,1410981168.280 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-09-17T19:12:48.281Z,1410981168.281 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-09-17T19:12:48.282Z,1410981168.282 [logger ThreadHandler](INFO): Protected caller Thread ID is 1816
2014-09-17T19:12:48.284Z,1410981168.284 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-09-17T19:12:48.285Z,1410981168.285 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-09-17T19:12:48.286Z,1410981168.286 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-09-17T19:12:48.622Z,1410981168.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-09-17T19:12:48.623Z,1410981168.623 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-09-17T19:12:48.781Z,1410981168.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-09-17T19:12:48.782Z,1410981168.782 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-09-17T19:12:48.892Z,1410981168.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-09-17T19:12:48.893Z,1410981168.893 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-09-17T19:12:49.018Z,1410981169.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-09-17T19:12:49.018Z,1410981169.018 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-09-17T19:12:49.104Z,1410981169.104 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-09-17T19:12:49.250Z,1410981169.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-09-17T19:12:49.251Z,1410981169.251 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-09-17T19:12:49.338Z,1410981169.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-09-17T19:12:49.338Z,1410981169.338 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-09-17T19:12:49.542Z,1410981169.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-09-17T19:12:49.543Z,1410981169.543 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-09-17T19:12:49.860Z,1410981169.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-09-17T19:12:49.860Z,1410981169.860 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-09-17T19:12:50.168Z,1410981170.168 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-09-17T19:12:50.168Z,1410981170.168 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-09-17T19:12:50.684Z,1410981170.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-09-17T19:12:50.685Z,1410981170.685 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-09-17T19:12:50.874Z,1410981170.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-09-17T19:12:50.874Z,1410981170.874 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-09-17T19:12:50.980Z,1410981170.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-09-17T19:12:50.981Z,1410981170.981 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-09-17T19:12:51.409Z,1410981171.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-09-17T19:12:51.409Z,1410981171.409 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-09-17T19:12:51.521Z,1410981171.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-09-17T19:12:51.523Z,1410981171.523 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-09-17T19:12:51.524Z,1410981171.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-09-17T19:12:51.629Z,1410981171.629 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-09-17T19:12:51.747Z,1410981171.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-09-17T19:12:51.845Z,1410981171.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-09-17T19:12:51.956Z,1410981171.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-09-17T19:12:52.054Z,1410981172.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-09-17T19:12:52.183Z,1410981172.183 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-09-17T19:12:52.335Z,1410981172.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-09-17T19:12:52.437Z,1410981172.437 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-09-17T19:12:52.524Z,1410981172.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-09-17T19:12:52.605Z,1410981172.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-09-17T19:12:52.706Z,1410981172.706 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-09-17T19:12:52.820Z,1410981172.820 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2014-09-17T19:12:52.820Z,1410981172.820 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2014-09-17T19:12:52.822Z,1410981172.822 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-09-17T19:12:52.898Z,1410981172.898 [VerticalControl](DEBUG): Construct VerticalControl.
2014-09-17T19:12:53.023Z,1410981173.023 [VerticalControl] Loaded
2014-09-17T19:12:53.023Z,1410981173.023 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-09-17T19:12:53.024Z,1410981173.024 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-09-17T19:12:53.098Z,1410981173.098 [HorizontalControl] Loaded
2014-09-17T19:12:53.099Z,1410981173.099 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-09-17T19:12:53.099Z,1410981173.099 [SpeedControl](DEBUG): Construct SpeedControl.
2014-09-17T19:12:53.105Z,1410981173.105 [SpeedControl] Loaded
2014-09-17T19:12:53.105Z,1410981173.105 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-09-17T19:12:53.106Z,1410981173.106 [LoopControl](DEBUG): Construct LoopControl.
2014-09-17T19:12:53.107Z,1410981173.107 [LoopControl] Loaded
2014-09-17T19:12:53.107Z,1410981173.107 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-09-17T19:12:53.108Z,1410981173.108 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-09-17T19:12:53.108Z,1410981173.108 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-09-17T19:12:54.368Z,1410981174.368 [SBIT](DEBUG): Construct Startup Built In Test.
2014-09-17T19:12:54.380Z,1410981174.380 [SBIT] Loaded
2014-09-17T19:12:54.380Z,1410981174.380 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-09-17T19:12:54.381Z,1410981174.381 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-09-17T19:12:54.411Z,1410981174.411 [IBIT] Loaded
2014-09-17T19:12:54.412Z,1410981174.412 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-09-17T19:12:54.414Z,1410981174.414 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-09-17T19:12:54.529Z,1410981174.529 [CBIT] Loaded
2014-09-17T19:12:54.529Z,1410981174.529 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-09-17T19:12:54.529Z,1410981174.529 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-09-17T19:12:54.530Z,1410981174.530 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-09-17T19:12:54.559Z,1410981174.559 [DepthRateCalculator] Loaded
2014-09-17T19:12:54.560Z,1410981174.560 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-09-17T19:12:54.565Z,1410981174.565 [PitchRateCalculator] Loaded
2014-09-17T19:12:54.566Z,1410981174.566 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-09-17T19:12:54.578Z,1410981174.578 [SpeedCalculator] Loaded
2014-09-17T19:12:54.578Z,1410981174.578 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-09-17T19:12:54.595Z,1410981174.595 [TempGradientCalculator] Loaded
2014-09-17T19:12:54.596Z,1410981174.596 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-09-17T19:12:54.601Z,1410981174.601 [YawRateCalculator] Loaded
2014-09-17T19:12:54.602Z,1410981174.602 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-09-17T19:12:54.602Z,1410981174.602 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-09-17T19:12:54.603Z,1410981174.603 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-09-17T19:12:54.689Z,1410981174.689 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-09-17T19:12:54.690Z,1410981174.690 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-09-17T19:12:54.968Z,1410981174.968 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-09-17T19:12:54.969Z,1410981174.969 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-09-17T19:12:55.070Z,1410981175.070 [DeadReckonUsingMultipleVelocitySourcesVector] Loaded
2014-09-17T19:12:55.071Z,1410981175.071 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySourcesVector" handled in the control thread.
2014-09-17T19:12:55.127Z,1410981175.127 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-09-17T19:12:55.128Z,1410981175.128 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-09-17T19:12:55.175Z,1410981175.175 [DeadReckonWithRespectToWater] Loaded
2014-09-17T19:12:55.175Z,1410981175.175 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-09-17T19:12:55.224Z,1410981175.224 [DeadReckonWithRespectToSeafloor] Loaded
2014-09-17T19:12:55.224Z,1410981175.224 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-09-17T19:12:55.286Z,1410981175.286 [DeadReckonUsingDVLWaterTrack] Loaded
2014-09-17T19:12:55.286Z,1410981175.286 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-09-17T19:12:55.336Z,1410981175.336 [DeadReckonUsingCompactModelForecast] Loaded
2014-09-17T19:12:55.337Z,1410981175.337 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-09-17T19:12:55.354Z,1410981175.354 [NavChart] Loaded
2014-09-17T19:12:55.354Z,1410981175.354 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-09-17T19:12:55.359Z,1410981175.359 [UniversalFixResidualReporter] Loaded
2014-09-17T19:12:55.359Z,1410981175.359 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-09-17T19:12:55.360Z,1410981175.360 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-09-17T19:12:55.360Z,1410981175.360 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-09-17T19:12:55.369Z,1410981175.369 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-09-17T19:12:55.369Z,1410981175.369 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-09-17T19:12:55.608Z,1410981175.608 [Aanderaa_O2] Loaded
2014-09-17T19:12:55.608Z,1410981175.608 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-09-17T19:12:55.686Z,1410981175.686 [CTD_NeilBrown] Loaded
2014-09-17T19:12:55.687Z,1410981175.687 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-09-17T19:12:55.688Z,1410981175.688 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 405644E0
2014-09-17T19:12:55.688Z,1410981175.688 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1901
2014-09-17T19:12:55.704Z,1410981175.704 [PAR_Licor] Loaded
2014-09-17T19:12:55.705Z,1410981175.705 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-09-17T19:12:55.744Z,1410981175.744 [WetLabsBB2FL] Loaded
2014-09-17T19:12:55.744Z,1410981175.744 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-09-17T19:12:55.745Z,1410981175.745 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405944E0
2014-09-17T19:12:55.746Z,1410981175.746 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1902
2014-09-17T19:12:55.747Z,1410981175.747 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-09-17T19:12:55.747Z,1410981175.747 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-09-17T19:12:56.358Z,1410981176.358 [AHRS_sp3003D] Loaded
2014-09-17T19:12:56.358Z,1410981176.358 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-09-17T19:12:56.597Z,1410981176.597 [Batt_Ocean_Server] Loaded
2014-09-17T19:12:56.597Z,1410981176.597 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-09-17T19:12:56.611Z,1410981176.611 [Depth_Keller] Loaded
2014-09-17T19:12:56.612Z,1410981176.612 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-09-17T19:12:56.617Z,1410981176.617 [DropWeight] Loaded
2014-09-17T19:12:56.617Z,1410981176.617 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-09-17T19:12:56.756Z,1410981176.756 [DVL_micro] Loaded
2014-09-17T19:12:56.756Z,1410981176.756 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-09-17T19:12:56.858Z,1410981176.858 [NAL9602] Loaded
2014-09-17T19:12:56.859Z,1410981176.859 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-09-17T19:12:56.902Z,1410981176.902 [Onboard] Loaded
2014-09-17T19:12:56.902Z,1410981176.902 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-09-17T19:12:56.910Z,1410981176.910 [Radio_Freewave] Loaded
2014-09-17T19:12:56.910Z,1410981176.910 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-09-17T19:12:56.911Z,1410981176.911 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 407D44E0
2014-09-17T19:12:56.912Z,1410981176.912 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 1904
2014-09-17T19:12:56.919Z,1410981176.919 [Radio_Surface] Loaded
2014-09-17T19:12:56.919Z,1410981176.919 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2014-09-17T19:12:56.921Z,1410981176.921 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408044E0
2014-09-17T19:12:56.921Z,1410981176.921 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1905
2014-09-17T19:12:57.053Z,1410981177.053 [DAT] Loaded
2014-09-17T19:12:57.053Z,1410981177.053 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2014-09-17T19:12:57.054Z,1410981177.054 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-09-17T19:12:57.055Z,1410981177.055 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-09-17T19:12:57.182Z,1410981177.182 [BuoyancyServo] Loaded
2014-09-17T19:12:57.182Z,1410981177.182 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-09-17T19:12:57.195Z,1410981177.195 [ElevatorServo] Loaded
2014-09-17T19:12:57.195Z,1410981177.195 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-09-17T19:12:57.207Z,1410981177.207 [MassServo] Loaded
2014-09-17T19:12:57.207Z,1410981177.207 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-09-17T19:12:57.220Z,1410981177.220 [RudderServo] Loaded
2014-09-17T19:12:57.220Z,1410981177.220 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-09-17T19:12:57.232Z,1410981177.232 [ThrusterServo] Loaded
2014-09-17T19:12:57.232Z,1410981177.232 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-09-17T19:12:57.233Z,1410981177.233 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-09-17T19:12:57.233Z,1410981177.233 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-09-17T19:12:57.431Z,1410981177.431 [InternalSim] Loaded
2014-09-17T19:12:57.431Z,1410981177.431 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-09-17T19:12:57.432Z,1410981177.432 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-09-17T19:12:57.433Z,1410981177.433 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-09-17T19:12:57.472Z,1410981177.472 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-09-17T19:12:57.474Z,1410981177.474 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-09-17T19:12:57.475Z,1410981177.475 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-09-17T19:12:57.483Z,1410981177.483 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-09-17T19:12:57.484Z,1410981177.484 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408B54E0
2014-09-17T19:12:57.484Z,1410981177.484 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1906
2014-09-17T19:12:57.490Z,1410981177.490 [Supervisor](INFO): Main Thread ID is 1812
2014-09-17T19:12:57.490Z,1410981177.490 [Supervisor](DEBUG): Running supervisor.
2014-09-17T19:12:57.490Z,1410981177.490 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1907
2014-09-17T19:12:57.495Z,1410981177.495 [controlThread ThreadHandler](INFO): Handler Thread ID is 1908
2014-09-17T19:12:57.495Z,1410981177.495 [controlThread](DEBUG): Initializing ControlThread
2014-09-17T19:12:57.496Z,1410981177.496 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-09-17T19:12:57.498Z,1410981177.498 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-09-17T19:12:57.499Z,1410981177.499 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-09-17T19:12:57.499Z,1410981177.499 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-09-17T19:12:57.499Z,1410981177.499 [SBIT](INFO): Initialize SBIT Component.
2014-09-17T19:12:57.500Z,1410981177.500 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11635
2014-09-17T19:12:57.500Z,1410981177.500 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-09-17T19:12:57.501Z,1410981177.501 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-09-17T19:12:57.501Z,1410981177.501 [IBIT](INFO): Initialize IBIT Component.
2014-09-17T19:12:57.502Z,1410981177.502 [CBIT](DEBUG): Initialize CBIT Component.
2014-09-17T19:12:57.502Z,1410981177.502 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2014-09-17T19:12:57.503Z,1410981177.503 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-09-17T19:12:57.503Z,1410981177.503 [logger ThreadHandler](INFO): Handler Thread ID is 1909
2014-09-17T19:12:57.522Z,1410981177.522 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1910
2014-09-17T19:12:57.522Z,1410981177.522 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-09-17T19:12:57.526Z,1410981177.526 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-17T19:12:57.526Z,1410981177.526 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-09-17T19:12:57.526Z,1410981177.526 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-09-17T19:12:57.526Z,1410981177.526 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-09-17T19:12:57.527Z,1410981177.527 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-09-17T19:12:57.527Z,1410981177.527 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Initializing DeadReckonUsingMultipleVelocitySourcesVector component.
2014-09-17T19:12:57.528Z,1410981177.528 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-17T19:12:57.528Z,1410981177.528 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-09-17T19:12:57.528Z,1410981177.528 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-09-17T19:12:57.529Z,1410981177.529 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-09-17T19:12:57.529Z,1410981177.529 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-09-17T19:12:57.530Z,1410981177.530 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-09-17T19:12:57.530Z,1410981177.530 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-17T19:12:57.535Z,1410981177.535 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-09-17T19:12:57.549Z,1410981177.549 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1912
2014-09-17T19:12:57.551Z,1410981177.551 [WetLabsBB2FL](INFO): Powering down
2014-09-17T19:12:57.570Z,1410981177.570 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 1913
2014-09-17T19:12:57.599Z,1410981177.599 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1915
2014-09-17T19:12:57.626Z,1410981177.626 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1916
2014-09-17T19:12:57.630Z,1410981177.630 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-09-17T19:12:57.631Z,1410981177.631 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-09-17T19:12:57.631Z,1410981177.631 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-09-17T19:12:57.631Z,1410981177.631 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-09-17T19:12:57.632Z,1410981177.632 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-09-17T19:12:57.632Z,1410981177.632 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-09-17T19:12:57.632Z,1410981177.632 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-09-17T19:12:57.632Z,1410981177.632 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-09-17T19:12:57.633Z,1410981177.633 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-09-17T19:12:57.855Z,1410981177.855 [Radio_Freewave](INFO): Powering up
2014-09-17T19:12:58.660Z,1410981178.660 [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-09-17T19:12:59.405Z,1410981179.405 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-09-17T19:12:59.437Z,1410981179.437 [InternalSim](DEBUG):  InternalSim initializing...
2014-09-17T19:12:59.552Z,1410981179.552 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-09-17T19:12:59.606Z,1410981179.606 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-17T19:12:59.641Z,1410981179.641 [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-09-17T19:12:59.642Z,1410981179.642 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-09-17T19:12:59.734Z,1410981179.734 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-09-17T19:12:59.758Z,1410981179.758 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-09-17T19:12:59.762Z,1410981179.762 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-17T19:12:59.787Z,1410981179.787 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-09-17T19:12:59.813Z,1410981179.813 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-09-17T19:12:59.838Z,1410981179.838 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-09-17T19:12:59.860Z,1410981179.860 [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-09-17T19:12:59.866Z,1410981179.866 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,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,DeadReckonUsingMultipleVelocitySourcesVector,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-09-17T19:12:59.983Z,1410981179.983 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-09-17T19:13:00.126Z,1410981180.126 [DVL_micro](INFO): Initializing
2014-09-17T19:13:00.222Z,1410981180.222 [DAT](INFO): Powering up
2014-09-17T19:13:00.222Z,1410981180.222 [DAT](DEBUG): Initializing DAT.
2014-09-17T19:13:00.323Z,1410981180.323 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:00.346Z,1410981180.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:00.366Z,1410981180.366 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:00.372Z,1410981180.372 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:00.411Z,1410981180.411 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:00.417Z,1410981180.417 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:00.556Z,1410981180.556 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-09-17T19:13:00.562Z,1410981180.562 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-09-17T19:13:00.580Z,1410981180.580 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-09-17T19:13:00.586Z,1410981180.586 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-09-17T19:13:00.600Z,1410981180.600 [MassServo](DEBUG): Initializing EZServoServo.
2014-09-17T19:13:00.606Z,1410981180.606 [MassServo](DEBUG): Initializing MassServo.
2014-09-17T19:13:00.624Z,1410981180.624 [RudderServo](DEBUG): Initializing EZServoServo.
2014-09-17T19:13:00.630Z,1410981180.630 [RudderServo](DEBUG): Initializing RudderServo.
2014-09-17T19:13:00.648Z,1410981180.648 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-09-17T19:13:00.654Z,1410981180.654 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-09-17T19:13:01.035Z,1410981181.035 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:01.036Z,1410981181.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:01.037Z,1410981181.037 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:01.038Z,1410981181.038 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:01.039Z,1410981181.039 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:01.040Z,1410981181.040 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-17T19:13:02.291Z,1410981182.291 [NAL9602](INFO): Powering up NAL9602
2014-09-17T19:13:03.170Z,1410981183.170 [Aanderaa_O2](INFO): Powering down
2014-09-17T19:13:05.037Z,1410981185.037 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:05.624Z,1410981185.624 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:06.523Z,1410981186.523 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:07.114Z,1410981187.114 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:07.603Z,1410981187.603 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:08.080Z,1410981188.080 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:08.589Z,1410981188.589 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:09.045Z,1410981189.046 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:09.771Z,1410981189.771 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:10.275Z,1410981190.275 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:10.760Z,1410981190.760 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:11.361Z,1410981191.361 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:11.822Z,1410981191.822 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:12.282Z,1410981192.282 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:12.735Z,1410981192.735 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:13.200Z,1410981193.200 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:13.328Z,1410981193.328 [SBIT](IMPORTANT): Beginning Startup BIT
2014-09-17T19:13:13.331Z,1410981193.331 [CBIT](IMPORTANT): Beginning GF scan
2014-09-17T19:13:13.709Z,1410981193.709 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:14.373Z,1410981194.373 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:15.003Z,1410981195.003 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:15.515Z,1410981195.515 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:16.060Z,1410981196.060 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:16.578Z,1410981196.578 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:17.110Z,1410981197.110 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:17.699Z,1410981197.699 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:18.240Z,1410981198.240 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:18.832Z,1410981198.832 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:19.518Z,1410981199.518 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:20.154Z,1410981200.154 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:20.684Z,1410981200.684 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:20.743Z,1410981200.743 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2014-09-17T19:13:20.743Z,1410981200.743 [DAT] Communications Fault, FailCount= 1
2014-09-17T19:13:20.744Z,1410981200.744 [DAT](ERROR): Communications Fault
2014-09-17T19:13:20.890Z,1410981200.890 [CBIT](ERROR): Communications Fault in component: DAT
2014-09-17T19:13:21.249Z,1410981201.249 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:21.262Z,1410981201.262 [DAT](INFO): Powering down
2014-09-17T19:13:21.955Z,1410981201.955 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:22.474Z,1410981202.474 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:22.645Z,1410981202.645 [CBIT](INFO): Clearing failed state for component DAT
2014-09-17T19:13:22.645Z,1410981202.645 [DAT] No Fault, FailCount= 1
2014-09-17T19:13:23.007Z,1410981203.007 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:23.516Z,1410981203.516 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:23.517Z,1410981203.517 [NAL9602](ERROR): NAL9602 initialization error.
2014-09-17T19:13:23.517Z,1410981203.517 [NAL9602] Communications Fault, FailCount= 1
2014-09-17T19:13:23.517Z,1410981203.517 [NAL9602](ERROR): Communications Fault
2014-09-17T19:13:23.690Z,1410981203.690 [CBIT](ERROR): Communications Fault in component: NAL9602
2014-09-17T19:13:24.113Z,1410981204.113 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:24.144Z,1410981204.144 [NAL9602](INFO): Powering down
2014-09-17T19:13:24.770Z,1410981204.770 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:24.782Z,1410981204.782 [DAT](INFO): Powering up
2014-09-17T19:13:24.782Z,1410981204.782 [DAT](DEBUG): Initializing DAT.
2014-09-17T19:13:25.495Z,1410981205.495 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:25.602Z,1410981205.602 [CBIT](INFO): Clearing failed state for component NAL9602
2014-09-17T19:13:25.602Z,1410981205.602 [NAL9602] No Fault, FailCount= 1
2014-09-17T19:13:25.976Z,1410981205.976 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:26.529Z,1410981206.529 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:27.113Z,1410981207.113 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:27.590Z,1410981207.590 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:28.147Z,1410981208.147 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:28.664Z,1410981208.664 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:29.557Z,1410981209.557 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:29.558Z,1410981209.558 [NAL9602](INFO): Powering up NAL9602
2014-09-17T19:13:30.233Z,1410981210.233 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:30.747Z,1410981210.747 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:31.320Z,1410981211.320 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:31.828Z,1410981211.828 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:32.330Z,1410981212.330 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:32.838Z,1410981212.838 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:33.407Z,1410981213.407 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:34.052Z,1410981214.052 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:34.613Z,1410981214.613 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:35.090Z,1410981215.090 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:35.719Z,1410981215.719 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:36.324Z,1410981216.324 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:36.800Z,1410981216.800 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:37.269Z,1410981217.269 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:37.354Z,1410981217.354 [DAT](DEBUG): 
2014-09-17T19:13:37.363Z,1410981217.363 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2014-09-17T19:13:37.364Z,1410981217.364 [DAT](DEBUG): MF Frequency Band
2014-09-17T19:13:37.365Z,1410981217.365 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2014-09-17T19:13:37.810Z,1410981217.810 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:37.827Z,1410981217.827 [DAT](DEBUG): Sep 17 2014 20:13:26 
2014-09-17T19:13:38.279Z,1410981218.279 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:38.767Z,1410981218.767 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:38.777Z,1410981218.777 [DAT](INFO): entering command mode
2014-09-17T19:13:39.236Z,1410981219.236 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:39.243Z,1410981219.243 [DAT](DEBUG): checking for command mode acknowledgment
2014-09-17T19:13:39.697Z,1410981219.697 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:39.709Z,1410981219.709 [DAT](DEBUG): checking for command mode acknowledgment
2014-09-17T19:13:39.710Z,1410981219.710 [DAT](DEBUG): read user prompt 1: user:1>
2014-09-17T19:13:39.710Z,1410981219.710 [DAT](INFO): command mode acknowledged
2014-09-17T19:13:39.807Z,1410981219.807 [CBIT](IMPORTANT): No ground fault detected
2014-09-17T19:13:40.162Z,1410981220.162 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:40.256Z,1410981220.256 [DAT](INFO): setting local address to 2
2014-09-17T19:13:40.834Z,1410981220.834 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:40.882Z,1410981220.882 [NAL9602](INFO): NAL9602 initialized
2014-09-17T19:13:40.904Z,1410981220.904 [DAT](DEBUG): checking for local address setting acknowledgment
2014-09-17T19:13:40.913Z,1410981220.913 [DAT](INFO): set local address to 2
2014-09-17T19:13:41.440Z,1410981221.440 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:41.533Z,1410981221.533 [CommandLine](IMPORTANT): got command 
2014-09-17T19:13:41.533Z,1410981221.533 [CommandLine](FAULT): Incomplete syntax. Try: help
2014-09-17T19:13:41.900Z,1410981221.900 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:42.362Z,1410981222.362 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:42.597Z,1410981222.597 [CommandLine](IMPORTANT): got command 
2014-09-17T19:13:42.597Z,1410981222.597 [CommandLine](FAULT): Incomplete syntax. Try: help
2014-09-17T19:13:42.794Z,1410981222.794 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:43.214Z,1410981223.214 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:43.699Z,1410981223.699 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:43.765Z,1410981223.765 [CommandLine](IMPORTANT): got command failComponent
2014-09-17T19:13:43.765Z,1410981223.765 [CommandLine](IMPORTANT): Failed components:
2014-09-17T19:13:43.766Z,1410981223.766 [CommandLine](IMPORTANT): No failed Components.
2014-09-17T19:13:44.209Z,1410981224.209 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:44.697Z,1410981224.697 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:45.141Z,1410981225.141 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:45.683Z,1410981225.683 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:46.183Z,1410981226.183 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:46.619Z,1410981226.619 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:47.069Z,1410981227.069 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:47.489Z,1410981227.489 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:47.986Z,1410981227.986 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:48.503Z,1410981228.503 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:48.999Z,1410981228.999 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:49.429Z,1410981229.429 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:49.861Z,1410981229.861 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:50.377Z,1410981230.377 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:50.887Z,1410981230.887 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:51.427Z,1410981231.427 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:51.876Z,1410981231.876 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:52.125Z,1410981232.125 [CommandLine](IMPORTANT): got command quit
2014-09-17T19:13:52.337Z,1410981232.337 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:52.745Z,1410981232.745 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:53.132Z,1410981233.132 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-17T19:13:53.132Z,1410981233.132 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:53.178Z,1410981233.178 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:53.200Z,1410981233.200 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-09-17T19:13:53.200Z,1410981233.200 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:53.200Z,1410981233.200 [CommandLine](INFO): Join timeout helper Thread ID is 1936
2014-09-17T19:13:53.212Z,1410981233.212 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-09-17T19:13:53.212Z,1410981233.212 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:53.212Z,1410981233.212 [NavChartDb](INFO): Join timeout helper Thread ID is 1937
2014-09-17T19:13:53.679Z,1410981233.679 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:54.127Z,1410981234.127 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:54.652Z,1410981234.652 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:55.121Z,1410981235.121 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:55.301Z,1410981235.301 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-17T19:13:55.301Z,1410981235.301 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:55.303Z,1410981235.303 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2014-09-17T19:13:55.303Z,1410981235.303 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:55.303Z,1410981235.303 [Radio_Surface](INFO): Join timeout helper Thread ID is 1941
2014-09-17T19:13:55.634Z,1410981235.634 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:55.700Z,1410981235.700 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-17T19:13:55.701Z,1410981235.701 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:55.703Z,1410981235.703 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-09-17T19:13:55.704Z,1410981235.704 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:55.704Z,1410981235.704 [Radio_Freewave](INFO): Join timeout helper Thread ID is 1942
2014-09-17T19:13:55.908Z,1410981235.908 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-17T19:13:56.011Z,1410981236.011 [Radio_Freewave](INFO): Powering down
2014-09-17T19:13:56.196Z,1410981236.196 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:56.413Z,1410981236.413 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:56.428Z,1410981236.428 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-09-17T19:13:56.429Z,1410981236.429 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:56.429Z,1410981236.429 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1945
2014-09-17T19:13:56.794Z,1410981236.794 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-17T19:13:56.794Z,1410981236.794 [WetLabsBB2FL](INFO): Powering down
2014-09-17T19:13:56.795Z,1410981236.795 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:56.814Z,1410981236.814 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-09-17T19:13:56.814Z,1410981236.814 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:56.815Z,1410981236.815 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1946
2014-09-17T19:13:56.942Z,1410981236.942 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-17T19:13:56.942Z,1410981236.942 [CTD_NeilBrown](INFO): Powering down
2014-09-17T19:13:56.943Z,1410981236.943 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:56.954Z,1410981236.954 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-09-17T19:13:56.954Z,1410981236.954 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:56.955Z,1410981236.955 [logger](INFO): Join timeout helper Thread ID is 1947
2014-09-17T19:13:56.985Z,1410981236.985 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-17T19:13:56.986Z,1410981236.986 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:56.998Z,1410981236.998 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-09-17T19:13:56.998Z,1410981236.998 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:56.999Z,1410981236.999 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-09-17T19:13:56.999Z,1410981236.999 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:56.999Z,1410981236.999 [controlThread](INFO): Join timeout helper Thread ID is 1948
2014-09-17T19:13:57.002Z,1410981237.002 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-09-17T19:13:57.123Z,1410981237.123 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-17T19:13:57.123Z,1410981237.123 [controlThread](DEBUG): Uninitializing ControlThread
2014-09-17T19:13:57.124Z,1410981237.124 [Aanderaa_O2](INFO): Powering down
2014-09-17T19:13:57.125Z,1410981237.125 [AHRS_sp3003D](INFO): Powering down
2014-09-17T19:13:57.282Z,1410981237.282 [DVL_micro](INFO): uninitialize:Powering down
2014-09-17T19:13:57.283Z,1410981237.283 [NAL9602](INFO): Powering down
2014-09-17T19:13:57.284Z,1410981237.284 [DAT](INFO): Powering down
2014-09-17T19:13:57.403Z,1410981237.403 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-17T19:13:57.405Z,1410981237.405 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-09-17T19:13:57.409Z,1410981237.409 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-09-17T19:13:57.410Z,1410981237.410 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-09-17T19:13:57.410Z,1410981237.410 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-09-17T19:13:57.411Z,1410981237.411 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-09-17T19:13:57.411Z,1410981237.411 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-09-17T19:13:57.411Z,1410981237.411 [BuoyancyServo](INFO): Powering down
2014-09-17T19:13:57.426Z,1410981237.426 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-09-17T19:13:57.426Z,1410981237.426 [ElevatorServo](INFO): Powering down
2014-09-17T19:13:57.427Z,1410981237.427 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-09-17T19:13:57.428Z,1410981237.428 [MassServo](INFO): Powering down
2014-09-17T19:13:57.428Z,1410981237.428 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-09-17T19:13:57.429Z,1410981237.429 [RudderServo](INFO): Powering down
2014-09-17T19:13:57.429Z,1410981237.429 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-09-17T19:13:57.430Z,1410981237.430 [ThrusterServo](INFO): Powering down
2014-09-17T19:13:57.431Z,1410981237.431 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-09-17T19:13:57.431Z,1410981237.431 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-09-17T19:13:57.431Z,1410981237.431 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-09-17T19:13:57.474Z,1410981237.474 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:57.539Z,1410981237.539 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:57.543Z,1410981237.543 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:57.553Z,1410981237.553 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:57.554Z,1410981237.554 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:57.958Z,1410981237.958 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-17T19:13:58.001Z,1410981238.001 [logger ThreadHandler](INFO): Thread cancelled.