2016-09-27T22:35:09.002Z,1475015709.002 [Supervisor](DEBUG): Initializing supervisor.
2016-09-27T22:35:09.005Z,1475015709.005 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2016-09-27T22:35:09.005Z,1475015709.005 [SyncHandler](INFO): Protected caller Thread ID is 766
2016-09-27T22:35:09.005Z,1475015709.006 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2016-09-27T22:35:09.007Z,1475015709.007 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2016-09-27T22:35:09.007Z,1475015709.007 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 767
2016-09-27T22:35:09.010Z,1475015709.010 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2016-09-27T22:35:09.022Z,1475015709.022 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2016-09-27T22:35:09.023Z,1475015709.023 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2016-09-27T22:35:09.023Z,1475015709.023 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 768
2016-09-27T22:35:09.024Z,1475015709.024 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2016-09-27T22:35:09.025Z,1475015709.025 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2016-09-27T22:35:09.025Z,1475015709.025 [logger ThreadHandler](INFO): Protected caller Thread ID is 769
2016-09-27T22:35:09.027Z,1475015709.027 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2016-09-27T22:35:09.027Z,1475015709.027 [Supervisor](INFO): Looking for Config files in directory: Config/
2016-09-27T22:35:09.032Z,1475015709.032 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2016-09-27T22:35:09.208Z,1475015709.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2016-09-27T22:35:09.211Z,1475015709.211 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2016-09-27T22:35:09.336Z,1475015709.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2016-09-27T22:35:09.337Z,1475015709.337 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2016-09-27T22:35:09.675Z,1475015709.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2016-09-27T22:35:09.676Z,1475015709.676 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2016-09-27T22:35:09.810Z,1475015709.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2016-09-27T22:35:09.811Z,1475015709.811 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2016-09-27T22:35:09.898Z,1475015709.898 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2016-09-27T22:35:10.101Z,1475015710.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2016-09-27T22:35:10.102Z,1475015710.102 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2016-09-27T22:35:10.188Z,1475015710.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2016-09-27T22:35:10.189Z,1475015710.188 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2016-09-27T22:35:10.466Z,1475015710.466 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2016-09-27T22:35:10.467Z,1475015710.467 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2016-09-27T22:35:10.818Z,1475015710.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2016-09-27T22:35:10.819Z,1475015710.819 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2016-09-27T22:35:11.124Z,1475015711.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2016-09-27T22:35:11.125Z,1475015711.125 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2016-09-27T22:35:11.632Z,1475015711.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2016-09-27T22:35:11.633Z,1475015711.633 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2016-09-27T22:35:11.849Z,1475015711.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2016-09-27T22:35:11.850Z,1475015711.850 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2016-09-27T22:35:11.973Z,1475015711.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2016-09-27T22:35:11.974Z,1475015711.974 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2016-09-27T22:35:12.536Z,1475015712.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2016-09-27T22:35:12.536Z,1475015712.536 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2016-09-27T22:35:17.619Z,1475015717.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2016-09-27T22:35:17.621Z,1475015717.621 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2016-09-27T22:35:18.572Z,1475015718.572 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2016-09-27T22:35:18.817Z,1475015718.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2016-09-27T22:35:18.818Z,1475015718.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2016-09-27T22:35:18.920Z,1475015718.920 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Navigation.cfg
2016-09-27T22:35:19.029Z,1475015719.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2016-09-27T22:35:19.129Z,1475015719.129 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2016-09-27T22:35:19.220Z,1475015719.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2016-09-27T22:35:19.368Z,1475015719.368 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2016-09-27T22:35:19.548Z,1475015719.548 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2016-09-27T22:35:19.660Z,1475015719.660 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2016-09-27T22:35:19.761Z,1475015719.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2016-09-27T22:35:19.849Z,1475015719.849 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2016-09-27T22:35:20.019Z,1475015720.019 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2016-09-27T22:35:20.028Z,1475015720.028 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2016-09-27T22:35:20.181Z,1475015720.181 [SBIT](DEBUG): Construct Startup Built In Test.
2016-09-27T22:35:20.213Z,1475015720.213 [SBIT] Loaded
2016-09-27T22:35:20.213Z,1475015720.213 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2016-09-27T22:35:20.214Z,1475015720.214 [IBIT](DEBUG): Construct Initiated Built In Test.
2016-09-27T22:35:20.243Z,1475015720.243 [IBIT] Loaded
2016-09-27T22:35:20.243Z,1475015720.243 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2016-09-27T22:35:20.247Z,1475015720.247 [CBIT](DEBUG): Construct Continuous Built In Test.
2016-09-27T22:35:20.398Z,1475015720.398 [CBIT] Loaded
2016-09-27T22:35:20.399Z,1475015720.399 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2016-09-27T22:35:20.399Z,1475015720.399 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2016-09-27T22:35:20.400Z,1475015720.400 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2016-09-27T22:35:20.469Z,1475015720.469 [VerticalControl](DEBUG): Construct VerticalControl.
2016-09-27T22:35:20.572Z,1475015720.572 [VerticalControl] Loaded
2016-09-27T22:35:20.572Z,1475015720.572 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2016-09-27T22:35:20.573Z,1475015720.573 [HorizontalControl](DEBUG): Construct HorizontalControl.
2016-09-27T22:35:20.637Z,1475015720.637 [HorizontalControl] Loaded
2016-09-27T22:35:20.637Z,1475015720.637 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2016-09-27T22:35:20.638Z,1475015720.638 [SpeedControl](DEBUG): Construct SpeedControl.
2016-09-27T22:35:20.644Z,1475015720.644 [SpeedControl] Loaded
2016-09-27T22:35:20.644Z,1475015720.644 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2016-09-27T22:35:20.645Z,1475015720.645 [LoopControl](DEBUG): Construct LoopControl.
2016-09-27T22:35:20.645Z,1475015720.645 [LoopControl] Loaded
2016-09-27T22:35:20.646Z,1475015720.645 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2016-09-27T22:35:20.646Z,1475015720.646 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2016-09-27T22:35:20.647Z,1475015720.647 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2016-09-27T22:35:20.685Z,1475015720.685 [DepthRateCalculator] Loaded
2016-09-27T22:35:20.686Z,1475015720.686 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2016-09-27T22:35:20.691Z,1475015720.691 [PitchRateCalculator] Loaded
2016-09-27T22:35:20.691Z,1475015720.691 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2016-09-27T22:35:20.703Z,1475015720.703 [SpeedCalculator] Loaded
2016-09-27T22:35:20.703Z,1475015720.703 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2016-09-27T22:35:20.720Z,1475015720.720 [TempGradientCalculator] Loaded
2016-09-27T22:35:20.720Z,1475015720.720 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2016-09-27T22:35:20.737Z,1475015720.737 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2016-09-27T22:35:20.737Z,1475015720.737 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2016-09-27T22:35:20.743Z,1475015720.743 [YawRateCalculator] Loaded
2016-09-27T22:35:20.743Z,1475015720.743 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2016-09-27T22:35:20.743Z,1475015720.743 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2016-09-27T22:35:20.744Z,1475015720.744 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2016-09-27T22:35:20.860Z,1475015720.860 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2016-09-27T22:35:20.860Z,1475015720.861 [StratificationFrontDetector](DEBUG): (re)initializing
2016-09-27T22:35:20.861Z,1475015720.861 [StratificationFrontDetector] Loaded
2016-09-27T22:35:20.861Z,1475015720.861 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2016-09-27T22:35:20.862Z,1475015720.862 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2016-09-27T22:35:20.864Z,1475015720.864 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2016-09-27T22:35:21.138Z,1475015721.138 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2016-09-27T22:35:21.139Z,1475015721.139 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2016-09-27T22:35:21.259Z,1475015721.260 [DeadReckonUsingMultipleVelocitySources] Loaded
2016-09-27T22:35:21.260Z,1475015721.260 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2016-09-27T22:35:21.316Z,1475015721.316 [DeadReckonUsingSpeedCalculator] Loaded
2016-09-27T22:35:21.316Z,1475015721.316 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2016-09-27T22:35:21.372Z,1475015721.372 [DeadReckonWithRespectToWater] Loaded
2016-09-27T22:35:21.372Z,1475015721.372 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2016-09-27T22:35:21.428Z,1475015721.428 [DeadReckonWithRespectToSeafloor] Loaded
2016-09-27T22:35:21.428Z,1475015721.428 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2016-09-27T22:35:21.489Z,1475015721.490 [DeadReckonUsingDVLWaterTrack] Loaded
2016-09-27T22:35:21.490Z,1475015721.490 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2016-09-27T22:35:21.507Z,1475015721.507 [NavChart] Loaded
2016-09-27T22:35:21.507Z,1475015721.507 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2016-09-27T22:35:21.512Z,1475015721.511 [UniversalFixResidualReporter] Loaded
2016-09-27T22:35:21.512Z,1475015721.512 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2016-09-27T22:35:21.512Z,1475015721.512 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2016-09-27T22:35:21.513Z,1475015721.513 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2016-09-27T22:35:21.522Z,1475015721.523 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2016-09-27T22:35:21.523Z,1475015721.523 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2016-09-27T22:35:21.665Z,1475015721.665 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2016-09-27T22:35:21.665Z,1475015721.665 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2016-09-27T22:35:22.282Z,1475015722.283 [DataOverHttps] Loaded
2016-09-27T22:35:22.283Z,1475015722.283 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2016-09-27T22:35:22.310Z,1475015722.310 [Depth_Keller] Loaded
2016-09-27T22:35:22.310Z,1475015722.310 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2016-09-27T22:35:22.405Z,1475015722.405 [NAL9602] Loaded
2016-09-27T22:35:22.406Z,1475015722.406 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2016-09-27T22:35:22.415Z,1475015722.415 [Onboard] Loaded
2016-09-27T22:35:22.416Z,1475015722.416 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2016-09-27T22:35:22.423Z,1475015722.423 [Radio_Surface] Loaded
2016-09-27T22:35:22.423Z,1475015722.423 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2016-09-27T22:35:22.424Z,1475015722.424 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097F4E0
2016-09-27T22:35:22.425Z,1475015722.425 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 848
2016-09-27T22:35:22.619Z,1475015722.619 [PNI_TCM] Loaded
2016-09-27T22:35:22.619Z,1475015722.619 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2016-09-27T22:35:22.981Z,1475015722.981 [Rowe_600LCM] Loaded
2016-09-27T22:35:22.981Z,1475015722.981 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2016-09-27T22:35:22.982Z,1475015722.982 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409AF4E0
2016-09-27T22:35:22.983Z,1475015722.983 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 849
2016-09-27T22:35:22.983Z,1475015722.983 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2016-09-27T22:35:22.984Z,1475015722.984 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2016-09-27T22:35:23.710Z,1475015723.710 [BuoyancyServo] Loaded
2016-09-27T22:35:23.710Z,1475015723.710 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2016-09-27T22:35:23.722Z,1475015723.722 [ElevatorServo] Loaded
2016-09-27T22:35:23.723Z,1475015723.723 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2016-09-27T22:35:23.734Z,1475015723.734 [MassServo] Loaded
2016-09-27T22:35:23.734Z,1475015723.734 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2016-09-27T22:35:23.746Z,1475015723.746 [RudderServo] Loaded
2016-09-27T22:35:23.747Z,1475015723.747 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2016-09-27T22:35:23.758Z,1475015723.758 [ThrusterServo] Loaded
2016-09-27T22:35:23.758Z,1475015723.758 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2016-09-27T22:35:23.759Z,1475015723.759 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2016-09-27T22:35:23.760Z,1475015723.760 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2016-09-27T22:35:23.892Z,1475015723.892 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2016-09-27T22:35:23.893Z,1475015723.893 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2016-09-27T22:35:23.933Z,1475015723.933 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2016-09-27T22:35:23.937Z,1475015723.937 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2016-09-27T22:35:23.938Z,1475015723.938 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2016-09-27T22:35:23.945Z,1475015723.945 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2016-09-27T22:35:23.946Z,1475015723.946 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A5D4E0
2016-09-27T22:35:23.947Z,1475015723.947 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 850
2016-09-27T22:35:23.952Z,1475015723.952 [Supervisor](INFO): Main Thread ID is 765
2016-09-27T22:35:23.952Z,1475015723.952 [Supervisor](DEBUG): Running supervisor.
2016-09-27T22:35:23.953Z,1475015723.953 [CommandLine ThreadHandler](INFO): Handler Thread ID is 851
2016-09-27T22:35:23.956Z,1475015723.956 [controlThread ThreadHandler](INFO): Handler Thread ID is 852
2016-09-27T22:35:23.956Z,1475015723.956 [controlThread](DEBUG): Initializing ControlThread
2016-09-27T22:35:23.957Z,1475015723.957 [SBIT](INFO): Initialize SBIT Component.
2016-09-27T22:35:23.957Z,1475015723.957 [SBIT](IMPORTANT): git: 2016-06-23-6-gc6c0fb7
2016-09-27T22:35:23.958Z,1475015723.958 [SBIT](INFO): git hash: c6c0fb75a403746acbf2d57d4f50ab749bc4e06e
2016-09-27T22:35:23.959Z,1475015723.959 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2016-09-27T22:35:23.960Z,1475015723.960 [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-09-27T22:35:23.961Z,1475015723.961 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2016-09-27T22:35:23.961Z,1475015723.961 [IBIT](INFO): Initialize IBIT Component.
2016-09-27T22:35:23.962Z,1475015723.962 [CBIT](DEBUG): Initialize CBIT Component.
2016-09-27T22:35:23.962Z,1475015723.963 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2016-09-27T22:35:23.963Z,1475015723.963 [logger ThreadHandler](INFO): Handler Thread ID is 853
2016-09-27T22:35:23.991Z,1475015723.991 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 854
2016-09-27T22:35:23.997Z,1475015723.997 [Radio_Surface](INFO): Powering up
2016-09-27T22:35:24.015Z,1475015724.015 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 855
2016-09-27T22:35:24.015Z,1475015724.015 [Rowe_600LCM](INFO): Initializing
2016-09-27T22:35:24.016Z,1475015724.016 [Rowe_600LCM](INFO): Checking LCM
2016-09-27T22:35:24.016Z,1475015724.016 [Rowe_600LCM](INFO): LCM OK
2016-09-27T22:35:24.016Z,1475015724.016 [Rowe_600LCM](INFO): Powering up
2016-09-27T22:35:24.035Z,1475015724.035 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 856
2016-09-27T22:35:24.043Z,1475015724.043 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2016-09-27T22:35:24.044Z,1475015724.044 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2016-09-27T22:35:24.044Z,1475015724.044 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2016-09-27T22:35:24.044Z,1475015724.044 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2016-09-27T22:35:24.044Z,1475015724.044 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2016-09-27T22:35:24.044Z,1475015724.044 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2016-09-27T22:35:24.045Z,1475015724.045 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2016-09-27T22:35:24.045Z,1475015724.045 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2016-09-27T22:35:24.045Z,1475015724.045 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2016-09-27T22:35:24.045Z,1475015724.045 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2016-09-27T22:35:24.045Z,1475015724.046 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2016-09-27T22:35:24.046Z,1475015724.046 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2016-09-27T22:35:24.046Z,1475015724.046 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2016-09-27T22:35:24.048Z,1475015724.048 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2016-09-27T22:35:24.049Z,1475015724.049 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2016-09-27T22:35:24.050Z,1475015724.050 [LoopControl](DEBUG): Initialize LoopControlComponent.
2016-09-27T22:35:24.050Z,1475015724.050 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2016-09-27T22:35:24.050Z,1475015724.050 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2016-09-27T22:35:24.051Z,1475015724.051 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2016-09-27T22:35:24.051Z,1475015724.051 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2016-09-27T22:35:24.052Z,1475015724.052 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2016-09-27T22:35:24.052Z,1475015724.053 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2016-09-27T22:35:24.053Z,1475015724.053 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-09-27T22:35:24.054Z,1475015724.054 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2016-09-27T22:35:24.054Z,1475015724.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2016-09-27T22:35:24.055Z,1475015724.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2016-09-27T22:35:24.055Z,1475015724.055 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2016-09-27T22:35:24.056Z,1475015724.056 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-09-27T22:35:24.056Z,1475015724.056 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-09-27T22:35:24.057Z,1475015724.057 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-09-27T22:35:24.057Z,1475015724.057 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-09-27T22:35:24.057Z,1475015724.057 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-09-27T22:35:24.058Z,1475015724.058 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2016-09-27T22:35:24.058Z,1475015724.059 [DeadReckonWithRespectToWater](INFO): Will consider orientation measurement stale after 120s.
2016-09-27T22:35:24.059Z,1475015724.059 [DeadReckonWithRespectToWater](INFO): Will consider velocity measurement stale after 20s.
2016-09-27T22:35:24.059Z,1475015724.059 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2016-09-27T22:35:24.060Z,1475015724.060 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2016-09-27T22:35:24.060Z,1475015724.060 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2016-09-27T22:35:24.060Z,1475015724.060 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2016-09-27T22:35:24.061Z,1475015724.061 [DeadReckonUsingDVLWaterTrack](INFO): Will consider orientation measurement stale after 120s.
2016-09-27T22:35:24.061Z,1475015724.061 [DeadReckonUsingDVLWaterTrack](INFO): Will consider velocity measurement stale after 20s.
2016-09-27T22:35:24.062Z,1475015724.062 [NavChart](DEBUG): Initialize NavChart Navigation.
2016-09-27T22:35:24.062Z,1475015724.062 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2016-09-27T22:35:24.081Z,1475015724.081 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2016-09-27T22:35:24.130Z,1475015724.130 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2016-09-27T22:35:24.153Z,1475015724.153 [MissionManager](DEBUG):
2016-09-27T22:35:24.170Z,1475015724.170 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2016-09-27T22:35:24.221Z,1475015724.221 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2016-09-27T22:35:24.239Z,1475015724.239 [Default:A.Wait](DEBUG): Construct Wait.
2016-09-27T22:35:24.241Z,1475015724.241 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2016-09-27T22:35:24.275Z,1475015724.275 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2016-09-27T22:35:24.277Z,1475015724.277 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2016-09-27T22:35:24.299Z,1475015724.299 [Default:E.Execute](DEBUG): Construct Execute.
2016-09-27T22:35:24.319Z,1475015724.319 [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-09-27T22:35:24.324Z,1475015724.324 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,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-09-27T22:35:24.638Z,1475015724.638 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:24.639Z,1475015724.639 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2016-09-27T22:35:24.779Z,1475015724.779 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-09-27T22:35:24.797Z,1475015724.797 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:24.827Z,1475015724.827 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:24.846Z,1475015724.846 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:24.854Z,1475015724.854 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:24.962Z,1475015724.963 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2016-09-27T22:35:24.970Z,1475015724.970 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2016-09-27T22:35:24.982Z,1475015724.982 [ElevatorServo](DEBUG): Initializing EZServoServo.
2016-09-27T22:35:24.990Z,1475015724.990 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2016-09-27T22:35:25.012Z,1475015725.012 [MassServo](DEBUG): Initializing EZServoServo.
2016-09-27T22:35:25.019Z,1475015725.019 [MassServo](DEBUG): Initializing MassServo.
2016-09-27T22:35:25.032Z,1475015725.032 [RudderServo](DEBUG): Initializing EZServoServo.
2016-09-27T22:35:25.038Z,1475015725.038 [RudderServo](DEBUG): Initializing RudderServo.
2016-09-27T22:35:25.044Z,1475015725.044 [ThrusterServo](DEBUG): Initializing EZServoServo.
2016-09-27T22:35:25.050Z,1475015725.050 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2016-09-27T22:35:25.341Z,1475015725.341 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:25.341Z,1475015725.341 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2016-09-27T22:35:25.342Z,1475015725.342 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-09-27T22:35:25.343Z,1475015725.343 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:25.344Z,1475015725.344 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:25.345Z,1475015725.345 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:25.345Z,1475015725.345 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:25.563Z,1475015725.563 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:25.563Z,1475015725.563 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2016-09-27T22:35:25.565Z,1475015725.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-09-27T22:35:25.565Z,1475015725.565 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:25.574Z,1475015725.574 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:25.575Z,1475015725.575 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:25.576Z,1475015725.576 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:25.930Z,1475015725.930 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2016-09-27T22:35:25.930Z,1475015725.930 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2016-09-27T22:35:25.931Z,1475015725.931 [BuoyancyServo] Communications Fault, FailCount= 1
2016-09-27T22:35:25.931Z,1475015725.931 [BuoyancyServo](ERROR): Communications Fault
2016-09-27T22:35:26.130Z,1475015726.130 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2016-09-27T22:35:26.166Z,1475015726.166 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.166Z,1475015726.166 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2016-09-27T22:35:26.167Z,1475015726.167 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-09-27T22:35:26.182Z,1475015726.182 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.183Z,1475015726.183 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.184Z,1475015726.184 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.185Z,1475015726.185 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.199Z,1475015726.199 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2016-09-27T22:35:26.200Z,1475015726.200 [BuoyancyServo](INFO): Powering down
2016-09-27T22:35:26.579Z,1475015726.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.579Z,1475015726.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2016-09-27T22:35:26.580Z,1475015726.580 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-09-27T22:35:26.581Z,1475015726.580 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.581Z,1475015726.581 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.582Z,1475015726.582 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.584Z,1475015726.584 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.966Z,1475015726.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.966Z,1475015726.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2016-09-27T22:35:26.967Z,1475015726.967 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-09-27T22:35:26.968Z,1475015726.968 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.969Z,1475015726.969 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.969Z,1475015726.969 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:26.970Z,1475015726.970 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:27.366Z,1475015727.366 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:27.366Z,1475015727.366 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2016-09-27T22:35:27.367Z,1475015727.367 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-09-27T22:35:27.368Z,1475015727.368 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:27.369Z,1475015727.369 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:27.370Z,1475015727.370 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:27.371Z,1475015727.371 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:27.744Z,1475015727.744 [NAL9602](INFO): Powering up NAL9602
2016-09-27T22:35:27.780Z,1475015727.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:27.780Z,1475015727.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2016-09-27T22:35:27.781Z,1475015727.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-09-27T22:35:27.782Z,1475015727.782 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:27.783Z,1475015727.783 [DeadReckonWithRespectToWater](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:27.783Z,1475015727.783 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:27.784Z,1475015727.784 [DeadReckonUsingDVLWaterTrack](DEBUG): Starting up and don't have orientation data yet.
2016-09-27T22:35:28.246Z,1475015728.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2016-09-27T22:35:28.255Z,1475015728.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-09-27T22:35:28.352Z,1475015728.352 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2016-09-27T22:35:28.352Z,1475015728.352 [BuoyancyServo] No Fault, FailCount= 1
2016-09-27T22:35:28.359Z,1475015728.359 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2016-09-27T22:35:28.457Z,1475015728.457 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:./bin/roweadcp -dev /dev/ttyB4 -b 9600 >& /dev/null &
2016-09-27T22:35:28.600Z,1475015728.601 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2016-09-27T22:35:28