2017-03-14T19:20:47.287Z,1489519247.287 [Supervisor](DEBUG): Initializing supervisor.
2017-03-14T19:20:47.290Z,1489519247.290 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-03-14T19:20:47.290Z,1489519247.290 [SyncHandler](INFO): Protected caller Thread ID is 769
2017-03-14T19:20:47.291Z,1489519247.291 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-03-14T19:20:47.292Z,1489519247.292 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-03-14T19:20:47.292Z,1489519247.292 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770
2017-03-14T19:20:47.295Z,1489519247.295 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-03-14T19:20:47.307Z,1489519247.307 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-03-14T19:20:47.308Z,1489519247.309 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-03-14T19:20:47.309Z,1489519247.309 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771
2017-03-14T19:20:47.310Z,1489519247.309 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-03-14T19:20:47.311Z,1489519247.311 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-03-14T19:20:47.311Z,1489519247.311 [logger ThreadHandler](INFO): Protected caller Thread ID is 772
2017-03-14T19:20:47.313Z,1489519247.313 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-03-14T19:20:47.313Z,1489519247.313 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-03-14T19:20:47.318Z,1489519247.318 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-03-14T19:20:47.407Z,1489519247.407 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-03-14T19:20:47.744Z,1489519247.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-03-14T19:20:47.745Z,1489519247.745 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-03-14T19:20:47.869Z,1489519247.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-03-14T19:20:47.870Z,1489519247.870 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-03-14T19:20:48.065Z,1489519248.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-03-14T19:20:48.066Z,1489519248.066 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-03-14T19:20:48.166Z,1489519248.166 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-03-14T19:20:48.169Z,1489519248.168 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-03-14T19:20:48.402Z,1489519248.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-03-14T19:20:48.405Z,1489519248.405 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-03-14T19:20:48.882Z,1489519248.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-03-14T19:20:48.883Z,1489519248.883 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-03-14T19:20:49.161Z,1489519249.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-03-14T19:20:49.162Z,1489519249.162 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-03-14T19:20:49.249Z,1489519249.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-03-14T19:20:49.249Z,1489519249.249 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-03-14T19:20:49.381Z,1489519249.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-03-14T19:20:49.381Z,1489519249.381 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-03-14T19:20:49.580Z,1489519249.579 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-03-14T19:20:49.580Z,1489519249.580 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-03-14T19:20:50.034Z,1489519250.034 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-03-14T19:20:50.034Z,1489519250.034 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-03-14T19:20:50.540Z,1489519250.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-03-14T19:20:50.541Z,1489519250.541 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-03-14T19:20:56.581Z,1489519256.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-03-14T19:20:56.582Z,1489519256.582 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-03-14T19:20:57.285Z,1489519257.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-03-14T19:20:57.287Z,1489519257.287 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/
2017-03-14T19:20:57.291Z,1489519257.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg
2017-03-14T19:20:57.380Z,1489519257.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg
2017-03-14T19:20:57.549Z,1489519257.549 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg
2017-03-14T19:20:59.738Z,1489519259.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-03-14T19:20:59.739Z,1489519259.739 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg
2017-03-14T19:20:59.838Z,1489519259.838 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg
2017-03-14T19:20:59.940Z,1489519259.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg
2017-03-14T19:21:00.047Z,1489519260.047 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg
2017-03-14T19:21:00.191Z,1489519260.191 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg
2017-03-14T19:21:00.353Z,1489519260.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg
2017-03-14T19:21:00.463Z,1489519260.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg
2017-03-14T19:21:00.553Z,1489519260.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg
2017-03-14T19:21:00.654Z,1489519260.654 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/root/
2017-03-14T19:21:00.655Z,1489519260.655 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-03-14T19:21:00.668Z,1489519260.668 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-03-14T19:21:00.708Z,1489519260.708 [DepthRateCalculator] Loaded
2017-03-14T19:21:00.709Z,1489519260.709 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-03-14T19:21:00.715Z,1489519260.715 [PitchRateCalculator] Loaded
2017-03-14T19:21:00.715Z,1489519260.715 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-03-14T19:21:00.727Z,1489519260.727 [SpeedCalculator] Loaded
2017-03-14T19:21:00.727Z,1489519260.727 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-03-14T19:21:00.744Z,1489519260.744 [TempGradientCalculator] Loaded
2017-03-14T19:21:00.744Z,1489519260.744 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-03-14T19:21:00.760Z,1489519260.760 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-03-14T19:21:00.761Z,1489519260.761 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-03-14T19:21:00.767Z,1489519260.767 [YawRateCalculator] Loaded
2017-03-14T19:21:00.767Z,1489519260.767 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-03-14T19:21:00.768Z,1489519260.768 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-03-14T19:21:00.768Z,1489519260.768 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-03-14T19:21:00.891Z,1489519260.891 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-03-14T19:21:00.892Z,1489519260.892 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-03-14T19:21:00.946Z,1489519260.946 [DeadReckonUsingSpeedCalculator] Loaded
2017-03-14T19:21:00.946Z,1489519260.946 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-03-14T19:21:01.001Z,1489519261.001 [DeadReckonWithRespectToWater] Loaded
2017-03-14T19:21:01.001Z,1489519261.001 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2017-03-14T19:21:01.057Z,1489519261.057 [DeadReckonWithRespectToSeafloor] Loaded
2017-03-14T19:21:01.057Z,1489519261.057 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2017-03-14T19:21:01.117Z,1489519261.117 [DeadReckonUsingDVLWaterTrack] Loaded
2017-03-14T19:21:01.117Z,1489519261.117 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2017-03-14T19:21:01.134Z,1489519261.134 [NavChart] Loaded
2017-03-14T19:21:01.134Z,1489519261.134 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-03-14T19:21:01.138Z,1489519261.138 [UniversalFixResidualReporter] Loaded
2017-03-14T19:21:01.139Z,1489519261.139 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-03-14T19:21:01.139Z,1489519261.139 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-03-14T19:21:01.140Z,1489519261.140 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-03-14T19:21:01.315Z,1489519261.315 [Aanderaa_O2] Loaded
2017-03-14T19:21:01.315Z,1489519261.315 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-03-14T19:21:01.396Z,1489519261.396 [CTD_NeilBrown] Loaded
2017-03-14T19:21:01.396Z,1489519261.396 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-03-14T19:21:01.397Z,1489519261.397 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2017-03-14T19:21:01.398Z,1489519261.398 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 853
2017-03-14T19:21:01.413Z,1489519261.413 [PAR_Licor] Loaded
2017-03-14T19:21:01.413Z,1489519261.413 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-03-14T19:21:01.465Z,1489519261.465 [WetLabsBB2FL] Loaded
2017-03-14T19:21:01.465Z,1489519261.465 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-03-14T19:21:01.466Z,1489519261.466 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2017-03-14T19:21:01.466Z,1489519261.466 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 854
2017-03-14T19:21:01.467Z,1489519261.467 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-03-14T19:21:01.468Z,1489519261.468 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-03-14T19:21:01.508Z,1489519261.508 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-03-14T19:21:01.509Z,1489519261.509 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-03-14T19:21:01.518Z,1489519261.518 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-03-14T19:21:01.519Z,1489519261.519 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-03-14T19:21:01.898Z,1489519261.898 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-03-14T19:21:01.898Z,1489519261.898 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-03-14T19:21:02.056Z,1489519262.056 [SBIT](DEBUG): Construct Startup Built In Test.
2017-03-14T19:21:02.087Z,1489519262.087 [SBIT] Loaded
2017-03-14T19:21:02.088Z,1489519262.088 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-03-14T19:21:02.088Z,1489519262.088 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-03-14T19:21:02.117Z,1489519262.118 [IBIT] Loaded
2017-03-14T19:21:02.118Z,1489519262.118 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-03-14T19:21:02.121Z,1489519262.121 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-03-14T19:21:02.276Z,1489519262.276 [CBIT] Loaded
2017-03-14T19:21:02.277Z,1489519262.277 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-03-14T19:21:02.277Z,1489519262.277 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-03-14T19:21:02.278Z,1489519262.278 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-03-14T19:21:02.897Z,1489519262.898 [DataOverHttps] Loaded
2017-03-14T19:21:02.898Z,1489519262.898 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-03-14T19:21:02.912Z,1489519262.912 [Depth_Keller] Loaded
2017-03-14T19:21:02.912Z,1489519262.912 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-03-14T19:21:02.917Z,1489519262.917 [DropWeight] Loaded
2017-03-14T19:21:02.917Z,1489519262.917 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-03-14T19:21:03.010Z,1489519263.010 [NAL9602] Loaded
2017-03-14T19:21:03.010Z,1489519263.010 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-03-14T19:21:03.020Z,1489519263.020 [Onboard] Loaded
2017-03-14T19:21:03.020Z,1489519263.020 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-03-14T19:21:03.028Z,1489519263.028 [Radio_Surface] Loaded
2017-03-14T19:21:03.028Z,1489519263.028 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-03-14T19:21:03.029Z,1489519263.029 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409864E0
2017-03-14T19:21:03.029Z,1489519263.029 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 855
2017-03-14T19:21:03.036Z,1489519263.036 [SCPI] Loaded
2017-03-14T19:21:03.036Z,1489519263.036 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2017-03-14T19:21:03.232Z,1489519263.232 [PNI_TCM] Loaded
2017-03-14T19:21:03.233Z,1489519263.233 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-03-14T19:21:03.591Z,1489519263.591 [Rowe_600LCM] Loaded
2017-03-14T19:21:03.592Z,1489519263.592 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-03-14T19:21:03.593Z,1489519263.593 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409B64E0
2017-03-14T19:21:03.593Z,1489519263.593 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 856
2017-03-14T19:21:05.440Z,1489519265.440 [BPC1] Loaded
2017-03-14T19:21:05.441Z,1489519265.441 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-03-14T19:21:05.441Z,1489519265.441 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-03-14T19:21:05.442Z,1489519265.442 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-03-14T19:21:05.571Z,1489519265.571 [BuoyancyServo] Loaded
2017-03-14T19:21:05.571Z,1489519265.571 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-03-14T19:21:05.588Z,1489519265.588 [ElevatorServo] Loaded
2017-03-14T19:21:05.588Z,1489519265.588 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-03-14T19:21:05.603Z,1489519265.603 [MassServo] Loaded
2017-03-14T19:21:05.604Z,1489519265.604 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-03-14T19:21:05.619Z,1489519265.619 [RudderServo] Loaded
2017-03-14T19:21:05.620Z,1489519265.620 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-03-14T19:21:05.635Z,1489519265.635 [ThrusterServo] Loaded
2017-03-14T19:21:05.635Z,1489519265.635 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-03-14T19:21:05.636Z,1489519265.636 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-03-14T19:21:05.637Z,1489519265.637 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-03-14T19:21:05.708Z,1489519265.708 [VerticalControl](DEBUG): Construct VerticalControl.
2017-03-14T19:21:05.801Z,1489519265.801 [VerticalControl] Loaded
2017-03-14T19:21:05.801Z,1489519265.801 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-03-14T19:21:05.802Z,1489519265.802 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-03-14T19:21:05.863Z,1489519265.863 [HorizontalControl] Loaded
2017-03-14T19:21:05.863Z,1489519265.863 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-03-14T19:21:05.864Z,1489519265.864 [SpeedControl](DEBUG): Construct SpeedControl.
2017-03-14T19:21:05.866Z,1489519265.866 [SpeedControl] Loaded
2017-03-14T19:21:05.866Z,1489519265.866 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-03-14T19:21:05.867Z,1489519265.867 [LoopControl](DEBUG): Construct LoopControl.
2017-03-14T19:21:05.867Z,1489519265.867 [LoopControl] Loaded
2017-03-14T19:21:05.868Z,1489519265.868 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-03-14T19:21:05.868Z,1489519265.868 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-03-14T19:21:05.869Z,1489519265.869 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-03-14T19:21:05.985Z,1489519265.985 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-03-14T19:21:05.985Z,1489519265.985 [StratificationFrontDetector](DEBUG): (re)initializing
2017-03-14T19:21:05.986Z,1489519265.986 [StratificationFrontDetector] Loaded
2017-03-14T19:21:05.986Z,1489519265.986 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-03-14T19:21:05.987Z,1489519265.987 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-03-14T19:21:05.987Z,1489519265.987 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-03-14T19:21:06.121Z,1489519266.121 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-03-14T19:21:06.125Z,1489519266.125 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-03-14T19:21:06.125Z,1489519266.125 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-03-14T19:21:06.133Z,1489519266.133 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-03-14T19:21:06.134Z,1489519266.134 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AC04E0
2017-03-14T19:21:06.134Z,1489519266.134 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 857
2017-03-14T19:21:06.139Z,1489519266.139 [Supervisor](INFO): Main Thread ID is 764
2017-03-14T19:21:06.139Z,1489519266.139 [Supervisor](DEBUG): Running supervisor.
2017-03-14T19:21:06.140Z,1489519266.140 [CommandLine ThreadHandler](INFO): Handler Thread ID is 858
2017-03-14T19:21:06.144Z,1489519266.144 [CommandLine](IMPORTANT): got command quit
2017-03-14T19:21:06.149Z,1489519266.149 [controlThread ThreadHandler](INFO): Handler Thread ID is 859
2017-03-14T19:21:06.149Z,1489519266.149 [controlThread](DEBUG): Initializing ControlThread
2017-03-14T19:21:06.150Z,1489519266.150 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-03-14T19:21:06.151Z,1489519266.151 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-03-14T19:21:06.151Z,1489519266.151 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-03-14T19:21:06.151Z,1489519266.151 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-03-14T19:21:06.152Z,1489519266.152 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-03-14T19:21:06.153Z,1489519266.153 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-03-14T19:21:06.153Z,1489519266.153 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-03-14T19:21:06.154Z,1489519266.154 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-03-14T19:21:06.154Z,1489519266.155 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-03-14T19:21:06.155Z,1489519266.155 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-03-14T19:21:06.155Z,1489519266.155 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-03-14T19:21:06.156Z,1489519266.156 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-03-14T19:21:06.156Z,1489519266.156 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2017-03-14T19:21:06.157Z,1489519266.157 [DeadReckonWithRespectToWater](INFO): Will consider orientation measurement stale after 120s.
2017-03-14T19:21:06.157Z,1489519266.157 [DeadReckonWithRespectToWater](INFO): Will consider velocity measurement stale after 20s.
2017-03-14T19:21:06.157Z,1489519266.158 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-03-14T19:21:06.158Z,1489519266.158 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-03-14T19:21:06.158Z,1489519266.158 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-03-14T19:21:06.159Z,1489519266.159 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2017-03-14T19:21:06.159Z,1489519266.159 [DeadReckonUsingDVLWaterTrack](INFO): Will consider orientation measurement stale after 120s.
2017-03-14T19:21:06.160Z,1489519266.159 [DeadReckonUsingDVLWaterTrack](INFO): Will consider velocity measurement stale after 20s.
2017-03-14T19:21:06.160Z,1489519266.160 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-03-14T19:21:06.160Z,1489519266.160 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-03-14T19:21:06.163Z,1489519266.164 [SBIT](INFO): Initialize SBIT Component.
2017-03-14T19:21:06.164Z,1489519266.164 [SBIT](IMPORTANT): git: 2016-12-14-22-g8c5365e
2017-03-14T19:21:06.164Z,1489519266.164 [SBIT](INFO): git hash: 8c5365e731cd7f7f852e0f64ae8b2bffb9c70498
2017-03-14T19:21:06.166Z,1489519266.166 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2017-03-14T19:21:06.166Z,1489519266.166 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016
2017-03-14T19:21:06.167Z,1489519266.167 [SBIT](INFO): Beginning SBIT in 79.000000 seconds.
2017-03-14T19:21:06.168Z,1489519266.168 [IBIT](INFO): Initialize IBIT Component.
2017-03-14T19:21:06.169Z,1489519266.169 [CBIT](DEBUG): Initialize CBIT Component.
2017-03-14T19:21:06.169Z,1489519266.169 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-03-14T19:21:06.170Z,1489519266.170 [logger ThreadHandler](INFO): Handler Thread ID is 860
2017-03-14T19:21:06.188Z,1489519266.188 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 861
2017-03-14T19:21:06.196Z,1489519266.196 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 862
2017-03-14T19:21:06.197Z,1489519266.197 [WetLabsBB2FL](INFO): Powering down
2017-03-14T19:21:06.216Z,1489519266.216 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 863
2017-03-14T19:21:06.228Z,1489519266.227 [Radio_Surface](INFO): Powering up
2017-03-14T19:21:06.234Z,1489519266.234 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 864
2017-03-14T19:21:06.235Z,1489519266.235 [Rowe_600LCM](INFO): Initializing
2017-03-14T19:21:06.236Z,1489519266.236 [Rowe_600LCM](INFO): Checking LCM
2017-03-14T19:21:06.236Z,1489519266.236 [Rowe_600LCM](INFO): LCM OK
2017-03-14T19:21:06.236Z,1489519266.236 [Rowe_600LCM](INFO): Powering up
2017-03-14T19:21:06.248Z,1489519266.248 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 865
2017-03-14T19:21:06.260Z,1489519266.260 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-03-14T19:21:06.263Z,1489519266.263 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-03-14T19:21:06.265Z,1489519266.265 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-03-14T19:21:06.268Z,1489519266.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-03-14T19:21:06.268Z,1489519266.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-03-14T19:21:06.268Z,1489519266.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-03-14T19:21:06.268Z,1489519266.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-03-14T19:21:06.269Z,1489519266.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-03-14T19:21:06.269Z,1489519266.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-03-14T19:21:06.269Z,1489519266.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-03-14T19:21:06.269Z,1489519266.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-03-14T19:21:06.269Z,1489519266.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-03-14T19:21:06.269Z,1489519266.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-03-14T19:21:06.270Z,1489519266.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-03-14T19:21:06.270Z,1489519266.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-03-14T19:21:06.270Z,1489519266.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-03-14T19:21:06.270Z,1489519266.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-03-14T19:21:06.270Z,1489519266.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-03-14T19:21:06.271Z,1489519266.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-03-14T19:21:06.272Z,1489519266.272 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-03-14T19:21:06.274Z,1489519266.274 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-03-14T19:21:06.332Z,1489519266.332 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-03-14T19:21:06.351Z,1489519266.351 [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://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.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>


2017-03-14T19:21:06.380Z,1489519266.379 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-03-14T19:21:06.457Z,1489519266.457 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-03-14T19:21:06.458Z,1489519266.458 [Default:A.Wait](DEBUG): Construct Wait.
2017-03-14T19:21:06.468Z,1489519266.468 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-03-14T19:21:06.494Z,1489519266.494 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-03-14T19:21:06.497Z,1489519266.497 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-03-14T19:21:06.512Z,1489519266.512 [Default:E.Execute](DEBUG): Construct Execute.
2017-03-14T19:21:06.532Z,1489519266.532 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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://okeanids.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

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

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

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

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2017-03-14T19:21:06.538Z,1489519266.538 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,SCPI,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-03-14T19:21:07.036Z,1489519267.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.036Z,1489519267.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-03-14T19:21:07.150Z,1489519267.150 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-03-14T19:21:07.150Z,1489519267.151 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:07.196Z,1489519267.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:07.207Z,1489519267.207 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.219Z,1489519267.219 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.239Z,1489519267.239 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.246Z,1489519267.246 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.307Z,1489519267.307 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-03-14T19:21:07.307Z,1489519267.307 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:07.308Z,1489519267.308 [CommandLine](INFO): Join timeout helper Thread ID is 868
2017-03-14T19:21:07.330Z,1489519267.330 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-03-14T19:21:07.331Z,1489519267.331 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:07.343Z,1489519267.343 [NavChartDb](INFO): Join timeout helper Thread ID is 869
2017-03-14T19:21:07.364Z,1489519267.364 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-03-14T19:21:07.371Z,1489519267.372 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-03-14T19:21:07.378Z,1489519267.378 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-03-14T19:21:07.384Z,1489519267.384 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-03-14T19:21:07.390Z,1489519267.390 [MassServo](DEBUG): Initializing EZServoServo.
2017-03-14T19:21:07.396Z,1489519267.395 [MassServo](DEBUG): Initializing MassServo.
2017-03-14T19:21:07.412Z,1489519267.412 [RudderServo](DEBUG): Initializing EZServoServo.
2017-03-14T19:21:07.420Z,1489519267.420 [RudderServo](DEBUG): Initializing RudderServo.
2017-03-14T19:21:07.437Z,1489519267.437 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-03-14T19:21:07.440Z,1489519267.440 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-03-14T19:21:07.677Z,1489519267.677 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2017-03-14T19:21:07.677Z,1489519267.677 [DropWeight] Hardware Fault, FailCount= 1
2017-03-14T19:21:07.677Z,1489519267.677 [DropWeight](ERROR): Hardware Fault
2017-03-14T19:21:07.756Z,1489519267.756 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.757Z,1489519267.757 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-03-14T19:21:07.758Z,1489519267.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:07.758Z,1489519267.758 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.780Z,1489519267.780 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.781Z,1489519267.781 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.782Z,1489519267.782 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.825Z,1489519267.825 [CommandLine](FAULT): Scheduling is paused
2017-03-14T19:21:07.844Z,1489519267.844 [CBIT](ERROR): Hardware Fault in component: DropWeight
2017-03-14T19:21:07.844Z,1489519267.844 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2017-03-14T19:21:07.969Z,1489519267.969 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.969Z,1489519267.969 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-03-14T19:21:07.970Z,1489519267.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:07.971Z,1489519267.971 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.979Z,1489519267.979 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.980Z,1489519267.980 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:07.981Z,1489519267.981 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:08.319Z,1489519268.319 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-03-14T19:21:08.319Z,1489519268.319 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-03-14T19:21:08.320Z,1489519268.320 [BuoyancyServo] Communications Fault, FailCount= 1
2017-03-14T19:21:08.320Z,1489519268.320 [BuoyancyServo](ERROR): Communications Fault
2017-03-14T19:21:08.531Z,1489519268.531 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-03-14T19:21:08.565Z,1489519268.565 [NAL9602](INFO): Powering up NAL9602
2017-03-14T19:21:08.627Z,1489519268.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:08.627Z,1489519268.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-03-14T19:21:08.628Z,1489519268.628 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:08.629Z,1489519268.629 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:08.630Z,1489519268.630 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:08.631Z,1489519268.631 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:08.643Z,1489519268.643 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:08.654Z,1489519268.654 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-03-14T19:21:08.655Z,1489519268.655 [BuoyancyServo](INFO): Powering down
2017-03-14T19:21:08.999Z,1489519268.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:08.999Z,1489519268.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-03-14T19:21:08.000Z,1489519269.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:09.007Z,1489519269.007 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.008Z,1489519269.008 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.009Z,1489519269.009 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.010Z,1489519269.010 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.375Z,1489519269.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.375Z,1489519269.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-03-14T19:21:09.376Z,1489519269.376 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:09.383Z,1489519269.383 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.384Z,1489519269.384 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.385Z,1489519269.385 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.386Z,1489519269.386 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.775Z,1489519269.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.775Z,1489519269.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-03-14T19:21:09.776Z,1489519269.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:09.783Z,1489519269.783 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.784Z,1489519269.784 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.785Z,1489519269.785 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:09.786Z,1489519269.786 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:10.175Z,1489519270.175 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:10.175Z,1489519270.175 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-03-14T19:21:10.176Z,1489519270.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:10.183Z,1489519270.183 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:10.184Z,1489519270.184 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:10.185Z,1489519270.185 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:10.186Z,1489519270.186 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:10.493Z,1489519270.493 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-03-14T19:21:10.593Z,1489519270.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:10.593Z,1489519270.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-03-14T19:21:10.594Z,1489519270.594 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:10.595Z,1489519270.595 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:10.596Z,1489519270.596 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:10.597Z,1489519270.597 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:10.598Z,1489519270.598 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:10.606Z,1489519270.606 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-03-14T19:21:10.991Z,1489519270.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.007Z,1489519271.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-03-14T19:21:11.008Z,1489519271.008 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:11.009Z,1489519271.009 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.010Z,1489519271.010 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.011Z,1489519271.011 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.028Z,1489519271.028 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.188Z,1489519271.188 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-03-14T19:21:11.188Z,1489519271.188 [BuoyancyServo] No Fault, FailCount= 1
2017-03-14T19:21:11.376Z,1489519271.376 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-03-14T19:21:11.377Z,1489519271.377 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-03-14T19:21:11.378Z,1489519271.378 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-03-14T19:21:11.396Z,1489519271.396 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.396Z,1489519271.396 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-03-14T19:21:11.397Z,1489519271.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:11.398Z,1489519271.398 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.399Z,1489519271.399 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.408Z,1489519271.408 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.409Z,1489519271.409 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.432Z,1489519271.432 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-03-14T19:21:11.433Z,1489519271.432 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-03-14T19:21:11.784Z,1489519271.784 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.785Z,1489519271.785 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:11.786Z,1489519271.786 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.787Z,1489519271.787 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.796Z,1489519271.796 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:11.797Z,1489519271.797 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.192Z,1489519272.192 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.193Z,1489519272.193 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:12.194Z,1489519272.194 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.195Z,1489519272.195 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.211Z,1489519272.211 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.212Z,1489519272.212 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.586Z,1489519272.586 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.587Z,1489519272.587 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:12.599Z,1489519272.599 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.600Z,1489519272.600 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.601Z,1489519272.601 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.602Z,1489519272.602 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.975Z,1489519272.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.976Z,1489519272.976 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:12.977Z,1489519272.977 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.978Z,1489519272.978 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.979Z,1489519272.979 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:12.992Z,1489519272.991 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:13.334Z,1489519273.334 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-03-14T19:21:13.334Z,1489519273.334 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.352Z,1489519273.352 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2017-03-14T19:21:13.352Z,1489519273.352 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.353Z,1489519273.353 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 880
2017-03-14T19:21:13.380Z,1489519273.379 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2017-03-14T19:21:13.380Z,1489519273.380 [Rowe_600LCM](INFO): Powering down
2017-03-14T19:21:13.390Z,1489519273.390 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.391Z,1489519273.391 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-03-14T19:21:13.391Z,1489519273.391 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.392Z,1489519273.392 [Radio_Surface](INFO): Join timeout helper Thread ID is 881
2017-03-14T19:21:13.403Z,1489519273.403 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:13.404Z,1489519273.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-03-14T19:21:13.405Z,1489519273.405 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:13.406Z,1489519273.406 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:13.407Z,1489519273.407 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:13.408Z,1489519273.408 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2017-03-14T19:21:13.507Z,1489519273.507 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-03-14T19:21:13.508Z,1489519273.508 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.511Z,1489519273.511 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-03-14T19:21:13.511Z,1489519273.511 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.512Z,1489519273.512 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 882
2017-03-14T19:21:13.512Z,1489519273.512 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-03-14T19:21:13.512Z,1489519273.513 [WetLabsBB2FL](INFO): Powering down
2017-03-14T19:21:13.513Z,1489519273.513 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.514Z,1489519273.514 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-03-14T19:21:13.514Z,1489519273.514 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.515Z,1489519273.514 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 883
2017-03-14T19:21:13.536Z,1489519273.536 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-03-14T19:21:13.536Z,1489519273.536 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.540Z,1489519273.540 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-03-14T19:21:13.540Z,1489519273.540 [logger ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.541Z,1489519273.541 [logger](INFO): Join timeout helper Thread ID is 884
2017-03-14T19:21:13.561Z,1489519273.561 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-03-14T19:21:13.562Z,1489519273.562 [logger ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.572Z,1489519273.572 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-03-14T19:21:13.572Z,1489519273.572 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.572Z,1489519273.572 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-03-14T19:21:13.572Z,1489519273.572 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.573Z,1489519273.573 [controlThread](INFO): Join timeout helper Thread ID is 885
2017-03-14T19:21:13.735Z,1489519273.735 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-03-14T19:21:13.736Z,1489519273.736 [controlThread](DEBUG): Uninitializing ControlThread
2017-03-14T19:21:13.736Z,1489519273.736 [Aanderaa_O2](INFO): Powering down
2017-03-14T19:21:13.761Z,1489519273.761 [NAL9602](INFO): Powering down
2017-03-14T19:21:13.781Z,1489519273.781 [PNI_TCM](INFO): Powering down
2017-03-14T19:21:13.801Z,1489519273.801 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-03-14T19:21:13.803Z,1489519273.803 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-03-14T19:21:13.806Z,1489519273.806 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-03-14T19:21:13.807Z,1489519273.807 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-03-14T19:21:13.807Z,1489519273.807 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-03-14T19:21:13.807Z,1489519273.807 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-03-14T19:21:13.808Z,1489519273.808 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-03-14T19:21:13.809Z,1489519273.809 [BuoyancyServo](INFO): Powering down
2017-03-14T19:21:13.823Z,1489519273.823 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-03-14T19:21:13.824Z,1489519273.824 [ElevatorServo](INFO): Powering down
2017-03-14T19:21:13.844Z,1489519273.844 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-03-14T19:21:13.844Z,1489519273.844 [MassServo](INFO): Powering down
2017-03-14T19:21:13.862Z,1489519273.862 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-03-14T19:21:13.863Z,1489519273.862 [RudderServo](INFO): Powering down
2017-03-14T19:21:13.864Z,1489519273.864 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-03-14T19:21:13.864Z,1489519273.864 [ThrusterServo](INFO): Powering down
2017-03-14T19:21:13.865Z,1489519273.865 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-03-14T19:21:13.865Z,1489519273.865 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-03-14T19:21:13.865Z,1489519273.865 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-03-14T19:21:13.866Z,1489519273.866 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.891Z,1489519273.891 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.896Z,1489519273.896 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:13.987Z,1489519273.987 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:14.014Z,1489519274.014 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:14.067Z,1489519274.067 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-03-14T19:21:14.117Z,1489519274.117 [logger ThreadHandler](INFO): Thread cancelled.