2015-09-29T10:17:44.494Z,1443521864.494 [Supervisor](DEBUG): Initializing supervisor.
2015-09-29T10:17:44.497Z,1443521864.497 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-09-29T10:17:44.498Z,1443521864.498 [SyncHandler](INFO): Protected caller Thread ID is 761
2015-09-29T10:17:44.498Z,1443521864.498 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-09-29T10:17:44.500Z,1443521864.500 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-09-29T10:17:44.501Z,1443521864.501 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 762
2015-09-29T10:17:44.503Z,1443521864.503 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-09-29T10:17:44.516Z,1443521864.516 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-09-29T10:17:44.520Z,1443521864.520 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-09-29T10:17:44.521Z,1443521864.521 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 763
2015-09-29T10:17:44.522Z,1443521864.522 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-09-29T10:17:44.524Z,1443521864.524 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-09-29T10:17:44.525Z,1443521864.525 [logger ThreadHandler](INFO): Protected caller Thread ID is 764
2015-09-29T10:17:44.527Z,1443521864.527 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-09-29T10:17:44.527Z,1443521864.527 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-09-29T10:17:44.531Z,1443521864.531 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-09-29T10:17:44.935Z,1443521864.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-09-29T10:17:44.937Z,1443521864.937 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-09-29T10:17:45.129Z,1443521865.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-09-29T10:17:45.131Z,1443521865.131 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-09-29T10:17:45.276Z,1443521865.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-09-29T10:17:45.276Z,1443521865.276 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-09-29T10:17:45.425Z,1443521865.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-09-29T10:17:45.426Z,1443521865.426 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-09-29T10:17:45.522Z,1443521865.522 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-09-29T10:17:45.744Z,1443521865.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-09-29T10:17:45.745Z,1443521865.745 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-09-29T10:17:45.840Z,1443521865.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-09-29T10:17:45.840Z,1443521865.840 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-09-29T10:17:46.161Z,1443521866.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-09-29T10:17:46.162Z,1443521866.162 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-09-29T10:17:46.566Z,1443521866.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-09-29T10:17:46.566Z,1443521866.566 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-09-29T10:17:46.925Z,1443521866.925 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-09-29T10:17:46.927Z,1443521866.927 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-09-29T10:17:47.535Z,1443521867.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-09-29T10:17:47.536Z,1443521867.536 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-09-29T10:17:47.772Z,1443521867.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-09-29T10:17:47.772Z,1443521867.772 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-09-29T10:17:47.885Z,1443521867.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-09-29T10:17:47.886Z,1443521867.886 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-09-29T10:17:48.426Z,1443521868.426 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-09-29T10:17:48.426Z,1443521868.426 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-09-29T10:17:48.553Z,1443521868.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-09-29T10:17:48.555Z,1443521868.555 [Supervisor](INFO): Looking for Config files in directory: Config/(none)/
2015-09-29T10:17:48.556Z,1443521868.556 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2015-09-29T10:17:48.560Z,1443521868.560 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-09-29T10:17:48.634Z,1443521868.634 [VerticalControl](DEBUG): Construct VerticalControl.
2015-09-29T10:17:48.770Z,1443521868.770 [VerticalControl] Loaded
2015-09-29T10:17:48.770Z,1443521868.770 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-09-29T10:17:48.771Z,1443521868.771 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-09-29T10:17:48.856Z,1443521868.856 [HorizontalControl] Loaded
2015-09-29T10:17:48.856Z,1443521868.856 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-09-29T10:17:48.857Z,1443521868.857 [SpeedControl](DEBUG): Construct SpeedControl.
2015-09-29T10:17:48.863Z,1443521868.863 [SpeedControl] Loaded
2015-09-29T10:17:48.863Z,1443521868.863 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-09-29T10:17:48.864Z,1443521868.864 [LoopControl](DEBUG): Construct LoopControl.
2015-09-29T10:17:48.864Z,1443521868.864 [LoopControl] Loaded
2015-09-29T10:17:48.865Z,1443521868.865 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-09-29T10:17:48.865Z,1443521868.865 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-09-29T10:17:48.866Z,1443521868.866 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-09-29T10:17:49.025Z,1443521869.025 [SBIT](DEBUG): Construct Startup Built In Test.
2015-09-29T10:17:49.037Z,1443521869.037 [SBIT] Loaded
2015-09-29T10:17:49.038Z,1443521869.038 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-09-29T10:17:49.038Z,1443521869.038 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-09-29T10:17:49.072Z,1443521869.072 [IBIT] Loaded
2015-09-29T10:17:49.073Z,1443521869.073 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-09-29T10:17:49.075Z,1443521869.075 [CBIT](DEBUG): Construct Continuous Built In Test.
2015-09-29T10:17:49.230Z,1443521869.230 [CBIT] Loaded
2015-09-29T10:17:49.231Z,1443521869.231 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-09-29T10:17:49.231Z,1443521869.231 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-09-29T10:17:49.232Z,1443521869.232 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-09-29T10:17:49.269Z,1443521869.269 [DepthRateCalculator] Loaded
2015-09-29T10:17:49.269Z,1443521869.269 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-09-29T10:17:49.279Z,1443521869.279 [PitchRateCalculator] Loaded
2015-09-29T10:17:49.279Z,1443521869.279 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-09-29T10:17:49.290Z,1443521869.290 [SpeedCalculator] Loaded
2015-09-29T10:17:49.291Z,1443521869.291 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-09-29T10:17:49.308Z,1443521869.308 [TempGradientCalculator] Loaded
2015-09-29T10:17:49.308Z,1443521869.308 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-09-29T10:17:49.330Z,1443521869.330 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2015-09-29T10:17:49.330Z,1443521869.330 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2015-09-29T10:17:49.335Z,1443521869.335 [YawRateCalculator] Loaded
2015-09-29T10:17:49.336Z,1443521869.336 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-09-29T10:17:49.336Z,1443521869.336 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-09-29T10:17:49.337Z,1443521869.337 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-09-29T10:17:49.460Z,1443521869.460 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2015-09-29T10:17:49.461Z,1443521869.461 [StratificationFrontDetector](DEBUG): (re)initializing
2015-09-29T10:17:49.461Z,1443521869.461 [StratificationFrontDetector] Loaded
2015-09-29T10:17:49.461Z,1443521869.461 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2015-09-29T10:17:49.462Z,1443521869.462 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-09-29T10:17:49.462Z,1443521869.462 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-09-29T10:17:49.759Z,1443521869.759 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-09-29T10:17:49.760Z,1443521869.760 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-09-29T10:17:49.891Z,1443521869.891 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-09-29T10:17:49.891Z,1443521869.891 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-09-29T10:17:49.956Z,1443521869.956 [DeadReckonUsingSpeedCalculator] Loaded
2015-09-29T10:17:49.957Z,1443521869.957 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-09-29T10:17:49.974Z,1443521869.974 [NavChart] Loaded
2015-09-29T10:17:49.975Z,1443521869.975 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-09-29T10:17:49.980Z,1443521869.980 [UniversalFixResidualReporter] Loaded
2015-09-29T10:17:49.980Z,1443521869.980 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-09-29T10:17:49.981Z,1443521869.981 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-09-29T10:17:49.981Z,1443521869.981 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-09-29T10:17:49.990Z,1443521869.990 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-09-29T10:17:49.991Z,1443521869.991 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-09-29T10:17:50.290Z,1443521870.290 [CTD_NeilBrown] Loaded
2015-09-29T10:17:50.290Z,1443521870.290 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2015-09-29T10:17:50.292Z,1443521870.292 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4059D4E0
2015-09-29T10:17:50.293Z,1443521870.293 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 810
2015-09-29T10:17:50.306Z,1443521870.306 [ISUS] Loaded
2015-09-29T10:17:50.306Z,1443521870.306 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2015-09-29T10:17:50.325Z,1443521870.325 [PAR_Licor] Loaded
2015-09-29T10:17:50.325Z,1443521870.325 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2015-09-29T10:17:50.381Z,1443521870.381 [WetLabsBB2FL] Loaded
2015-09-29T10:17:50.381Z,1443521870.381 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2015-09-29T10:17:50.384Z,1443521870.384 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405CD4E0
2015-09-29T10:17:50.385Z,1443521870.385 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 811
2015-09-29T10:17:50.385Z,1443521870.385 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-09-29T10:17:50.386Z,1443521870.386 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-09-29T10:17:51.106Z,1443521871.106 [AHRS_sp3003D] Loaded
2015-09-29T10:17:51.106Z,1443521871.106 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2015-09-29T10:17:51.300Z,1443521871.300 [DataOverHttps] Loaded
2015-09-29T10:17:51.300Z,1443521871.300 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-09-29T10:17:51.314Z,1443521871.314 [Depth_Keller] Loaded
2015-09-29T10:17:51.314Z,1443521871.314 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-09-29T10:17:51.319Z,1443521871.319 [DropWeight] Loaded
2015-09-29T10:17:51.319Z,1443521871.319 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2015-09-29T10:17:51.476Z,1443521871.476 [DVL_micro] Loaded
2015-09-29T10:17:51.476Z,1443521871.476 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2015-09-29T10:17:51.585Z,1443521871.585 [NAL9602] Loaded
2015-09-29T10:17:51.585Z,1443521871.585 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-09-29T10:17:51.632Z,1443521871.632 [Onboard] Loaded
2015-09-29T10:17:51.632Z,1443521871.632 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-09-29T10:17:51.644Z,1443521871.644 [Radio_Surface] Loaded
2015-09-29T10:17:51.645Z,1443521871.645 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-09-29T10:17:51.648Z,1443521871.648 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408114E0
2015-09-29T10:17:51.649Z,1443521871.649 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 812
2015-09-29T10:17:51.809Z,1443521871.809 [DAT] Loaded
2015-09-29T10:17:51.809Z,1443521871.809 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2015-09-29T10:17:51.816Z,1443521871.816 [SCPI] Loaded
2015-09-29T10:17:51.816Z,1443521871.816 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2015-09-29T10:17:51.817Z,1443521871.817 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-09-29T10:17:51.817Z,1443521871.817 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-09-29T10:17:51.947Z,1443521871.947 [BuoyancyServo] Loaded
2015-09-29T10:17:51.948Z,1443521871.948 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-09-29T10:17:51.965Z,1443521871.965 [ElevatorServo] Loaded
2015-09-29T10:17:51.965Z,1443521871.965 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-09-29T10:17:51.977Z,1443521871.977 [MassServo] Loaded
2015-09-29T10:17:51.977Z,1443521871.977 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-09-29T10:17:51.989Z,1443521871.989 [RudderServo] Loaded
2015-09-29T10:17:51.989Z,1443521871.989 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-09-29T10:17:52.005Z,1443521872.005 [ThrusterServo] Loaded
2015-09-29T10:17:52.005Z,1443521872.005 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-09-29T10:17:52.006Z,1443521872.006 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-09-29T10:17:52.006Z,1443521872.006 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-09-29T10:17:52.210Z,1443521872.210 [InternalSim] Loaded
2015-09-29T10:17:52.210Z,1443521872.210 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2015-09-29T10:17:52.211Z,1443521872.211 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-09-29T10:17:52.211Z,1443521872.211 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-09-29T10:17:52.255Z,1443521872.255 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-09-29T10:17:52.259Z,1443521872.259 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-09-29T10:17:52.260Z,1443521872.260 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-09-29T10:17:52.271Z,1443521872.271 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-09-29T10:17:52.272Z,1443521872.272 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408BF4E0
2015-09-29T10:17:52.273Z,1443521872.273 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 813
2015-09-29T10:17:52.278Z,1443521872.278 [Supervisor](INFO): Main Thread ID is 760
2015-09-29T10:17:52.278Z,1443521872.278 [Supervisor](DEBUG): Running supervisor.
2015-09-29T10:17:52.278Z,1443521872.278 [CommandLine ThreadHandler](INFO): Handler Thread ID is 814
2015-09-29T10:17:52.285Z,1443521872.285 [controlThread ThreadHandler](INFO): Handler Thread ID is 815
2015-09-29T10:17:52.285Z,1443521872.285 [controlThread](DEBUG): Initializing ControlThread
2015-09-29T10:17:52.286Z,1443521872.286 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-09-29T10:17:52.288Z,1443521872.288 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-09-29T10:17:52.289Z,1443521872.289 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-09-29T10:17:52.289Z,1443521872.289 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-09-29T10:17:52.290Z,1443521872.290 [SBIT](INFO): Initialize SBIT Component.
2015-09-29T10:17:52.290Z,1443521872.290 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12634
2015-09-29T10:17:52.292Z,1443521872.292 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.32-45-generic-pae
Kernel Reported: 2.6.27.8
2015-09-29T10:17:52.292Z,1443521872.292 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #102-Ubuntu SMP Wed Jan 2 22:10:16 UTC 2013
Kernel Reported: #641 PREEMPT Mon Sep 28 16:45:10 PDT 2015
2015-09-29T10:17:52.293Z,1443521872.293 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2015-09-29T10:17:52.293Z,1443521872.293 [IBIT](INFO): Initialize IBIT Component.
2015-09-29T10:17:52.294Z,1443521872.294 [CBIT](DEBUG): Initialize CBIT Component.
2015-09-29T10:17:52.294Z,1443521872.294 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2015-09-29T10:17:52.295Z,1443521872.295 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-09-29T10:17:52.295Z,1443521872.295 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-09-29T10:17:52.295Z,1443521872.295 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-09-29T10:17:52.296Z,1443521872.296 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-09-29T10:17:52.296Z,1443521872.296 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-09-29T10:17:52.297Z,1443521872.297 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2015-09-29T10:17:52.297Z,1443521872.297 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-09-29T10:17:52.298Z,1443521872.298 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-09-29T10:17:52.298Z,1443521872.298 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2015-09-29T10:17:52.299Z,1443521872.299 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2015-09-29T10:17:52.299Z,1443521872.299 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-09-29T10:17:52.300Z,1443521872.300 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2015-09-29T10:17:52.300Z,1443521872.300 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2015-09-29T10:17:52.300Z,1443521872.300 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-09-29T10:17:52.301Z,1443521872.301 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-09-29T10:17:52.306Z,1443521872.306 [InternalSim](DEBUG):  InternalSim initializing...
2015-09-29T10:17:52.329Z,1443521872.329 [logger ThreadHandler](INFO): Handler Thread ID is 816
2015-09-29T10:17:52.369Z,1443521872.369 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 817
2015-09-29T10:17:52.437Z,1443521872.437 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 818
2015-09-29T10:17:52.445Z,1443521872.445 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 819
2015-09-29T10:17:52.515Z,1443521872.515 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 820
2015-09-29T10:17:52.522Z,1443521872.522 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-09-29T10:17:52.524Z,1443521872.524 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-09-29T10:17:52.525Z,1443521872.525 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-09-29T10:17:52.525Z,1443521872.525 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-09-29T10:17:52.525Z,1443521872.525 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-09-29T10:17:52.526Z,1443521872.526 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-09-29T10:17:52.526Z,1443521872.526 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-09-29T10:17:52.526Z,1443521872.526 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-09-29T10:17:52.527Z,1443521872.527 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-09-29T10:17:52.556Z,1443521872.556 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-09-29T10:17:52.622Z,1443521872.622 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-09-29T10:17:52.632Z,1443521872.632 [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>


2015-09-29T10:17:52.649Z,1443521872.649 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-09-29T10:17:52.696Z,1443521872.696 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2015-09-29T10:17:52.697Z,1443521872.697 [Default:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-09-29T10:17:52.747Z,1443521872.747 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-09-29T10:17:52.767Z,1443521872.767 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2015-09-29T10:17:52.789Z,1443521872.789 [Default:D.Execute](DEBUG): Construct Execute.
2015-09-29T10:17:52.801Z,1443521872.801 [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: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>


2015-09-29T10:17:52.807Z,1443521872.807 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,ISUS,PAR_Licor,AHRS_sp3003D,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,SCPI,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-09-29T10:17:53.020Z,1443521873.020 [DVL_micro](INFO): Initializing
2015-09-29T10:17:53.170Z,1443521873.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-09-29T10:17:53.201Z,1443521873.201 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-09-29T10:17:53.305Z,1443521873.305 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-09-29T10:17:53.316Z,1443521873.316 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-09-29T10:17:53.328Z,1443521873.328 [MassServo](DEBUG): Initializing MassServo.
2015-09-29T10:17:53.340Z,1443521873.340 [RudderServo](DEBUG): Initializing RudderServo.
2015-09-29T10:17:53.352Z,1443521873.352 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-09-29T10:17:53.620Z,1443521873.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-09-29T10:17:53.621Z,1443521873.621 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-09-29T10:18:02.688Z,1443521882.688 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:03.003Z,1443521883.003 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:03.143Z,1443521883.143 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:03.543Z,1443521883.543 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003100
2015-09-29T10:18:03.547Z,1443521883.547 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:03.942Z,1443521883.942 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:04.342Z,1443521884.342 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:04.742Z,1443521884.742 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:05.142Z,1443521885.142 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:05.541Z,1443521885.541 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:05.941Z,1443521885.941 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:06.341Z,1443521886.341 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:06.743Z,1443521886.743 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:07.146Z,1443521887.146 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:07.546Z,1443521887.546 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:07.950Z,1443521887.950 [NAL9602](INFO): No messages in MT queue
2015-09-29T10:18:07.951Z,1443521887.951 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:08.347Z,1443521888.347 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:08.754Z,1443521888.754 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:09.147Z,1443521889.147 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:09.546Z,1443521889.546 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:09.947Z,1443521889.947 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:10.341Z,1443521890.341 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:10.741Z,1443521890.741 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:11.141Z,1443521891.141 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:11.541Z,1443521891.541 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:11.942Z,1443521891.942 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:12.341Z,1443521892.341 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:12.741Z,1443521892.741 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:12.775Z,1443521892.775 [SBIT](IMPORTANT): Beginning Startup BIT
2015-09-29T10:18:13.142Z,1443521893.142 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:13.541Z,1443521893.541 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:13.941Z,1443521893.941 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:14.342Z,1443521894.342 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:14.741Z,1443521894.741 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:15.142Z,1443521895.142 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:15.541Z,1443521895.541 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:15.941Z,1443521895.941 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:16.341Z,1443521896.341 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:16.741Z,1443521896.741 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate.
2015-09-29T10:18:17.142Z,1443521897.142 [D