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