2015-09-29T10:33:39.644Z,1443522819.644 [Supervisor](DEBUG): Initializing supervisor. 2015-09-29T10:33:39.647Z,1443522819.647 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0 2015-09-29T10:33:39.647Z,1443522819.647 [SyncHandler](INFO): Protected caller Thread ID is 762 2015-09-29T10:33:39.648Z,1443522819.648 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2015-09-29T10:33:39.650Z,1443522819.650 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0 2015-09-29T10:33:39.651Z,1443522819.651 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 763 2015-09-29T10:33:39.653Z,1443522819.653 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2015-09-29T10:33:39.666Z,1443522819.666 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2015-09-29T10:33:39.670Z,1443522819.670 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0 2015-09-29T10:33:39.671Z,1443522819.671 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 764 2015-09-29T10:33:39.671Z,1443522819.671 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2015-09-29T10:33:39.674Z,1443522819.674 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0 2015-09-29T10:33:39.675Z,1443522819.675 [logger ThreadHandler](INFO): Protected caller Thread ID is 765 2015-09-29T10:33:39.677Z,1443522819.677 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2015-09-29T10:33:39.677Z,1443522819.677 [Supervisor](INFO): Looking for Config files in directory: Config/ 2015-09-29T10:33:39.681Z,1443522819.681 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2015-09-29T10:33:40.093Z,1443522820.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2015-09-29T10:33:40.095Z,1443522820.095 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2015-09-29T10:33:40.287Z,1443522820.287 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2015-09-29T10:33:40.289Z,1443522820.289 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2015-09-29T10:33:40.435Z,1443522820.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2015-09-29T10:33:40.436Z,1443522820.436 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2015-09-29T10:33:40.587Z,1443522820.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2015-09-29T10:33:40.588Z,1443522820.588 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2015-09-29T10:33:40.687Z,1443522820.687 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2015-09-29T10:33:40.915Z,1443522820.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2015-09-29T10:33:40.916Z,1443522820.916 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2015-09-29T10:33:41.015Z,1443522821.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2015-09-29T10:33:41.016Z,1443522821.016 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2015-09-29T10:33:41.339Z,1443522821.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2015-09-29T10:33:41.340Z,1443522821.340 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2015-09-29T10:33:41.751Z,1443522821.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2015-09-29T10:33:41.752Z,1443522821.752 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2015-09-29T10:33:42.117Z,1443522822.117 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2015-09-29T10:33:42.121Z,1443522822.121 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2015-09-29T10:33:42.730Z,1443522822.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2015-09-29T10:33:42.731Z,1443522822.731 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2015-09-29T10:33:42.968Z,1443522822.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2015-09-29T10:33:42.968Z,1443522822.968 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2015-09-29T10:33:43.087Z,1443522823.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2015-09-29T10:33:43.088Z,1443522823.088 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2015-09-29T10:33:43.631Z,1443522823.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2015-09-29T10:33:43.632Z,1443522823.632 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2015-09-29T10:33:43.759Z,1443522823.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2015-09-29T10:33:43.761Z,1443522823.761 [Supervisor](INFO): Looking for Config files in directory: Config/(none)/ 2015-09-29T10:33:43.761Z,1443522823.761 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2015-09-29T10:33:43.766Z,1443522823.766 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2015-09-29T10:33:43.841Z,1443522823.841 [VerticalControl](DEBUG): Construct VerticalControl. 2015-09-29T10:33:43.975Z,1443522823.975 [VerticalControl] Loaded 2015-09-29T10:33:43.976Z,1443522823.976 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2015-09-29T10:33:43.976Z,1443522823.976 [HorizontalControl](DEBUG): Construct HorizontalControl. 2015-09-29T10:33:44.058Z,1443522824.058 [HorizontalControl] Loaded 2015-09-29T10:33:44.058Z,1443522824.058 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2015-09-29T10:33:44.059Z,1443522824.059 [SpeedControl](DEBUG): Construct SpeedControl. 2015-09-29T10:33:44.065Z,1443522824.065 [SpeedControl] Loaded 2015-09-29T10:33:44.065Z,1443522824.065 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2015-09-29T10:33:44.066Z,1443522824.066 [LoopControl](DEBUG): Construct LoopControl. 2015-09-29T10:33:44.070Z,1443522824.070 [LoopControl] Loaded 2015-09-29T10:33:44.071Z,1443522824.071 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2015-09-29T10:33:44.071Z,1443522824.071 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2015-09-29T10:33:44.072Z,1443522824.072 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2015-09-29T10:33:44.233Z,1443522824.233 [SBIT](DEBUG): Construct Startup Built In Test. 2015-09-29T10:33:44.246Z,1443522824.246 [SBIT] Loaded 2015-09-29T10:33:44.246Z,1443522824.246 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2015-09-29T10:33:44.247Z,1443522824.247 [IBIT](DEBUG): Construct Initiated Built In Test. 2015-09-29T10:33:44.281Z,1443522824.281 [IBIT] Loaded 2015-09-29T10:33:44.281Z,1443522824.281 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2015-09-29T10:33:44.283Z,1443522824.283 [CBIT](DEBUG): Construct Continuous Built In Test. 2015-09-29T10:33:44.439Z,1443522824.439 [CBIT] Loaded 2015-09-29T10:33:44.439Z,1443522824.439 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2015-09-29T10:33:44.439Z,1443522824.439 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2015-09-29T10:33:44.440Z,1443522824.440 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2015-09-29T10:33:44.481Z,1443522824.481 [DepthRateCalculator] Loaded 2015-09-29T10:33:44.482Z,1443522824.482 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2015-09-29T10:33:44.487Z,1443522824.487 [PitchRateCalculator] Loaded 2015-09-29T10:33:44.487Z,1443522824.487 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2015-09-29T10:33:44.499Z,1443522824.499 [SpeedCalculator] Loaded 2015-09-29T10:33:44.500Z,1443522824.500 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2015-09-29T10:33:44.521Z,1443522824.521 [TempGradientCalculator] Loaded 2015-09-29T10:33:44.521Z,1443522824.521 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2015-09-29T10:33:44.539Z,1443522824.539 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2015-09-29T10:33:44.539Z,1443522824.539 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2015-09-29T10:33:44.545Z,1443522824.545 [YawRateCalculator] Loaded 2015-09-29T10:33:44.545Z,1443522824.545 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2015-09-29T10:33:44.546Z,1443522824.546 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2015-09-29T10:33:44.546Z,1443522824.546 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2015-09-29T10:33:44.672Z,1443522824.672 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2015-09-29T10:33:44.672Z,1443522824.672 [StratificationFrontDetector](DEBUG): (re)initializing 2015-09-29T10:33:44.672Z,1443522824.672 [StratificationFrontDetector] Loaded 2015-09-29T10:33:44.673Z,1443522824.673 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2015-09-29T10:33:44.673Z,1443522824.673 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2015-09-29T10:33:44.674Z,1443522824.674 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2015-09-29T10:33:44.966Z,1443522824.966 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2015-09-29T10:33:44.967Z,1443522824.967 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2015-09-29T10:33:45.096Z,1443522825.096 [DeadReckonUsingMultipleVelocitySources] Loaded 2015-09-29T10:33:45.097Z,1443522825.097 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2015-09-29T10:33:45.162Z,1443522825.162 [DeadReckonUsingSpeedCalculator] Loaded 2015-09-29T10:33:45.162Z,1443522825.162 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2015-09-29T10:33:45.180Z,1443522825.180 [NavChart] Loaded 2015-09-29T10:33:45.180Z,1443522825.180 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2015-09-29T10:33:45.186Z,1443522825.186 [UniversalFixResidualReporter] Loaded 2015-09-29T10:33:45.186Z,1443522825.186 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2015-09-29T10:33:45.186Z,1443522825.186 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2015-09-29T10:33:45.187Z,1443522825.187 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2015-09-29T10:33:45.196Z,1443522825.196 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2015-09-29T10:33:45.197Z,1443522825.197 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2015-09-29T10:33:45.496Z,1443522825.496 [CTD_NeilBrown] Loaded 2015-09-29T10:33:45.496Z,1443522825.496 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2015-09-29T10:33:45.497Z,1443522825.497 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4059D4E0 2015-09-29T10:33:45.498Z,1443522825.498 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 811 2015-09-29T10:33:45.511Z,1443522825.511 [ISUS] Loaded 2015-09-29T10:33:45.511Z,1443522825.511 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread. 2015-09-29T10:33:45.526Z,1443522825.526 [PAR_Licor] Loaded 2015-09-29T10:33:45.526Z,1443522825.526 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2015-09-29T10:33:45.578Z,1443522825.578 [WetLabsBB2FL] Loaded 2015-09-29T10:33:45.578Z,1443522825.578 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2015-09-29T10:33:45.579Z,1443522825.579 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405CD4E0 2015-09-29T10:33:45.580Z,1443522825.580 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 812 2015-09-29T10:33:45.580Z,1443522825.580 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2015-09-29T10:33:45.581Z,1443522825.581 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2015-09-29T10:33:46.229Z,1443522826.229 [AHRS_sp3003D] Loaded 2015-09-29T10:33:46.229Z,1443522826.229 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2015-09-29T10:33:46.409Z,1443522826.409 [DataOverHttps] Loaded 2015-09-29T10:33:46.409Z,1443522826.409 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2015-09-29T10:33:46.423Z,1443522826.423 [Depth_Keller] Loaded 2015-09-29T10:33:46.423Z,1443522826.423 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2015-09-29T10:33:46.428Z,1443522826.428 [DropWeight] Loaded 2015-09-29T10:33:46.429Z,1443522826.429 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2015-09-29T10:33:46.570Z,1443522826.570 [DVL_micro] Loaded 2015-09-29T10:33:46.570Z,1443522826.570 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2015-09-29T10:33:46.667Z,1443522826.667 [NAL9602] Loaded 2015-09-29T10:33:46.667Z,1443522826.667 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2015-09-29T10:33:46.711Z,1443522826.711 [Onboard] Loaded 2015-09-29T10:33:46.711Z,1443522826.711 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2015-09-29T10:33:46.723Z,1443522826.723 [Radio_Surface] Loaded 2015-09-29T10:33:46.723Z,1443522826.723 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2015-09-29T10:33:46.724Z,1443522826.724 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408114E0 2015-09-29T10:33:46.724Z,1443522826.724 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 813 2015-09-29T10:33:46.868Z,1443522826.868 [DAT] Loaded 2015-09-29T10:33:46.868Z,1443522826.868 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2015-09-29T10:33:46.875Z,1443522826.875 [SCPI] Loaded 2015-09-29T10:33:46.875Z,1443522826.875 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2015-09-29T10:33:46.876Z,1443522826.876 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2015-09-29T10:33:46.877Z,1443522826.877 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2015-09-29T10:33:46.998Z,1443522826.998 [BuoyancyServo] Loaded 2015-09-29T10:33:46.999Z,1443522826.999 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2015-09-29T10:33:47.012Z,1443522827.012 [ElevatorServo] Loaded 2015-09-29T10:33:47.012Z,1443522827.012 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2015-09-29T10:33:47.024Z,1443522827.024 [MassServo] Loaded 2015-09-29T10:33:47.024Z,1443522827.024 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2015-09-29T10:33:47.036Z,1443522827.036 [RudderServo] Loaded 2015-09-29T10:33:47.036Z,1443522827.036 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2015-09-29T10:33:47.048Z,1443522827.048 [ThrusterServo] Loaded 2015-09-29T10:33:47.048Z,1443522827.048 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2015-09-29T10:33:47.048Z,1443522827.048 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2015-09-29T10:33:47.049Z,1443522827.049 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2015-09-29T10:33:47.233Z,1443522827.233 [InternalSim] Loaded 2015-09-29T10:33:47.233Z,1443522827.233 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2015-09-29T10:33:47.234Z,1443522827.234 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2015-09-29T10:33:47.234Z,1443522827.234 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2015-09-29T10:33:47.274Z,1443522827.274 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2015-09-29T10:33:47.278Z,1443522827.278 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2015-09-29T10:33:47.279Z,1443522827.279 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2015-09-29T10:33:47.285Z,1443522827.285 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2015-09-29T10:33:47.287Z,1443522827.287 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408BF4E0 2015-09-29T10:33:47.287Z,1443522827.287 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 814 2015-09-29T10:33:47.292Z,1443522827.292 [Supervisor](INFO): Main Thread ID is 761 2015-09-29T10:33:47.292Z,1443522827.292 [Supervisor](DEBUG): Running supervisor. 2015-09-29T10:33:47.293Z,1443522827.293 [CommandLine ThreadHandler](INFO): Handler Thread ID is 815 2015-09-29T10:33:47.295Z,1443522827.295 [controlThread ThreadHandler](INFO): Handler Thread ID is 816 2015-09-29T10:33:47.296Z,1443522827.296 [controlThread](DEBUG): Initializing ControlThread 2015-09-29T10:33:47.297Z,1443522827.297 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2015-09-29T10:33:47.299Z,1443522827.299 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2015-09-29T10:33:47.299Z,1443522827.299 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2015-09-29T10:33:47.300Z,1443522827.300 [LoopControl](DEBUG): Initialize LoopControlComponent. 2015-09-29T10:33:47.300Z,1443522827.300 [SBIT](INFO): Initialize SBIT Component. 2015-09-29T10:33:47.301Z,1443522827.301 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12634 2015-09-29T10:33:47.302Z,1443522827.302 [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:33:47.303Z,1443522827.303 [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:33:47.304Z,1443522827.304 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2015-09-29T10:33:47.304Z,1443522827.304 [IBIT](INFO): Initialize IBIT Component. 2015-09-29T10:33:47.305Z,1443522827.305 [CBIT](DEBUG): Initialize CBIT Component. 2015-09-29T10:33:47.305Z,1443522827.305 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2015-09-29T10:33:47.305Z,1443522827.305 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2015-09-29T10:33:47.306Z,1443522827.306 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2015-09-29T10:33:47.306Z,1443522827.306 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2015-09-29T10:33:47.306Z,1443522827.306 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2015-09-29T10:33:47.307Z,1443522827.307 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2015-09-29T10:33:47.307Z,1443522827.307 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2015-09-29T10:33:47.308Z,1443522827.308 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2015-09-29T10:33:47.309Z,1443522827.309 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2015-09-29T10:33:47.309Z,1443522827.309 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2015-09-29T10:33:47.310Z,1443522827.310 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2015-09-29T10:33:47.310Z,1443522827.310 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2015-09-29T10:33:47.311Z,1443522827.311 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2015-09-29T10:33:47.311Z,1443522827.311 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2015-09-29T10:33:47.311Z,1443522827.311 [NavChart](DEBUG): Initialize NavChart Navigation. 2015-09-29T10:33:47.312Z,1443522827.312 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2015-09-29T10:33:47.317Z,1443522827.317 [InternalSim](DEBUG): InternalSim initializing... 2015-09-29T10:33:47.335Z,1443522827.335 [logger ThreadHandler](INFO): Handler Thread ID is 817 2015-09-29T10:33:47.375Z,1443522827.375 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 818 2015-09-29T10:33:47.431Z,1443522827.431 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 819 2015-09-29T10:33:47.440Z,1443522827.440 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 820 2015-09-29T10:33:47.496Z,1443522827.496 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 821 2015-09-29T10:33:47.504Z,1443522827.504 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2015-09-29T10:33:47.506Z,1443522827.506 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2015-09-29T10:33:47.507Z,1443522827.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2015-09-29T10:33:47.507Z,1443522827.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2015-09-29T10:33:47.507Z,1443522827.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2015-09-29T10:33:47.507Z,1443522827.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2015-09-29T10:33:47.507Z,1443522827.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2015-09-29T10:33:47.508Z,1443522827.508 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2015-09-29T10:33:47.508Z,1443522827.508 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2015-09-29T10:33:47.511Z,1443522827.511 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2015-09-29T10:33:47.557Z,1443522827.557 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2015-09-29T10:33:47.601Z,1443522827.601 [MissionManager](DEBUG): 2015-09-29T10:33:47.602Z,1443522827.602 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2015-09-29T10:33:47.671Z,1443522827.671 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2015-09-29T10:33:47.672Z,1443522827.672 [Default:A.GoToSurface](DEBUG): Construct GoToSurface. 2015-09-29T10:33:47.726Z,1443522827.726 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2015-09-29T10:33:47.729Z,1443522827.729 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2015-09-29T10:33:47.756Z,1443522827.756 [Default:D.Execute](DEBUG): Construct Execute. 2015-09-29T10:33:47.760Z,1443522827.760 [MissionManager](DEBUG): 0 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2015-09-29T10:33:47.766Z,1443522827.766 [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:33:47.958Z,1443522827.958 [DVL_micro](INFO): Initializing 2015-09-29T10:33:48.119Z,1443522828.119 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-09-29T10:33:48.130Z,1443522828.130 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-09-29T10:33:48.240Z,1443522828.240 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2015-09-29T10:33:48.255Z,1443522828.255 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2015-09-29T10:33:48.270Z,1443522828.270 [MassServo](DEBUG): Initializing MassServo. 2015-09-29T10:33:48.284Z,1443522828.284 [RudderServo](DEBUG): Initializing RudderServo. 2015-09-29T10:33:48.295Z,1443522828.295 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2015-09-29T10:33:48.520Z,1443522828.520 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-09-29T10:33:48.521Z,1443522828.521 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-09-29T10:33:57.305Z,1443522837.305 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate. 2015-09-29T10:33:57.649Z,1443522837.649 [DAT](ERROR): Could not read simulated DAT measurements from SimSlate. 2015-09-29T10:33:57.794Z,1443522837.794 [DAT](ERROR): Could not read simulated DAT measurements from SimS