2014-11-13T22:55:27.505Z,1415919327.505 [Supervisor](DEBUG): Initializing supervisor.
2014-11-13T22:55:27.507Z,1415919327.507 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-11-13T22:55:27.508Z,1415919327.508 [SyncHandler](INFO): Protected caller Thread ID is 5237
2014-11-13T22:55:27.508Z,1415919327.508 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-11-13T22:55:27.509Z,1415919327.509 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-11-13T22:55:27.510Z,1415919327.510 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5238
2014-11-13T22:55:27.512Z,1415919327.512 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-11-13T22:55:27.524Z,1415919327.524 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-11-13T22:55:27.525Z,1415919327.525 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-11-13T22:55:27.525Z,1415919327.525 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5239
2014-11-13T22:55:27.526Z,1415919327.526 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-11-13T22:55:27.527Z,1415919327.527 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-11-13T22:55:27.527Z,1415919327.527 [logger ThreadHandler](INFO): Protected caller Thread ID is 5240
2014-11-13T22:55:27.529Z,1415919327.529 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-11-13T22:55:27.530Z,1415919327.530 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-11-13T22:55:27.531Z,1415919327.531 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-11-13T22:55:27.849Z,1415919327.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-11-13T22:55:27.849Z,1415919327.849 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-11-13T22:55:28.003Z,1415919328.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-11-13T22:55:28.004Z,1415919328.004 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-11-13T22:55:28.111Z,1415919328.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-11-13T22:55:28.112Z,1415919328.112 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-11-13T22:55:28.233Z,1415919328.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-11-13T22:55:28.233Z,1415919328.233 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-11-13T22:55:28.318Z,1415919328.318 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-11-13T22:55:28.472Z,1415919328.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-11-13T22:55:28.473Z,1415919328.473 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-11-13T22:55:28.557Z,1415919328.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-11-13T22:55:28.558Z,1415919328.558 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-11-13T22:55:28.760Z,1415919328.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-11-13T22:55:28.760Z,1415919328.760 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-11-13T22:55:29.145Z,1415919329.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-11-13T22:55:29.146Z,1415919329.146 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-11-13T22:55:29.450Z,1415919329.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-11-13T22:55:29.451Z,1415919329.451 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-11-13T22:55:29.935Z,1415919329.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-11-13T22:55:29.936Z,1415919329.936 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-11-13T22:55:30.142Z,1415919330.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-11-13T22:55:30.143Z,1415919330.143 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-11-13T22:55:30.266Z,1415919330.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-11-13T22:55:30.266Z,1415919330.266 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-11-13T22:55:30.675Z,1415919330.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-11-13T22:55:30.676Z,1415919330.676 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-11-13T22:55:30.784Z,1415919330.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-11-13T22:55:30.786Z,1415919330.786 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-11-13T22:55:30.787Z,1415919330.787 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-11-13T22:55:30.890Z,1415919330.890 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-11-13T22:55:31.004Z,1415919331.004 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-11-13T22:55:31.100Z,1415919331.100 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-11-13T22:55:31.209Z,1415919331.209 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-11-13T22:55:31.304Z,1415919331.304 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-11-13T22:55:31.453Z,1415919331.453 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-11-13T22:55:31.604Z,1415919331.604 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-11-13T22:55:31.703Z,1415919331.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-11-13T22:55:31.792Z,1415919331.792 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-11-13T22:55:31.872Z,1415919331.872 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-11-13T22:55:31.971Z,1415919331.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-11-13T22:55:32.081Z,1415919332.081 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2014-11-13T22:55:32.081Z,1415919332.081 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-11-13T22:55:32.087Z,1415919332.087 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-11-13T22:55:32.135Z,1415919332.135 [VerticalControl](DEBUG): Construct VerticalControl.
2014-11-13T22:55:32.250Z,1415919332.250 [VerticalControl] Loaded
2014-11-13T22:55:32.250Z,1415919332.250 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-11-13T22:55:32.251Z,1415919332.251 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-11-13T22:55:32.320Z,1415919332.320 [HorizontalControl] Loaded
2014-11-13T22:55:32.321Z,1415919332.321 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-11-13T22:55:32.321Z,1415919332.321 [SpeedControl](DEBUG): Construct SpeedControl.
2014-11-13T22:55:32.327Z,1415919332.327 [SpeedControl] Loaded
2014-11-13T22:55:32.327Z,1415919332.327 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-11-13T22:55:32.328Z,1415919332.328 [LoopControl](DEBUG): Construct LoopControl.
2014-11-13T22:55:32.328Z,1415919332.328 [LoopControl] Loaded
2014-11-13T22:55:32.328Z,1415919332.328 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-11-13T22:55:32.329Z,1415919332.329 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-11-13T22:55:32.329Z,1415919332.329 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-11-13T22:55:32.423Z,1415919332.423 [SBIT](DEBUG): Construct Startup Built In Test.
2014-11-13T22:55:32.434Z,1415919332.434 [SBIT] Loaded
2014-11-13T22:55:32.434Z,1415919332.434 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-11-13T22:55:32.435Z,1415919332.435 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-11-13T22:55:32.485Z,1415919332.485 [IBIT] Loaded
2014-11-13T22:55:32.485Z,1415919332.485 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-11-13T22:55:32.487Z,1415919332.487 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-11-13T22:55:32.603Z,1415919332.603 [CBIT] Loaded
2014-11-13T22:55:32.604Z,1415919332.604 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-11-13T22:55:32.604Z,1415919332.604 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-11-13T22:55:32.605Z,1415919332.605 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-11-13T22:55:32.653Z,1415919332.653 [DepthRateCalculator] Loaded
2014-11-13T22:55:32.653Z,1415919332.653 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-11-13T22:55:32.658Z,1415919332.658 [PitchRateCalculator] Loaded
2014-11-13T22:55:32.659Z,1415919332.659 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-11-13T22:55:32.682Z,1415919332.682 [SpeedCalculator] Loaded
2014-11-13T22:55:32.682Z,1415919332.682 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-11-13T22:55:32.722Z,1415919332.722 [TempGradientCalculator] Loaded
2014-11-13T22:55:32.723Z,1415919332.723 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-11-13T22:55:32.728Z,1415919332.728 [YawRateCalculator] Loaded
2014-11-13T22:55:32.728Z,1415919332.728 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-11-13T22:55:32.728Z,1415919332.728 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-11-13T22:55:32.729Z,1415919332.729 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-11-13T22:55:32.828Z,1415919332.828 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-11-13T22:55:32.841Z,1415919332.841 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-11-13T22:55:33.318Z,1415919333.318 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-11-13T22:55:33.319Z,1415919333.319 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-11-13T22:55:33.479Z,1415919333.479 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-11-13T22:55:33.479Z,1415919333.479 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-11-13T22:55:33.586Z,1415919333.586 [DeadReckonUsingSpeedCalculator] Loaded
2014-11-13T22:55:33.586Z,1415919333.586 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2014-11-13T22:55:33.679Z,1415919333.679 [DeadReckonWithRespectToWater] Loaded
2014-11-13T22:55:33.679Z,1415919333.679 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-11-13T22:55:33.793Z,1415919333.793 [DeadReckonWithRespectToSeafloor] Loaded
2014-11-13T22:55:33.793Z,1415919333.793 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-11-13T22:55:33.892Z,1415919333.892 [DeadReckonUsingDVLWaterTrack] Loaded
2014-11-13T22:55:33.893Z,1415919333.893 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-11-13T22:55:34.002Z,1415919334.002 [DeadReckonUsingCompactModelForecast] Loaded
2014-11-13T22:55:34.002Z,1415919334.002 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-11-13T22:55:34.042Z,1415919334.042 [NavChart] Loaded
2014-11-13T22:55:34.043Z,1415919334.043 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-11-13T22:55:34.047Z,1415919334.047 [UniversalFixResidualReporter] Loaded
2014-11-13T22:55:34.047Z,1415919334.047 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-11-13T22:55:34.048Z,1415919334.048 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-11-13T22:55:34.048Z,1415919334.048 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-11-13T22:55:34.054Z,1415919334.054 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-11-13T22:55:34.067Z,1415919334.067 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-11-13T22:55:34.359Z,1415919334.359 [Aanderaa_O2] Loaded
2014-11-13T22:55:34.359Z,1415919334.359 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2014-11-13T22:55:34.435Z,1415919334.435 [CTD_NeilBrown] Loaded
2014-11-13T22:55:34.435Z,1415919334.435 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-11-13T22:55:34.443Z,1415919334.443 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4056B4E0
2014-11-13T22:55:34.444Z,1415919334.444 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5322
2014-11-13T22:55:34.497Z,1415919334.497 [PAR_Licor] Loaded
2014-11-13T22:55:34.498Z,1415919334.498 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-11-13T22:55:34.573Z,1415919334.573 [WetLabsBB2FL] Loaded
2014-11-13T22:55:34.574Z,1415919334.574 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-11-13T22:55:34.583Z,1415919334.583 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4059B4E0
2014-11-13T22:55:34.584Z,1415919334.584 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5323
2014-11-13T22:55:34.584Z,1415919334.584 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-11-13T22:55:34.585Z,1415919334.585 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-11-13T22:55:35.245Z,1415919335.245 [AHRS_sp3003D] Loaded
2014-11-13T22:55:35.245Z,1415919335.245 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-11-13T22:55:35.273Z,1415919335.273 [Depth_Keller] Loaded
2014-11-13T22:55:35.274Z,1415919335.274 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-11-13T22:55:35.278Z,1415919335.278 [DropWeight] Loaded
2014-11-13T22:55:35.279Z,1415919335.279 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-11-13T22:55:35.479Z,1415919335.479 [NAL9602] Loaded
2014-11-13T22:55:35.479Z,1415919335.479 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-11-13T22:55:35.569Z,1415919335.569 [Onboard] Loaded
2014-11-13T22:55:35.570Z,1415919335.570 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-11-13T22:55:35.577Z,1415919335.577 [Radio_Freewave] Loaded
2014-11-13T22:55:35.577Z,1415919335.577 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-11-13T22:55:35.585Z,1415919335.585 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 407EA4E0
2014-11-13T22:55:35.585Z,1415919335.585 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 5324
2014-11-13T22:55:35.592Z,1415919335.592 [Radio_Surface] Loaded
2014-11-13T22:55:35.592Z,1415919335.592 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2014-11-13T22:55:35.601Z,1415919335.601 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4081A4E0
2014-11-13T22:55:35.601Z,1415919335.601 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5325
2014-11-13T22:55:35.864Z,1415919335.864 [DAT] Loaded
2014-11-13T22:55:35.864Z,1415919335.864 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2014-11-13T22:55:35.877Z,1415919335.877 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-11-13T22:55:35.878Z,1415919335.878 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-11-13T22:55:36.064Z,1415919336.064 [BuoyancyServo] Loaded
2014-11-13T22:55:36.064Z,1415919336.064 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-11-13T22:55:36.088Z,1415919336.088 [ElevatorServo] Loaded
2014-11-13T22:55:36.089Z,1415919336.089 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-11-13T22:55:36.112Z,1415919336.112 [MassServo] Loaded
2014-11-13T22:55:36.113Z,1415919336.113 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-11-13T22:55:36.136Z,1415919336.136 [RudderServo] Loaded
2014-11-13T22:55:36.137Z,1415919336.137 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-11-13T22:55:36.160Z,1415919336.160 [ThrusterServo] Loaded
2014-11-13T22:55:36.160Z,1415919336.160 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-11-13T22:55:36.161Z,1415919336.161 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-11-13T22:55:36.161Z,1415919336.161 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-11-13T22:55:36.675Z,1415919336.675 [ExternalSim] Loaded
2014-11-13T22:55:36.675Z,1415919336.675 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2014-11-13T22:55:36.675Z,1415919336.675 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-11-13T22:55:36.676Z,1415919336.676 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-11-13T22:55:36.739Z,1415919336.739 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-11-13T22:55:36.741Z,1415919336.741 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-11-13T22:55:36.742Z,1415919336.742 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-11-13T22:55:36.748Z,1415919336.748 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-11-13T22:55:36.755Z,1415919336.755 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408CB4E0
2014-11-13T22:55:36.755Z,1415919336.755 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5326
2014-11-13T22:55:36.762Z,1415919336.762 [Supervisor](INFO): Main Thread ID is 5236
2014-11-13T22:55:36.762Z,1415919336.762 [Supervisor](DEBUG): Running supervisor.
2014-11-13T22:55:36.763Z,1415919336.763 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5327
2014-11-13T22:55:36.779Z,1415919336.779 [controlThread ThreadHandler](INFO): Handler Thread ID is 5328
2014-11-13T22:55:36.780Z,1415919336.780 [controlThread](DEBUG): Initializing ControlThread
2014-11-13T22:55:36.780Z,1415919336.780 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-11-13T22:55:36.782Z,1415919336.782 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-11-13T22:55:36.783Z,1415919336.783 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-11-13T22:55:36.783Z,1415919336.783 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-11-13T22:55:36.784Z,1415919336.784 [SBIT](INFO): Initialize SBIT Component.
2014-11-13T22:55:36.784Z,1415919336.784 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11881
2014-11-13T22:55:36.784Z,1415919336.784 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-11-13T22:55:36.785Z,1415919336.785 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-11-13T22:55:36.785Z,1415919336.785 [IBIT](INFO): Initialize IBIT Component.
2014-11-13T22:55:36.786Z,1415919336.786 [CBIT](DEBUG): Initialize CBIT Component.
2014-11-13T22:55:36.786Z,1415919336.786 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-11-13T22:55:36.799Z,1415919336.799 [logger ThreadHandler](INFO): Handler Thread ID is 5329
2014-11-13T22:55:36.811Z,1415919336.811 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-11-13T22:55:36.811Z,1415919336.811 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-11-13T22:55:36.812Z,1415919336.812 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-11-13T22:55:36.812Z,1415919336.812 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-11-13T22:55:36.813Z,1415919336.813 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-11-13T22:55:36.813Z,1415919336.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-11-13T22:55:36.813Z,1415919336.813 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2014-11-13T22:55:36.814Z,1415919336.814 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-11-13T22:55:36.814Z,1415919336.814 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-11-13T22:55:36.815Z,1415919336.815 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-11-13T22:55:36.815Z,1415919336.815 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-11-13T22:55:36.816Z,1415919336.816 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-11-13T22:55:36.816Z,1415919336.816 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2014-11-13T22:55:36.822Z,1415919336.822 [ExternalSim](INFO): ExternalSim initializing...
2014-11-13T22:55:36.855Z,1415919336.855 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5330
2014-11-13T22:55:36.856Z,1415919336.856 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-11-13T22:55:36.868Z,1415919336.868 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5332
2014-11-13T22:55:36.869Z,1415919336.869 [WetLabsBB2FL](INFO): Powering down
2014-11-13T22:55:36.874Z,1415919336.874 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-11-13T22:55:36.920Z,1415919336.920 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 5333
2014-11-13T22:55:36.957Z,1415919336.957 [ExternalSim](ERROR): Error making socket connection to simulation server at tethyscode.shore.mbari.org: Could not connect due to error: No route to host
2014-11-13T22:55:36.980Z,1415919336.980 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5335
2014-11-13T22:55:37.008Z,1415919337.008 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5336
2014-11-13T22:55:37.011Z,1415919337.011 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-11-13T22:55:37.012Z,1415919337.012 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-11-13T22:55:37.012Z,1415919337.012 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-11-13T22:55:37.012Z,1415919337.012 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-11-13T22:55:37.013Z,1415919337.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-11-13T22:55:37.013Z,1415919337.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-11-13T22:55:37.013Z,1415919337.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-11-13T22:55:37.013Z,1415919337.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-11-13T22:55:37.014Z,1415919337.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-11-13T22:55:37.143Z,1415919337.143 [ExternalSim](DEBUG): beaconLat = 36.80340
2014-11-13T22:55:37.143Z,1415919337.143 [ExternalSim](DEBUG): beaconLon = -121.82230
2014-11-13T22:55:37.159Z,1415919337.159 [ExternalSim](DEBUG): beaconDepth = 25.00
2014-11-13T22:55:37.162Z,1415919337.162 [ExternalSim](DEBUG): Simulator initialized
2014-11-13T22:55:37.182Z,1415919337.182 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-11-13T22:55:37.262Z,1415919337.262 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-11-13T22:55:37.344Z,1415919337.344 [Radio_Freewave](INFO): Powering up
2014-11-13T22:55:37.359Z,1415919337.359 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2014-11-13T22:55:37.360Z,1415919337.360 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-11-13T22:55:37.499Z,1415919337.499 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-11-13T22:55:37.503Z,1415919337.503 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-11-13T22:55:37.527Z,1415919337.527 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-11-13T22:55:37.564Z,1415919337.564 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-11-13T22:55:37.594Z,1415919337.594 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-11-13T22:55:37.626Z,1415919337.626 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-11-13T22:55:37.656Z,1415919337.656 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

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

    <Aggregate Id="StartClock">

        <Sequence/>

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

    </Aggregate>

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

    <Aggregate Id="CheckIn">

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

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

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

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

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

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="UpdateAndReportMinutesSinceMissionStarted">

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

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

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

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2014-11-13T22:55:37.662Z,1415919337.662 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,Aanderaa_O2,PAR_Licor,AHRS_sp3003D,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-11-13T22:55:37.861Z,1415919337.861 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-11-13T22:55:37.892Z,1415919337.892 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2014-11-13T22:55:37.989Z,1415919337.989 [DAT](INFO): Powering up
2014-11-13T22:55:37.990Z,1415919337.990 [DAT](DEBUG): Initializing DAT.
2014-11-13T22:55:38.039Z,1415919338.039 [DepthRateCalculator](ERROR): Depth measurement is not active
2014-11-13T22:55:38.121Z,1415919338.121 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:55:38.124Z,1415919338.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:38.150Z,1415919338.150 [Radio_Freewave](IMPORTANT): Starting PPPD with command:/sbin/pppd nocrtscts /dev/ttyS1 noauth 115200 10.1.1.2:10.1.1.1 persist maxfail 0
2014-11-13T22:55:38.554Z,1415919338.554 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:55:38.579Z,1415919338.579 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-11-13T22:55:38.598Z,1415919338.598 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:55:38.606Z,1415919338.606 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-11-13T22:55:38.612Z,1415919338.612 [MassServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:55:38.618Z,1415919338.618 [MassServo](DEBUG): Initializing MassServo.
2014-11-13T22:55:38.640Z,1415919338.640 [RudderServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:55:38.646Z,1415919338.646 [RudderServo](DEBUG): Initializing RudderServo.
2014-11-13T22:55:38.663Z,1415919338.663 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:55:38.670Z,1415919338.670 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-11-13T22:55:39.049Z,1415919339.049 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:55:39.050Z,1415919339.050 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:39.415Z,1415919339.415 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2014-11-13T22:55:39.415Z,1415919339.415 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2014-11-13T22:55:39.415Z,1415919339.415 [BuoyancyServo] Communications Fault, FailCount= 1
2014-11-13T22:55:39.415Z,1415919339.415 [BuoyancyServo](ERROR): Communications Fault
2014-11-13T22:55:39.633Z,1415919339.633 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2014-11-13T22:55:40.035Z,1415919340.035 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:55:40.036Z,1415919340.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:40.062Z,1415919340.062 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-11-13T22:55:40.063Z,1415919340.063 [BuoyancyServo](INFO): Powering down
2014-11-13T22:55:40.592Z,1415919340.592 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:55:40.593Z,1415919340.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:41.096Z,1415919341.096 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:55:41.097Z,1415919341.097 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:41.238Z,1415919341.238 [Aanderaa_O2](INFO): Powering down
2014-11-13T22:55:41.710Z,1415919341.710 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:55:41.711Z,1415919341.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:42.187Z,1415919342.187 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:55:42.188Z,1415919342.188 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:42.310Z,1415919342.310 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2014-11-13T22:55:42.310Z,1415919342.310 [BuoyancyServo] No Fault, FailCount= 1
2014-11-13T22:55:42.672Z,1415919342.672 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:55:42.673Z,1415919342.673 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:42.699Z,1415919342.699 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-11-13T22:55:42.817Z,1415919342.817 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-11-13T22:55:43.261Z,1415919343.261 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2014-11-13T22:55:43.262Z,1415919343.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:43.860Z,1415919343.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:44.404Z,1415919344.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:44.864Z,1415919344.864 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:45.305Z,1415919345.305 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:45.829Z,1415919345.829 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:46.391Z,1415919346.391 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:46.851Z,1415919346.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:47.428Z,1415919347.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:48.053Z,1415919348.053 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:48.525Z,1415919348.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:48.974Z,1415919348.974 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:49.415Z,1415919349.415 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:49.860Z,1415919349.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:50.500Z,1415919350.500 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:50.975Z,1415919350.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:51.412Z,1415919351.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:51.879Z,1415919351.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:52.342Z,1415919352.342 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:52.497Z,1415919352.497 [SBIT](IMPORTANT): Beginning Startup BIT
2014-11-13T22:55:52.512Z,1415919352.512 [CBIT](IMPORTANT): Beginning GF scan
2014-11-13T22:55:52.887Z,1415919352.887 [DAT](DEBUG): 
2014-11-13T22:55:52.888Z,1415919352.888 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2014-11-13T22:55:52.888Z,1415919352.888 [DAT](DEBUG): MF Frequency Band
2014-11-13T22:55:52.889Z,1415919352.889 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2014-11-13T22:55:52.919Z,1415919352.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:53.390Z,1415919353.390 [DAT](DEBUG): Nov 13 2014 23:55:26 
2014-11-13T22:55:53.411Z,1415919353.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:53.864Z,1415919353.864 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:54.297Z,1415919354.297 [DAT](INFO): entering command mode
2014-11-13T22:55:54.328Z,1415919354.328 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:54.860Z,1415919354.860 [DAT](DEBUG): checking for command mode acknowledgment
2014-11-13T22:55:54.861Z,1415919354.861 [DAT](DEBUG): read user prompt 1: user:1>
2014-11-13T22:55:54.861Z,1415919354.861 [DAT](INFO): command mode acknowledged
2014-11-13T22:55:54.872Z,1415919354.872 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:55.714Z,1415919355.714 [DAT](INFO): setting local address to 2
2014-11-13T22:55:55.729Z,1415919355.729 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:56.188Z,1415919356.188 [DAT](DEBUG): checking for local address setting acknowledgment
2014-11-13T22:55:56.189Z,1415919356.189 [DAT](INFO): set local address to 2
2014-11-13T22:55:56.195Z,1415919356.195 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:56.682Z,1415919356.682 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:57.108Z,1415919357.108 [CommandLine](IMPORTANT): got command quit
2014-11-13T22:55:57.131Z,1415919357.131 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:57.596Z,1415919357.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:58.115Z,1415919358.115 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:55:58.115Z,1415919358.115 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:55:58.174Z,1415919358.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:58.306Z,1415919358.306 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-11-13T22:55:58.307Z,1415919358.307 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:55:58.307Z,1415919358.307 [CommandLine](INFO): Join timeout helper Thread ID is 5351
2014-11-13T22:55:58.313Z,1415919358.313 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-11-13T22:55:58.313Z,1415919358.313 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:55:58.313Z,1415919358.313 [NavChartDb](INFO): Join timeout helper Thread ID is 5352
2014-11-13T22:55:58.673Z,1415919358.673 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:59.187Z,1415919359.187 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:55:59.683Z,1415919359.683 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:56:00.217Z,1415919360.217 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:56:00.650Z,1415919360.650 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:56:00.650Z,1415919360.650 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:00.652Z,1415919360.652 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2014-11-13T22:56:00.652Z,1415919360.652 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:00.652Z,1415919360.652 [Radio_Surface](INFO): Join timeout helper Thread ID is 5353
2014-11-13T22:56:00.687Z,1415919360.687 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:56:00.804Z,1415919360.804 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:56:00.804Z,1415919360.804 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:00.805Z,1415919360.805 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-11-13T22:56:00.805Z,1415919360.805 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:00.805Z,1415919360.805 [Radio_Freewave](INFO): Join timeout helper Thread ID is 5354
2014-11-13T22:56:01.145Z,1415919361.145 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:56:01.184Z,1415919361.184 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:56:01.316Z,1415919361.316 [Radio_Freewave](INFO): Powering down
2014-11-13T22:56:01.666Z,1415919361.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:56:01.738Z,1415919361.738 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:01.745Z,1415919361.745 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-11-13T22:56:01.745Z,1415919361.745 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:01.746Z,1415919361.746 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5356
2014-11-13T22:56:02.002Z,1415919362.002 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:56:02.002Z,1415919362.002 [WetLabsBB2FL](INFO): Powering down
2014-11-13T22:56:02.003Z,1415919362.003 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.007Z,1415919362.007 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-11-13T22:56:02.007Z,1415919362.007 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.007Z,1415919362.007 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5357
2014-11-13T22:56:02.014Z,1415919362.014 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:56:02.014Z,1415919362.014 [CTD_NeilBrown](INFO): Powering down
2014-11-13T22:56:02.015Z,1415919362.015 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.027Z,1415919362.027 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-11-13T22:56:02.027Z,1415919362.027 [logger ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.027Z,1415919362.027 [logger](INFO): Join timeout helper Thread ID is 5358
2014-11-13T22:56:02.039Z,1415919362.039 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:56:02.039Z,1415919362.039 [logger ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.055Z,1415919362.055 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-11-13T22:56:02.055Z,1415919362.055 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.055Z,1415919362.055 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-11-13T22:56:02.055Z,1415919362.055 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.055Z,1415919362.055 [controlThread](INFO): Join timeout helper Thread ID is 5359
2014-11-13T22:56:02.151Z,1415919362.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-11-13T22:56:02.264Z,1415919362.264 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-11-13T22:56:02.264Z,1415919362.264 [controlThread](DEBUG): Uninitializing ControlThread
2014-11-13T22:56:02.264Z,1415919362.264 [Aanderaa_O2](INFO): Powering down
2014-11-13T22:56:02.265Z,1415919362.265 [AHRS_sp3003D](INFO): Powering down
2014-11-13T22:56:02.266Z,1415919362.266 [NAL9602](INFO): Powering down
2014-11-13T22:56:02.267Z,1415919362.267 [DAT](INFO): Powering down
2014-11-13T22:56:02.288Z,1415919362.288 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-11-13T22:56:02.290Z,1415919362.290 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-11-13T22:56:02.295Z,1415919362.295 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-11-13T22:56:02.295Z,1415919362.295 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-11-13T22:56:02.295Z,1415919362.295 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-11-13T22:56:02.296Z,1415919362.296 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-11-13T22:56:02.296Z,1415919362.296 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-11-13T22:56:02.296Z,1415919362.296 [BuoyancyServo](INFO): Powering down
2014-11-13T22:56:02.310Z,1415919362.310 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-11-13T22:56:02.310Z,1415919362.310 [ElevatorServo](INFO): Powering down
2014-11-13T22:56:02.311Z,1415919362.311 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-11-13T22:56:02.311Z,1415919362.311 [MassServo](INFO): Powering down
2014-11-13T22:56:02.312Z,1415919362.312 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-11-13T22:56:02.312Z,1415919362.312 [RudderServo](INFO): Powering down
2014-11-13T22:56:02.313Z,1415919362.313 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-11-13T22:56:02.313Z,1415919362.313 [ThrusterServo](INFO): Powering down
2014-11-13T22:56:02.314Z,1415919362.314 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-11-13T22:56:02.315Z,1415919362.315 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-11-13T22:56:02.315Z,1415919362.315 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-11-13T22:56:02.360Z,1415919362.360 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.421Z,1415919362.421 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.425Z,1415919362.425 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.435Z,1415919362.435 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.436Z,1415919362.436 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.714Z,1415919362.714 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-11-13T22:56:02.750Z,1415919362.750 [logger ThreadHandler](INFO): Thread cancelled.