2016-11-02T19:09:16.260Z,1478113756.260 [Supervisor](DEBUG): Initializing supervisor. 2016-11-02T19:09:16.263Z,1478113756.263 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2016-11-02T19:09:16.264Z,1478113756.263 [SyncHandler](INFO): Protected caller Thread ID is 768 2016-11-02T19:09:16.264Z,1478113756.264 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2016-11-02T19:09:16.265Z,1478113756.265 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2016-11-02T19:09:16.266Z,1478113756.266 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 769 2016-11-02T19:09:16.268Z,1478113756.268 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2016-11-02T19:09:16.280Z,1478113756.280 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2016-11-02T19:09:16.281Z,1478113756.281 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2016-11-02T19:09:16.282Z,1478113756.282 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 770 2016-11-02T19:09:16.282Z,1478113756.282 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2016-11-02T19:09:16.283Z,1478113756.283 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2016-11-02T19:09:16.284Z,1478113756.284 [logger ThreadHandler](INFO): Protected caller Thread ID is 771 2016-11-02T19:09:16.286Z,1478113756.286 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2016-11-02T19:09:16.286Z,1478113756.286 [Supervisor](INFO): Looking for Config files in directory: Config/ 2016-11-02T19:09:16.290Z,1478113756.290 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2016-11-02T19:09:16.486Z,1478113756.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2016-11-02T19:09:16.488Z,1478113756.488 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2016-11-02T19:09:16.616Z,1478113756.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2016-11-02T19:09:16.617Z,1478113756.617 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2016-11-02T19:09:16.967Z,1478113756.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2016-11-02T19:09:16.968Z,1478113756.968 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2016-11-02T19:09:17.101Z,1478113757.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2016-11-02T19:09:17.102Z,1478113757.102 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2016-11-02T19:09:17.191Z,1478113757.191 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2016-11-02T19:09:17.397Z,1478113757.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2016-11-02T19:09:17.398Z,1478113757.398 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2016-11-02T19:09:17.486Z,1478113757.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2016-11-02T19:09:17.486Z,1478113757.486 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2016-11-02T19:09:17.771Z,1478113757.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2016-11-02T19:09:17.771Z,1478113757.771 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2016-11-02T19:09:18.131Z,1478113758.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2016-11-02T19:09:18.132Z,1478113758.132 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2016-11-02T19:09:18.444Z,1478113758.444 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2016-11-02T19:09:18.445Z,1478113758.445 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2016-11-02T19:09:18.972Z,1478113758.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2016-11-02T19:09:18.973Z,1478113758.973 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2016-11-02T19:09:23.609Z,1478113763.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2016-11-02T19:09:23.610Z,1478113763.610 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2016-11-02T19:09:23.715Z,1478113763.715 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2016-11-02T19:09:23.716Z,1478113763.716 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2016-11-02T19:09:24.202Z,1478113764.202 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2016-11-02T19:09:25.922Z,1478113765.922 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2016-11-02T19:09:26.040Z,1478113766.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2016-11-02T19:09:26.042Z,1478113766.042 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2016-11-02T19:09:26.045Z,1478113766.045 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2016-11-02T19:09:26.279Z,1478113766.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2016-11-02T19:09:26.280Z,1478113766.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2016-11-02T19:09:26.384Z,1478113766.384 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2016-11-02T19:09:26.494Z,1478113766.494 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2016-11-02T19:09:26.596Z,1478113766.596 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2016-11-02T19:09:26.688Z,1478113766.688 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2016-11-02T19:09:26.840Z,1478113766.840 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2016-11-02T19:09:27.024Z,1478113767.024 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2016-11-02T19:09:27.139Z,1478113767.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2016-11-02T19:09:27.242Z,1478113767.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2016-11-02T19:09:27.331Z,1478113767.331 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2016-11-02T19:09:27.506Z,1478113767.506 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2016-11-02T19:09:27.514Z,1478113767.514 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2016-11-02T19:09:27.555Z,1478113767.555 [DepthRateCalculator] Loaded 2016-11-02T19:09:27.555Z,1478113767.556 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2016-11-02T19:09:27.562Z,1478113767.562 [PitchRateCalculator] Loaded 2016-11-02T19:09:27.562Z,1478113767.562 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2016-11-02T19:09:27.574Z,1478113767.574 [SpeedCalculator] Loaded 2016-11-02T19:09:27.574Z,1478113767.574 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2016-11-02T19:09:27.590Z,1478113767.591 [TempGradientCalculator] Loaded 2016-11-02T19:09:27.591Z,1478113767.591 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2016-11-02T19:09:27.607Z,1478113767.607 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2016-11-02T19:09:27.608Z,1478113767.608 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2016-11-02T19:09:27.614Z,1478113767.614 [YawRateCalculator] Loaded 2016-11-02T19:09:27.614Z,1478113767.614 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2016-11-02T19:09:27.614Z,1478113767.615 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2016-11-02T19:09:27.617Z,1478113767.616 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2016-11-02T19:09:27.740Z,1478113767.740 [DeadReckonUsingMultipleVelocitySources] Loaded 2016-11-02T19:09:27.740Z,1478113767.740 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2016-11-02T19:09:27.796Z,1478113767.796 [DeadReckonUsingSpeedCalculator] Loaded 2016-11-02T19:09:27.796Z,1478113767.796 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2016-11-02T19:09:27.851Z,1478113767.851 [DeadReckonWithRespectToWater] Loaded 2016-11-02T19:09:27.851Z,1478113767.851 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread. 2016-11-02T19:09:27.907Z,1478113767.907 [DeadReckonWithRespectToSeafloor] Loaded 2016-11-02T19:09:27.907Z,1478113767.907 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2016-11-02T19:09:27.969Z,1478113767.969 [DeadReckonUsingDVLWaterTrack] Loaded 2016-11-02T19:09:27.969Z,1478113767.969 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread. 2016-11-02T19:09:27.986Z,1478113767.986 [NavChart] Loaded 2016-11-02T19:09:27.986Z,1478113767.986 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2016-11-02T19:09:27.991Z,1478113767.991 [UniversalFixResidualReporter] Loaded 2016-11-02T19:09:27.991Z,1478113767.991 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2016-11-02T19:09:27.992Z,1478113767.991 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2016-11-02T19:09:27.993Z,1478113767.993 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2016-11-02T19:09:28.137Z,1478113768.137 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2016-11-02T19:09:28.139Z,1478113768.139 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2016-11-02T19:09:28.181Z,1478113768.181 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2016-11-02T19:09:28.181Z,1478113768.181 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2016-11-02T19:09:28.191Z,1478113768.191 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2016-11-02T19:09:28.191Z,1478113768.191 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2016-11-02T19:09:28.466Z,1478113768.466 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2016-11-02T19:09:28.467Z,1478113768.467 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2016-11-02T19:09:28.620Z,1478113768.620 [SBIT](DEBUG): Construct Startup Built In Test. 2016-11-02T19:09:28.652Z,1478113768.652 [SBIT] Loaded 2016-11-02T19:09:28.652Z,1478113768.652 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2016-11-02T19:09:28.653Z,1478113768.653 [IBIT](DEBUG): Construct Initiated Built In Test. 2016-11-02T19:09:28.681Z,1478113768.681 [IBIT] Loaded 2016-11-02T19:09:28.682Z,1478113768.682 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2016-11-02T19:09:28.685Z,1478113768.685 [CBIT](DEBUG): Construct Continuous Built In Test. 2016-11-02T19:09:28.845Z,1478113768.845 [CBIT] Loaded 2016-11-02T19:09:28.845Z,1478113768.845 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2016-11-02T19:09:28.846Z,1478113768.846 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2016-11-02T19:09:28.848Z,1478113768.848 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2016-11-02T19:09:29.467Z,1478113769.467 [DataOverHttps] Loaded 2016-11-02T19:09:29.467Z,1478113769.467 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2016-11-02T19:09:29.493Z,1478113769.493 [Depth_Keller] Loaded 2016-11-02T19:09:29.493Z,1478113769.493 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2016-11-02T19:09:29.499Z,1478113769.499 [DropWeight] Loaded 2016-11-02T19:09:29.499Z,1478113769.499 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2016-11-02T19:09:29.593Z,1478113769.593 [NAL9602] Loaded 2016-11-02T19:09:29.593Z,1478113769.593 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2016-11-02T19:09:29.603Z,1478113769.603 [Onboard] Loaded 2016-11-02T19:09:29.603Z,1478113769.603 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2016-11-02T19:09:29.610Z,1478113769.610 [Radio_Surface] Loaded 2016-11-02T19:09:29.611Z,1478113769.611 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2016-11-02T19:09:29.612Z,1478113769.612 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0 2016-11-02T19:09:29.612Z,1478113769.612 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 851 2016-11-02T19:09:29.885Z,1478113769.885 [PNI_TCM] Loaded 2016-11-02T19:09:29.885Z,1478113769.885 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2016-11-02T19:09:30.245Z,1478113770.245 [Rowe_600LCM] Loaded 2016-11-02T19:09:30.245Z,1478113770.245 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2016-11-02T19:09:30.246Z,1478113770.246 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 4095E4E0 2016-11-02T19:09:30.247Z,1478113770.247 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 852 2016-11-02T19:09:32.125Z,1478113772.125 [BPC1] Loaded 2016-11-02T19:09:32.126Z,1478113772.126 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2016-11-02T19:09:32.126Z,1478113772.126 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2016-11-02T19:09:32.127Z,1478113772.127 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2016-11-02T19:09:32.256Z,1478113772.256 [BuoyancyServo] Loaded 2016-11-02T19:09:32.257Z,1478113772.257 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2016-11-02T19:09:32.273Z,1478113772.273 [ElevatorServo] Loaded 2016-11-02T19:09:32.274Z,1478113772.274 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2016-11-02T19:09:32.289Z,1478113772.289 [MassServo] Loaded 2016-11-02T19:09:32.289Z,1478113772.289 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2016-11-02T19:09:32.305Z,1478113772.305 [RudderServo] Loaded 2016-11-02T19:09:32.306Z,1478113772.306 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2016-11-02T19:09:32.321Z,1478113772.321 [ThrusterServo] Loaded 2016-11-02T19:09:32.322Z,1478113772.322 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2016-11-02T19:09:32.322Z,1478113772.322 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2016-11-02T19:09:32.323Z,1478113772.323 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2016-11-02T19:09:32.394Z,1478113772.394 [VerticalControl](DEBUG): Construct VerticalControl. 2016-11-02T19:09:32.489Z,1478113772.489 [VerticalControl] Loaded 2016-11-02T19:09:32.489Z,1478113772.489 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2016-11-02T19:09:32.490Z,1478113772.490 [HorizontalControl](DEBUG): Construct HorizontalControl. 2016-11-02T19:09:32.551Z,1478113772.551 [HorizontalControl] Loaded 2016-11-02T19:09:32.551Z,1478113772.551 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2016-11-02T19:09:32.552Z,1478113772.552 [SpeedControl](DEBUG): Construct SpeedControl. 2016-11-02T19:09:32.554Z,1478113772.554 [SpeedControl] Loaded 2016-11-02T19:09:32.554Z,1478113772.554 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2016-11-02T19:09:32.555Z,1478113772.555 [LoopControl](DEBUG): Construct LoopControl. 2016-11-02T19:09:32.555Z,1478113772.555 [LoopControl] Loaded 2016-11-02T19:09:32.556Z,1478113772.556 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2016-11-02T19:09:32.556Z,1478113772.556 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2016-11-02T19:09:32.558Z,1478113772.558 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2016-11-02T19:09:32.676Z,1478113772.676 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2016-11-02T19:09:32.676Z,1478113772.676 [StratificationFrontDetector](DEBUG): (re)initializing 2016-11-02T19:09:32.676Z,1478113772.676 [StratificationFrontDetector] Loaded 2016-11-02T19:09:32.677Z,1478113772.677 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2016-11-02T19:09:32.677Z,1478113772.677 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2016-11-02T19:09:32.678Z,1478113772.678 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2016-11-02T19:09:32.813Z,1478113772.813 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2016-11-02T19:09:32.817Z,1478113772.817 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2016-11-02T19:09:32.818Z,1478113772.818 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2016-11-02T19:09:32.825Z,1478113772.825 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2016-11-02T19:09:32.826Z,1478113772.826 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A684E0 2016-11-02T19:09:32.826Z,1478113772.826 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 853 2016-11-02T19:09:32.831Z,1478113772.831 [Supervisor](INFO): Main Thread ID is 763 2016-11-02T19:09:32.831Z,1478113772.832 [Supervisor](DEBUG): Running supervisor. 2016-11-02T19:09:32.832Z,1478113772.832 [CommandLine ThreadHandler](INFO): Handler Thread ID is 854 2016-11-02T19:09:32.835Z,1478113772.835 [controlThread ThreadHandler](INFO): Handler Thread ID is 855 2016-11-02T19:09:32.836Z,1478113772.836 [controlThread](DEBUG): Initializing ControlThread 2016-11-02T19:09:32.837Z,1478113772.837 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2016-11-02T19:09:32.837Z,1478113772.837 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2016-11-02T19:09:32.837Z,1478113772.837 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2016-11-02T19:09:32.838Z,1478113772.838 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2016-11-02T19:09:32.838Z,1478113772.838 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2016-11-02T19:09:32.839Z,1478113772.839 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2016-11-02T19:09:32.840Z,1478113772.840 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-11-02T19:09:32.841Z,1478113772.841 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-11-02T19:09:32.841Z,1478113772.841 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-11-02T19:09:32.841Z,1478113772.841 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-11-02T19:09:32.842Z,1478113772.842 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-11-02T19:09:32.842Z,1478113772.842 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-11-02T19:09:32.843Z,1478113772.843 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component. 2016-11-02T19:09:32.843Z,1478113772.843 [DeadReckonWithRespectToWater](INFO): Will consider orientation measurement stale after 120s. 2016-11-02T19:09:32.843Z,1478113772.843 [DeadReckonWithRespectToWater](INFO): Will consider velocity measurement stale after 20s. 2016-11-02T19:09:32.844Z,1478113772.844 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2016-11-02T19:09:32.844Z,1478113772.844 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2016-11-02T19:09:32.845Z,1478113772.845 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2016-11-02T19:09:32.845Z,1478113772.845 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component. 2016-11-02T19:09:32.845Z,1478113772.845 [DeadReckonUsingDVLWaterTrack](INFO): Will consider orientation measurement stale after 120s. 2016-11-02T19:09:32.846Z,1478113772.846 [DeadReckonUsingDVLWaterTrack](INFO): Will consider velocity measurement stale after 20s. 2016-11-02T19:09:32.846Z,1478113772.846 [NavChart](DEBUG): Initialize NavChart Navigation. 2016-11-02T19:09:32.847Z,1478113772.847 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2016-11-02T19:09:32.847Z,1478113772.847 [SBIT](INFO): Initialize SBIT Component. 2016-11-02T19:09:32.848Z,1478113772.848 [SBIT](IMPORTANT): git: 2016-06-23-33-gbd95f16 2016-11-02T19:09:32.848Z,1478113772.848 [SBIT](INFO): git hash: bd95f16bed7639295ecaa8e6ee95eb4c3e5c6837 2016-11-02T19:09:32.849Z,1478113772.849 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2016-11-02T19:09:32.850Z,1478113772.850 [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 2016-11-02T19:09:32.851Z,1478113772.851 [SBIT](INFO): Beginning SBIT in 32.000000 seconds. 2016-11-02T19:09:32.851Z,1478113772.851 [IBIT](INFO): Initialize IBIT Component. 2016-11-02T19:09:32.852Z,1478113772.852 [CBIT](DEBUG): Initialize CBIT Component. 2016-11-02T19:09:32.852Z,1478113772.852 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2016-11-02T19:09:32.853Z,1478113772.853 [logger ThreadHandler](INFO): Handler Thread ID is 856 2016-11-02T19:09:32.882Z,1478113772.882 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 857 2016-11-02T19:09:32.889Z,1478113772.889 [Radio_Surface](INFO): Powering up 2016-11-02T19:09:32.894Z,1478113772.894 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 858 2016-11-02T19:09:32.895Z,1478113772.895 [Rowe_600LCM](INFO): Initializing 2016-11-02T19:09:32.896Z,1478113772.896 [Rowe_600LCM](INFO): Checking LCM 2016-11-02T19:09:32.896Z,1478113772.896 [Rowe_600LCM](INFO): LCM OK 2016-11-02T19:09:32.896Z,1478113772.896 [Rowe_600LCM](INFO): Powering up 2016-11-02T19:09:32.902Z,1478113772.902 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 859 2016-11-02T19:09:32.912Z,1478113772.912 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2016-11-02T19:09:32.913Z,1478113772.913 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2016-11-02T19:09:32.913Z,1478113772.913 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2016-11-02T19:09:32.913Z,1478113772.913 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2016-11-02T19:09:32.913Z,1478113772.913 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2016-11-02T19:09:32.914Z,1478113772.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2016-11-02T19:09:32.914Z,1478113772.914 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2016-11-02T19:09:32.914Z,1478113772.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2016-11-02T19:09:32.914Z,1478113772.914 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2016-11-02T19:09:32.914Z,1478113772.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2016-11-02T19:09:32.914Z,1478113772.914 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2016-11-02T19:09:32.915Z,1478113772.915 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2016-11-02T19:09:32.915Z,1478113772.915 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2016-11-02T19:09:32.915Z,1478113772.915 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2016-11-02T19:09:32.915Z,1478113772.915 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2016-11-02T19:09:32.915Z,1478113772.915 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2016-11-02T19:09:32.944Z,1478113772.944 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2016-11-02T19:09:32.946Z,1478113772.946 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2016-11-02T19:09:32.947Z,1478113772.947 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2016-11-02T19:09:32.947Z,1478113772.947 [LoopControl](DEBUG): Initialize LoopControlComponent. 2016-11-02T19:09:32.948Z,1478113772.948 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2016-11-02T19:09:32.977Z,1478113772.977 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2016-11-02T19:09:33.008Z,1478113773.008 [MissionManager](DEBUG): 2016-11-02T19:09:33.009Z,1478113773.009 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2016-11-02T19:09:33.094Z,1478113773.094 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2016-11-02T19:09:33.095Z,1478113773.095 [Default:A.Wait](DEBUG): Construct Wait. 2016-11-02T19:09:33.097Z,1478113773.097 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2016-11-02T19:09:33.136Z,1478113773.136 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2016-11-02T19:09:33.139Z,1478113773.139 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2016-11-02T19:09:33.175Z,1478113773.175 [Default:E.Execute](DEBUG): Construct Execute. 2016-11-02T19:09:33.195Z,1478113773.195 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2016-11-02T19:09:33.200Z,1478113773.200 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,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, 2016-11-02T19:09:33.623Z,1478113773.623 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:33.623Z,1478113773.623 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2016-11-02T19:09:33.764Z,1478113773.764 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-11-02T19:09:33.798Z,1478113773.798 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:33.810Z,1478113773.810 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:33.822Z,1478113773.822 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:33.831Z,1478113773.831 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:33.942Z,1478113773.942 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2016-11-02T19:09:33.950Z,1478113773.950 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2016-11-02T19:09:33.972Z,1478113773.972 [ElevatorServo](DEBUG): Initializing EZServoServo. 2016-11-02T19:09:33.978Z,1478113773.978 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2016-11-02T19:09:33.000Z,1478113774.000 [MassServo](DEBUG): Initializing EZServoServo. 2016-11-02T19:09:34.006Z,1478113774.006 [MassServo](DEBUG): Initializing MassServo. 2016-11-02T19:09:34.012Z,1478113774.012 [RudderServo](DEBUG): Initializing EZServoServo. 2016-11-02T19:09:34.022Z,1478113774.022 [RudderServo](DEBUG): Initializing RudderServo. 2016-11-02T19:09:34.044Z,1478113774.044 [ThrusterServo](DEBUG): Initializing EZServoServo. 2016-11-02T19:09:34.050Z,1478113774.050 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2016-11-02T19:09:34.291Z,1478113774.291 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2016-11-02T19:09:34.291Z,1478113774.291 [DropWeight] Hardware Fault, FailCount= 1 2016-11-02T19:09:34.291Z,1478113774.291 [DropWeight](ERROR): Hardware Fault 2016-11-02T19:09:34.355Z,1478113774.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:34.355Z,1478113774.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2016-11-02T19:09:34.356Z,1478113774.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-11-02T19:09:34.357Z,1478113774.357 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:34.358Z,1478113774.358 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:34.366Z,1478113774.366 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:34.367Z,1478113774.367 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:34.395Z,1478113774.395 [CommandLine](FAULT): Scheduling is paused 2016-11-02T19:09:34.397Z,1478113774.397 [CBIT](ERROR): Hardware Fault in component: DropWeight 2016-11-02T19:09:34.398Z,1478113774.398 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2016-11-02T19:09:34.579Z,1478113774.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:34.579Z,1478113774.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2016-11-02T19:09:34.580Z,1478113774.580 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-11-02T19:09:34.581Z,1478113774.581 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:34.582Z,1478113774.582 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:34.584Z,1478113774.584 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:34.585Z,1478113774.585 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:34.922Z,1478113774.922 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2016-11-02T19:09:34.922Z,1478113774.922 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2016-11-02T19:09:34.922Z,1478113774.922 [BuoyancyServo] Communications Fault, FailCount= 1 2016-11-02T19:09:34.922Z,1478113774.922 [BuoyancyServo](ERROR): Communications Fault 2016-11-02T19:09:35.133Z,1478113775.134 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2016-11-02T19:09:35.144Z,1478113775.144 [NAL9602](INFO): Powering up NAL9602 2016-11-02T19:09:35.181Z,1478113775.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.182Z,1478113775.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2016-11-02T19:09:35.183Z,1478113775.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-11-02T19:09:35.190Z,1478113775.190 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.191Z,1478113775.191 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.191Z,1478113775.191 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.192Z,1478113775.192 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.199Z,1478113775.199 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2016-11-02T19:09:35.199Z,1478113775.199 [BuoyancyServo](INFO): Powering down 2016-11-02T19:09:35.596Z,1478113775.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.596Z,1478113775.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2016-11-02T19:09:35.598Z,1478113775.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-11-02T19:09:35.606Z,1478113775.606 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.607Z,1478113775.607 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.608Z,1478113775.608 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.609Z,1478113775.609 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.970Z,1478113775.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.970Z,1478113775.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2016-11-02T19:09:35.971Z,1478113775.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-11-02T19:09:35.986Z,1478113775.986 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.987Z,1478113775.987 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.988Z,1478113775.987 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:35.988Z,1478113775.988 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:36.381Z,1478113776.381 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:36.382Z,1478113776.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2016-11-02T19:09:36.383Z,1478113776.383 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-11-02T19:09:36.383Z,1478113776.383 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:36.384Z,1478113776.384 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:36.385Z,1478113776.385 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:36.394Z,1478113776.394 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:36.782Z,1478113776.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:36.783Z,1478113776.783 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2016-11-02T19:09:36.784Z,1478113776.784 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-11-02T19:09:36.784Z,1478113776.784 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:36.785Z,1478113776.785 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:36.794Z,1478113776.794 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:36.795Z,1478113776.795 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet. 2016-11-02T19:09:37.187Z,1478113777.187 [Ro