2017-12-08T12:50:33.154Z,1512737433.154 [Supervisor](DEBUG): Initializing supervisor. 2017-12-08T12:50:33.157Z,1512737433.157 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-12-08T12:50:33.158Z,1512737433.158 [SyncHandler](INFO): Protected caller Thread ID is 770 2017-12-08T12:50:33.158Z,1512737433.158 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-12-08T12:50:33.159Z,1512737433.159 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-12-08T12:50:33.160Z,1512737433.160 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 771 2017-12-08T12:50:33.162Z,1512737433.162 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-12-08T12:50:33.173Z,1512737433.173 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-12-08T12:50:33.174Z,1512737433.174 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-12-08T12:50:33.175Z,1512737433.175 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 772 2017-12-08T12:50:33.176Z,1512737433.176 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-12-08T12:50:33.176Z,1512737433.176 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-12-08T12:50:33.177Z,1512737433.177 [logger ThreadHandler](INFO): Protected caller Thread ID is 773 2017-12-08T12:50:33.179Z,1512737433.179 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-12-08T12:50:33.179Z,1512737433.179 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-12-08T12:50:33.182Z,1512737433.182 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-12-08T12:50:33.373Z,1512737433.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-12-08T12:50:33.374Z,1512737433.374 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-12-08T12:50:33.519Z,1512737433.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-12-08T12:50:33.520Z,1512737433.520 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-12-08T12:50:33.863Z,1512737433.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-12-08T12:50:33.863Z,1512737433.863 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-12-08T12:50:34.005Z,1512737434.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-12-08T12:50:34.008Z,1512737434.008 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-12-08T12:50:34.095Z,1512737434.095 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-12-08T12:50:34.297Z,1512737434.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-12-08T12:50:34.298Z,1512737434.298 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-12-08T12:50:34.402Z,1512737434.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-12-08T12:50:34.404Z,1512737434.404 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-12-08T12:50:34.800Z,1512737434.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-12-08T12:50:34.801Z,1512737434.801 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-12-08T12:50:35.507Z,1512737435.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-12-08T12:50:35.508Z,1512737435.508 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-12-08T12:50:36.115Z,1512737436.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-12-08T12:50:36.116Z,1512737436.116 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-12-08T12:50:40.143Z,1512737440.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-12-08T12:50:40.143Z,1512737440.143 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-12-08T12:50:40.350Z,1512737440.350 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-12-08T12:50:40.353Z,1512737440.353 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-12-08T12:50:40.457Z,1512737440.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-12-08T12:50:40.458Z,1512737440.458 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-12-08T12:50:40.929Z,1512737440.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-12-08T12:50:40.929Z,1512737440.929 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-12-08T12:50:41.042Z,1512737441.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-12-08T12:50:41.044Z,1512737441.044 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2017-12-08T12:50:41.047Z,1512737441.047 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2017-12-08T12:50:41.279Z,1512737441.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-12-08T12:50:41.280Z,1512737441.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2017-12-08T12:50:44.114Z,1512737444.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2017-12-08T12:50:44.227Z,1512737444.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2017-12-08T12:50:44.331Z,1512737444.331 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2017-12-08T12:50:44.423Z,1512737444.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2017-12-08T12:50:44.580Z,1512737444.580 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2017-12-08T12:50:44.762Z,1512737444.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2017-12-08T12:50:44.878Z,1512737444.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2017-12-08T12:50:44.982Z,1512737444.982 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2017-12-08T12:50:45.079Z,1512737445.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2017-12-08T12:50:45.257Z,1512737445.257 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-12-08T12:50:45.260Z,1512737445.260 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-12-08T12:50:45.409Z,1512737445.409 [SBIT](DEBUG): Construct Startup Built In Test. 2017-12-08T12:50:45.439Z,1512737445.439 [SBIT] Loaded 2017-12-08T12:50:45.439Z,1512737445.439 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-12-08T12:50:45.440Z,1512737445.440 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-12-08T12:50:45.468Z,1512737445.468 [IBIT] Loaded 2017-12-08T12:50:45.468Z,1512737445.468 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-12-08T12:50:45.472Z,1512737445.472 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-12-08T12:50:45.625Z,1512737445.625 [CBIT] Loaded 2017-12-08T12:50:45.625Z,1512737445.625 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-12-08T12:50:45.625Z,1512737445.625 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-12-08T12:50:45.626Z,1512737445.626 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-12-08T12:50:45.713Z,1512737445.713 [VerticalControl](DEBUG): Construct VerticalControl. 2017-12-08T12:50:45.812Z,1512737445.812 [VerticalControl] Loaded 2017-12-08T12:50:45.812Z,1512737445.812 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-12-08T12:50:45.813Z,1512737445.813 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-12-08T12:50:45.873Z,1512737445.873 [HorizontalControl] Loaded 2017-12-08T12:50:45.874Z,1512737445.874 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-12-08T12:50:45.874Z,1512737445.874 [SpeedControl](DEBUG): Construct SpeedControl. 2017-12-08T12:50:45.880Z,1512737445.880 [SpeedControl] Loaded 2017-12-08T12:50:45.880Z,1512737445.880 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-12-08T12:50:45.881Z,1512737445.881 [LoopControl](DEBUG): Construct LoopControl. 2017-12-08T12:50:45.881Z,1512737445.881 [LoopControl] Loaded 2017-12-08T12:50:45.882Z,1512737445.882 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-12-08T12:50:45.882Z,1512737445.882 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-12-08T12:50:45.883Z,1512737445.883 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-12-08T12:50:45.937Z,1512737445.937 [DepthRateCalculator] Loaded 2017-12-08T12:50:45.937Z,1512737445.937 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-12-08T12:50:45.942Z,1512737445.942 [PitchRateCalculator] Loaded 2017-12-08T12:50:45.942Z,1512737445.942 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-12-08T12:50:45.958Z,1512737445.958 [SpeedCalculator] Loaded 2017-12-08T12:50:45.958Z,1512737445.958 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-12-08T12:50:45.979Z,1512737445.979 [TempGradientCalculator] Loaded 2017-12-08T12:50:45.980Z,1512737445.980 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-12-08T12:50:45.995Z,1512737445.995 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-12-08T12:50:45.995Z,1512737445.995 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-12-08T12:50:46.000Z,1512737446.000 [YawRateCalculator] Loaded 2017-12-08T12:50:46.000Z,1512737446.000 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-12-08T12:50:46.001Z,1512737446.001 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-12-08T12:50:46.002Z,1512737446.002 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-12-08T12:50:46.124Z,1512737446.124 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-12-08T12:50:46.124Z,1512737446.124 [StratificationFrontDetector](DEBUG): (re)initializing 2017-12-08T12:50:46.125Z,1512737446.125 [StratificationFrontDetector] Loaded 2017-12-08T12:50:46.125Z,1512737446.125 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-12-08T12:50:46.125Z,1512737446.125 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-12-08T12:50:46.126Z,1512737446.126 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-12-08T12:50:46.415Z,1512737446.415 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-12-08T12:50:46.415Z,1512737446.415 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-12-08T12:50:46.565Z,1512737446.565 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-12-08T12:50:46.565Z,1512737446.565 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-12-08T12:50:46.619Z,1512737446.619 [DeadReckonUsingSpeedCalculator] Loaded 2017-12-08T12:50:46.619Z,1512737446.619 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-12-08T12:50:46.673Z,1512737446.673 [DeadReckonWithRespectToSeafloor] Loaded 2017-12-08T12:50:46.674Z,1512737446.674 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2017-12-08T12:50:46.690Z,1512737446.690 [NavChart] Loaded 2017-12-08T12:50:46.690Z,1512737446.690 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-12-08T12:50:46.694Z,1512737446.694 [UniversalFixResidualReporter] Loaded 2017-12-08T12:50:46.694Z,1512737446.694 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-12-08T12:50:46.695Z,1512737446.695 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-12-08T12:50:46.696Z,1512737446.696 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-12-08T12:50:46.721Z,1512737446.721 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-12-08T12:50:46.722Z,1512737446.722 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-12-08T12:50:46.916Z,1512737446.916 [Aanderaa_O2] Loaded 2017-12-08T12:50:46.916Z,1512737446.916 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-12-08T12:50:46.950Z,1512737446.950 [CTD_Seabird] Loaded 2017-12-08T12:50:46.951Z,1512737446.951 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-12-08T12:50:46.952Z,1512737446.952 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407984E0 2017-12-08T12:50:46.952Z,1512737446.952 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 852 2017-12-08T12:50:46.967Z,1512737446.967 [PAR_Licor] Loaded 2017-12-08T12:50:46.967Z,1512737446.967 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-12-08T12:50:47.016Z,1512737447.016 [WetLabsBB2FL] Loaded 2017-12-08T12:50:47.016Z,1512737447.016 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-12-08T12:50:47.017Z,1512737447.017 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407C84E0 2017-12-08T12:50:47.018Z,1512737447.018 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 853 2017-12-08T12:50:47.060Z,1512737447.060 [WetLabsSeaOWL_UV_A] Loaded 2017-12-08T12:50:47.061Z,1512737447.061 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2017-12-08T12:50:47.062Z,1512737447.062 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407F84E0 2017-12-08T12:50:47.062Z,1512737447.062 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 854 2017-12-08T12:50:47.063Z,1512737447.063 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-12-08T12:50:47.063Z,1512737447.063 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-12-08T12:50:47.673Z,1512737447.673 [DataOverHttps] Loaded 2017-12-08T12:50:47.673Z,1512737447.673 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-12-08T12:50:47.687Z,1512737447.687 [Depth_Keller] Loaded 2017-12-08T12:50:47.687Z,1512737447.687 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-12-08T12:50:47.692Z,1512737447.692 [DropWeight] Loaded 2017-12-08T12:50:47.692Z,1512737447.692 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-12-08T12:50:47.782Z,1512737447.782 [NAL9602] Loaded 2017-12-08T12:50:47.783Z,1512737447.783 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-12-08T12:50:47.788Z,1512737447.788 [Onboard] Loaded 2017-12-08T12:50:47.788Z,1512737447.788 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-12-08T12:50:47.795Z,1512737447.795 [Radio_Surface] Loaded 2017-12-08T12:50:47.796Z,1512737447.796 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-12-08T12:50:47.797Z,1512737447.797 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A404E0 2017-12-08T12:50:47.797Z,1512737447.797 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 855 2017-12-08T12:50:47.989Z,1512737447.989 [PNI_TCM] Loaded 2017-12-08T12:50:47.989Z,1512737447.989 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-12-08T12:50:48.048Z,1512737448.048 [Rowe_600LCM] Loaded 2017-12-08T12:50:48.049Z,1512737448.049 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-12-08T12:50:48.050Z,1512737448.050 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A704E0 2017-12-08T12:50:48.050Z,1512737448.050 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 856 2017-12-08T12:50:49.838Z,1512737449.838 [BPC1] Loaded 2017-12-08T12:50:49.838Z,1512737449.838 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-12-08T12:50:49.839Z,1512737449.839 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-12-08T12:50:49.839Z,1512737449.839 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-12-08T12:50:49.956Z,1512737449.956 [BuoyancyServo] Loaded 2017-12-08T12:50:49.956Z,1512737449.956 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-12-08T12:50:49.968Z,1512737449.968 [ElevatorServo] Loaded 2017-12-08T12:50:49.968Z,1512737449.968 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-12-08T12:50:49.980Z,1512737449.980 [MassServo] Loaded 2017-12-08T12:50:49.980Z,1512737449.980 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-12-08T12:50:49.991Z,1512737449.991 [RudderServo] Loaded 2017-12-08T12:50:49.992Z,1512737449.992 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-12-08T12:50:50.003Z,1512737450.003 [ThrusterServo] Loaded 2017-12-08T12:50:50.003Z,1512737450.003 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-12-08T12:50:50.004Z,1512737450.004 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-12-08T12:50:50.004Z,1512737450.004 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-12-08T12:50:50.127Z,1512737450.127 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-12-08T12:50:50.127Z,1512737450.127 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-12-08T12:50:50.165Z,1512737450.165 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-12-08T12:50:50.169Z,1512737450.169 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-12-08T12:50:50.170Z,1512737450.170 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-12-08T12:50:50.177Z,1512737450.177 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-12-08T12:50:50.178Z,1512737450.178 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B174E0 2017-12-08T12:50:50.178Z,1512737450.178 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 857 2017-12-08T12:50:50.183Z,1512737450.183 [Supervisor](INFO): Main Thread ID is 766 2017-12-08T12:50:50.183Z,1512737450.183 [Supervisor](DEBUG): Running supervisor. 2017-12-08T12:50:50.184Z,1512737450.184 [CommandLine ThreadHandler](INFO): Handler Thread ID is 858 2017-12-08T12:50:50.187Z,1512737450.187 [controlThread ThreadHandler](INFO): Handler Thread ID is 859 2017-12-08T12:50:50.187Z,1512737450.187 [controlThread](DEBUG): Initializing ControlThread 2017-12-08T12:50:50.188Z,1512737450.188 [SBIT](INFO): Initialize SBIT Component. 2017-12-08T12:50:50.188Z,1512737450.188 [SBIT](IMPORTANT): git: 2017-11-20-3-gb0389ee 2017-12-08T12:50:50.189Z,1512737450.189 [SBIT](INFO): git hash: b0389eed17bddc8251b1231910f9148d930dbf7d 2017-12-08T12:50:50.190Z,1512737450.190 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-12-08T12:50:50.191Z,1512737450.191 [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 2017-12-08T12:50:50.192Z,1512737450.192 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2017-12-08T12:50:50.192Z,1512737450.192 [IBIT](INFO): Initialize IBIT Component. 2017-12-08T12:50:50.193Z,1512737450.193 [CBIT](DEBUG): Initialize CBIT Component. 2017-12-08T12:50:50.193Z,1512737450.193 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-12-08T12:50:50.194Z,1512737450.194 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2017-12-08T12:50:50.195Z,1512737450.195 [logger ThreadHandler](INFO): Handler Thread ID is 860 2017-12-08T12:50:50.222Z,1512737450.222 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 861 2017-12-08T12:50:50.223Z,1512737450.223 [CTD_Seabird](INFO): Initializing 2017-12-08T12:50:50.267Z,1512737450.267 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 863 2017-12-08T12:50:50.268Z,1512737450.268 [WetLabsBB2FL](INFO): Powering down 2017-12-08T12:50:50.278Z,1512737450.278 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-12-08T12:50:50.281Z,1512737450.281 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-12-08T12:50:50.281Z,1512737450.281 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-12-08T12:50:50.282Z,1512737450.282 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-12-08T12:50:50.283Z,1512737450.283 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-12-08T12:50:50.283Z,1512737450.283 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-12-08T12:50:50.283Z,1512737450.283 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-12-08T12:50:50.284Z,1512737450.284 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-12-08T12:50:50.284Z,1512737450.284 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-12-08T12:50:50.285Z,1512737450.285 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-12-08T12:50:50.286Z,1512737450.286 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-08T12:50:50.287Z,1512737450.287 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-08T12:50:50.288Z,1512737450.288 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-08T12:50:50.288Z,1512737450.288 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-08T12:50:50.289Z,1512737450.289 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-08T12:50:50.289Z,1512737450.289 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-08T12:50:50.290Z,1512737450.290 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-12-08T12:50:50.290Z,1512737450.290 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-12-08T12:50:50.290Z,1512737450.290 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-12-08T12:50:50.291Z,1512737450.291 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-12-08T12:50:50.291Z,1512737450.291 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-12-08T12:50:50.308Z,1512737450.308 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-12-08T12:50:50.324Z,1512737450.324 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 864 2017-12-08T12:50:50.346Z,1512737450.346 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-08T12:50:50.355Z,1512737450.355 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 865 2017-12-08T12:50:50.361Z,1512737450.361 [Radio_Surface](INFO): Powering up 2017-12-08T12:50:50.379Z,1512737450.379 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 866 2017-12-08T12:50:50.379Z,1512737450.379 [Rowe_600LCM](INFO): Initializing 2017-12-08T12:50:50.380Z,1512737450.380 [Rowe_600LCM](INFO): Checking LCM 2017-12-08T12:50:50.450Z,1512737450.450 [MissionManager](DEBUG): 2017-12-08T12:50:50.451Z,1512737450.451 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-12-08T12:50:50.452Z,1512737450.452 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 867 2017-12-08T12:50:50.465Z,1512737450.465 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-12-08T12:50:50.466Z,1512737450.466 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-12-08T12:50:50.466Z,1512737450.466 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-12-08T12:50:50.466Z,1512737450.466 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-12-08T12:50:50.466Z,1512737450.466 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-12-08T12:50:50.467Z,1512737450.467 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-12-08T12:50:50.467Z,1512737450.467 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-12-08T12:50:50.467Z,1512737450.467 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-12-08T12:50:50.467Z,1512737450.467 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-12-08T12:50:50.467Z,1512737450.467 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-12-08T12:50:50.468Z,1512737450.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-12-08T12:50:50.468Z,1512737450.468 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-12-08T12:50:50.468Z,1512737450.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-12-08T12:50:50.468Z,1512737450.468 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-12-08T12:50:50.468Z,1512737450.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-12-08T12:50:50.469Z,1512737450.469 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-12-08T12:50:50.583Z,1512737450.583 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-12-08T12:50:50.583Z,1512737450.583 [CTD_Seabird](INFO): Powering down 2017-12-08T12:50:50.606Z,1512737450.606 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-12-08T12:50:50.608Z,1512737450.608 [Default:A.Wait](DEBUG): Construct Wait. 2017-12-08T12:50:50.609Z,1512737450.609 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-08T12:50:50.648Z,1512737450.648 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-12-08T12:50:50.675Z,1512737450.675 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-12-08T12:50:50.692Z,1512737450.692 [Default:E.Execute](DEBUG): Construct Execute. 2017-12-08T12:50:50.812Z,1512737450.812 [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 2017-12-08T12:50:50.817Z,1512737450.817 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-12-08T12:50:50.916Z,1512737450.916 [DataOverHttps](DEBUG): dashIP=mivegas.soest.hawaii.edu does not start with a digit so assuming it is a host name 2017-12-08T12:50:51.159Z,1512737451.159 [DataOverHttps] Hardware Fault, FailCount= 1 2017-12-08T12:50:51.159Z,1512737451.159 [DataOverHttps](ERROR): Hardware Fault 2017-12-08T12:50:51.160Z,1512737451.160 [DataOverHttps](FAULT): Error resolving dash IP address & port: mivegas.soest.hawaii.edu:80. Error is Temporary failure in name resolution 2017-12-08T12:50:51.168Z,1512737451.168 [Depth_Keller](ERROR): Pressure reading out of range: 1727.332275 decibar 2017-12-08T12:50:51.193Z,1512737451.193 [Rowe_600LCM](INFO): LCM OK 2017-12-08T12:50:51.193Z,1512737451.193 [Rowe_600LCM](INFO): Powering up 2017-12-08T12:50:51.382Z,1512737451.382 [DepthRateCalculator](ERROR): Depth measurement is not active 2017-12-08T12:50:51.488Z,1512737451.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:51.488Z,1512737451.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T12:50:51.691Z,1512737451.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T12:50:51.720Z,1512737451.720 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:51.733Z,1512737451.733 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:51.838Z,1512737451.838 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-12-08T12:50:51.845Z,1512737451.845 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-12-08T12:50:51.854Z,1512737451.854 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-12-08T12:50:51.857Z,1512737451.857 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-12-08T12:50:51.878Z,1512737451.878 [MassServo](DEBUG): Initializing EZServoServo. 2017-12-08T12:50:51.885Z,1512737451.885 [MassServo](DEBUG): Initializing MassServo. 2017-12-08T12:50:51.891Z,1512737451.891 [RudderServo](DEBUG): Initializing EZServoServo. 2017-12-08T12:50:51.897Z,1512737451.897 [RudderServo](DEBUG): Initializing RudderServo. 2017-12-08T12:50:51.903Z,1512737451.903 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-12-08T12:50:51.909Z,1512737451.909 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-12-08T12:50:51.961Z,1512737451.961 [CBIT](ERROR): Hardware Fault in component: DataOverHttps 2017-12-08T12:50:51.961Z,1512737451.961 [CBIT](FAULT): Hardware Fault in component: DataOverHttps 2017-12-08T12:50:52.263Z,1512737452.263 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2017-12-08T12:50:52.263Z,1512737452.263 [DropWeight] Hardware Fault, FailCount= 1 2017-12-08T12:50:52.263Z,1512737452.263 [DropWeight](ERROR): Hardware Fault 2017-12-08T12:50:52.312Z,1512737452.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:52.312Z,1512737452.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T12:50:52.313Z,1512737452.313 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T12:50:52.321Z,1512737452.321 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:52.322Z,1512737452.322 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:52.339Z,1512737452.339 [CommandLine](FAULT): Scheduling is paused 2017-12-08T12:50:52.340Z,1512737452.340 [CBIT](INFO): Critical error at 20171208T125052 2017-12-08T12:50:52.340Z,1512737452.340 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-12-08T12:50:52.342Z,1512737452.342 [CBIT](ERROR): Hardware Fault in component: DropWeight 2017-12-08T12:50:52.343Z,1512737452.343 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2017-12-08T12:50:52.464Z,1512737452.464 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:52.464Z,1512737452.464 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T12:50:52.465Z,1512737452.465 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T12:50:52.474Z,1512737452.474 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:52.475Z,1512737452.475 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:52.814Z,1512737452.814 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-12-08T12:50:52.814Z,1512737452.814 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-12-08T12:50:52.814Z,1512737452.814 [BuoyancyServo] Communications Fault, FailCount= 1 2017-12-08T12:50:52.814Z,1512737452.814 [BuoyancyServo](ERROR): Communications Fault 2017-12-08T12:50:53.020Z,1512737453.020 [CBIT](INFO): Critical error at 20171208T125052 2017-12-08T12:50:53.022Z,1512737453.022 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-12-08T12:50:53.095Z,1512737453.095 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:53.095Z,1512737453.095 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T12:50:53.097Z,1512737453.097 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T12:50:53.098Z,1512737453.098 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:53.103Z,1512737453.103 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:53.124Z,1512737453.124 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-12-08T12:50:53.124Z,1512737453.124 [BuoyancyServo](INFO): Powering down 2017-12-08T12:50:53.486Z,1512737453.486 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:53.486Z,1512737453.486 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T12:50:53.488Z,1512737453.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T12:50:53.495Z,1512737453.495 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:53.496Z,1512737453.496 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:53.867Z,1512737453.867 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:53.867Z,1512737453.867 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T12:50:53.868Z,1512737453.868 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T12:50:53.884Z,1512737453.884 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:53.885Z,1512737453.885 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:54.352Z,1512737454.352 [Aanderaa_O2](INFO): Powering down 2017-12-08T12:50:54.537Z,1512737454.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:54.537Z,1512737454.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T12:50:54.538Z,1512737454.538 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T12:50:54.539Z,1512737454.539 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:54.540Z,1512737454.540 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:54.738Z,1512737454.738 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T12:50:54.739Z,1512737454.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T12:50:54.740Z,1512737454.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T12:50:54.740Z,1512737454.740 [DeadReckonUsingSpeedCalculator](DEBU