2017-12-08T13:52:06.184Z,1512741126.184 [Supervisor](DEBUG): Initializing supervisor. 2017-12-08T13:52:06.186Z,1512741126.186 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-12-08T13:52:06.187Z,1512741126.187 [SyncHandler](INFO): Protected caller Thread ID is 769 2017-12-08T13:52:06.188Z,1512741126.188 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-12-08T13:52:06.188Z,1512741126.188 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-12-08T13:52:06.189Z,1512741126.189 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770 2017-12-08T13:52:06.192Z,1512741126.192 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-12-08T13:52:06.204Z,1512741126.204 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-12-08T13:52:06.205Z,1512741126.205 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-12-08T13:52:06.205Z,1512741126.205 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771 2017-12-08T13:52:06.206Z,1512741126.206 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-12-08T13:52:06.207Z,1512741126.207 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-12-08T13:52:06.207Z,1512741126.207 [logger ThreadHandler](INFO): Protected caller Thread ID is 772 2017-12-08T13:52:06.210Z,1512741126.210 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-12-08T13:52:06.210Z,1512741126.210 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-12-08T13:52:06.213Z,1512741126.213 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-12-08T13:52:06.415Z,1512741126.415 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-12-08T13:52:06.416Z,1512741126.416 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-12-08T13:52:06.567Z,1512741126.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-12-08T13:52:06.568Z,1512741126.568 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-12-08T13:52:06.940Z,1512741126.940 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-12-08T13:52:06.941Z,1512741126.941 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-12-08T13:52:07.088Z,1512741127.088 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-12-08T13:52:07.091Z,1512741127.091 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-12-08T13:52:07.179Z,1512741127.179 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-12-08T13:52:07.394Z,1512741127.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-12-08T13:52:07.395Z,1512741127.395 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-12-08T13:52:07.501Z,1512741127.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-12-08T13:52:07.503Z,1512741127.503 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-12-08T13:52:07.857Z,1512741127.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-12-08T13:52:07.858Z,1512741127.858 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-12-08T13:52:08.239Z,1512741128.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-12-08T13:52:08.240Z,1512741128.240 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-12-08T13:52:08.571Z,1512741128.571 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-12-08T13:52:08.572Z,1512741128.572 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-12-08T13:52:09.116Z,1512741129.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-12-08T13:52:09.117Z,1512741129.117 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-12-08T13:52:13.889Z,1512741133.889 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-12-08T13:52:13.891Z,1512741133.891 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-12-08T13:52:13.998Z,1512741133.998 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-12-08T13:52:13.999Z,1512741133.999 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-12-08T13:52:14.504Z,1512741134.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-12-08T13:52:14.504Z,1512741134.504 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-12-08T13:52:16.097Z,1512741136.097 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-12-08T13:52:16.099Z,1512741136.099 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2017-12-08T13:52:16.102Z,1512741136.102 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2017-12-08T13:52:16.337Z,1512741136.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-12-08T13:52:16.338Z,1512741136.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2017-12-08T13:52:16.446Z,1512741136.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2017-12-08T13:52:16.560Z,1512741136.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2017-12-08T13:52:16.668Z,1512741136.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2017-12-08T13:52:16.762Z,1512741136.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2017-12-08T13:52:16.924Z,1512741136.924 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2017-12-08T13:52:17.113Z,1512741137.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2017-12-08T13:52:17.232Z,1512741137.232 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2017-12-08T13:52:17.339Z,1512741137.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2017-12-08T13:52:17.436Z,1512741137.436 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2017-12-08T13:52:17.622Z,1512741137.622 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-12-08T13:52:17.625Z,1512741137.625 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-12-08T13:52:17.775Z,1512741137.775 [SBIT](DEBUG): Construct Startup Built In Test. 2017-12-08T13:52:17.806Z,1512741137.806 [SBIT] Loaded 2017-12-08T13:52:17.806Z,1512741137.806 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-12-08T13:52:17.807Z,1512741137.807 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-12-08T13:52:17.836Z,1512741137.836 [IBIT] Loaded 2017-12-08T13:52:17.836Z,1512741137.836 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-12-08T13:52:17.840Z,1512741137.840 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-12-08T13:52:17.996Z,1512741137.996 [CBIT] Loaded 2017-12-08T13:52:17.996Z,1512741137.996 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-12-08T13:52:17.996Z,1512741137.996 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-12-08T13:52:17.997Z,1512741137.997 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-12-08T13:52:18.085Z,1512741138.085 [VerticalControl](DEBUG): Construct VerticalControl. 2017-12-08T13:52:18.188Z,1512741138.188 [VerticalControl] Loaded 2017-12-08T13:52:18.188Z,1512741138.188 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-12-08T13:52:18.189Z,1512741138.189 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-12-08T13:52:18.251Z,1512741138.251 [HorizontalControl] Loaded 2017-12-08T13:52:18.251Z,1512741138.251 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-12-08T13:52:18.252Z,1512741138.252 [SpeedControl](DEBUG): Construct SpeedControl. 2017-12-08T13:52:18.258Z,1512741138.258 [SpeedControl] Loaded 2017-12-08T13:52:18.258Z,1512741138.258 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-12-08T13:52:18.259Z,1512741138.259 [LoopControl](DEBUG): Construct LoopControl. 2017-12-08T13:52:18.260Z,1512741138.260 [LoopControl] Loaded 2017-12-08T13:52:18.260Z,1512741138.260 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-12-08T13:52:18.260Z,1512741138.260 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-12-08T13:52:18.261Z,1512741138.261 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-12-08T13:52:18.315Z,1512741138.315 [DepthRateCalculator] Loaded 2017-12-08T13:52:18.316Z,1512741138.316 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-12-08T13:52:18.321Z,1512741138.321 [PitchRateCalculator] Loaded 2017-12-08T13:52:18.321Z,1512741138.321 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-12-08T13:52:18.337Z,1512741138.337 [SpeedCalculator] Loaded 2017-12-08T13:52:18.337Z,1512741138.337 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-12-08T13:52:18.359Z,1512741138.359 [TempGradientCalculator] Loaded 2017-12-08T13:52:18.360Z,1512741138.360 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-12-08T13:52:18.375Z,1512741138.375 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-12-08T13:52:18.376Z,1512741138.376 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-12-08T13:52:18.381Z,1512741138.381 [YawRateCalculator] Loaded 2017-12-08T13:52:18.381Z,1512741138.381 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-12-08T13:52:18.382Z,1512741138.382 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-12-08T13:52:18.382Z,1512741138.382 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-12-08T13:52:18.506Z,1512741138.506 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-12-08T13:52:18.506Z,1512741138.506 [StratificationFrontDetector](DEBUG): (re)initializing 2017-12-08T13:52:18.507Z,1512741138.507 [StratificationFrontDetector] Loaded 2017-12-08T13:52:18.507Z,1512741138.507 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-12-08T13:52:18.508Z,1512741138.508 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-12-08T13:52:18.508Z,1512741138.508 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-12-08T13:52:18.778Z,1512741138.778 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-12-08T13:52:18.778Z,1512741138.778 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-12-08T13:52:18.895Z,1512741138.895 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-12-08T13:52:18.895Z,1512741138.895 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-12-08T13:52:18.950Z,1512741138.950 [DeadReckonUsingSpeedCalculator] Loaded 2017-12-08T13:52:18.951Z,1512741138.951 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-12-08T13:52:19.006Z,1512741139.006 [DeadReckonWithRespectToSeafloor] Loaded 2017-12-08T13:52:19.006Z,1512741139.006 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2017-12-08T13:52:19.023Z,1512741139.023 [NavChart] Loaded 2017-12-08T13:52:19.023Z,1512741139.023 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-12-08T13:52:19.027Z,1512741139.027 [UniversalFixResidualReporter] Loaded 2017-12-08T13:52:19.027Z,1512741139.027 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-12-08T13:52:19.028Z,1512741139.028 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-12-08T13:52:19.029Z,1512741139.029 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-12-08T13:52:19.054Z,1512741139.054 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-12-08T13:52:19.055Z,1512741139.055 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-12-08T13:52:19.253Z,1512741139.253 [Aanderaa_O2] Loaded 2017-12-08T13:52:19.253Z,1512741139.253 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-12-08T13:52:19.289Z,1512741139.289 [CTD_Seabird] Loaded 2017-12-08T13:52:19.289Z,1512741139.289 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-12-08T13:52:19.290Z,1512741139.290 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407984E0 2017-12-08T13:52:19.291Z,1512741139.291 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 852 2017-12-08T13:52:19.306Z,1512741139.306 [PAR_Licor] Loaded 2017-12-08T13:52:19.307Z,1512741139.307 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-12-08T13:52:19.356Z,1512741139.356 [WetLabsBB2FL] Loaded 2017-12-08T13:52:19.357Z,1512741139.357 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-12-08T13:52:19.358Z,1512741139.358 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407C84E0 2017-12-08T13:52:19.358Z,1512741139.358 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 853 2017-12-08T13:52:19.402Z,1512741139.402 [WetLabsSeaOWL_UV_A] Loaded 2017-12-08T13:52:19.402Z,1512741139.402 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2017-12-08T13:52:19.403Z,1512741139.403 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407F84E0 2017-12-08T13:52:19.404Z,1512741139.404 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 854 2017-12-08T13:52:19.404Z,1512741139.404 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-12-08T13:52:19.405Z,1512741139.405 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-12-08T13:52:20.017Z,1512741140.017 [DataOverHttps] Loaded 2017-12-08T13:52:20.017Z,1512741140.017 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-12-08T13:52:20.031Z,1512741140.031 [Depth_Keller] Loaded 2017-12-08T13:52:20.031Z,1512741140.031 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-12-08T13:52:20.036Z,1512741140.036 [DropWeight] Loaded 2017-12-08T13:52:20.037Z,1512741140.037 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-12-08T13:52:20.160Z,1512741140.160 [NAL9602] Loaded 2017-12-08T13:52:20.160Z,1512741140.160 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-12-08T13:52:20.191Z,1512741140.191 [Onboard] Loaded 2017-12-08T13:52:20.192Z,1512741140.192 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-12-08T13:52:20.199Z,1512741140.199 [Radio_Surface] Loaded 2017-12-08T13:52:20.199Z,1512741140.199 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-12-08T13:52:20.200Z,1512741140.200 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A404E0 2017-12-08T13:52:20.200Z,1512741140.200 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 855 2017-12-08T13:52:20.393Z,1512741140.393 [PNI_TCM] Loaded 2017-12-08T13:52:20.394Z,1512741140.394 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-12-08T13:52:20.454Z,1512741140.454 [Rowe_600LCM] Loaded 2017-12-08T13:52:20.454Z,1512741140.454 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-12-08T13:52:20.455Z,1512741140.455 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A704E0 2017-12-08T13:52:20.456Z,1512741140.456 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 856 2017-12-08T13:52:22.313Z,1512741142.313 [BPC1] Loaded 2017-12-08T13:52:22.314Z,1512741142.314 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-12-08T13:52:22.314Z,1512741142.314 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-12-08T13:52:22.315Z,1512741142.315 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-12-08T13:52:22.434Z,1512741142.434 [BuoyancyServo] Loaded 2017-12-08T13:52:22.434Z,1512741142.434 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-12-08T13:52:22.446Z,1512741142.446 [ElevatorServo] Loaded 2017-12-08T13:52:22.446Z,1512741142.446 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-12-08T13:52:22.458Z,1512741142.458 [MassServo] Loaded 2017-12-08T13:52:22.458Z,1512741142.458 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-12-08T13:52:22.470Z,1512741142.470 [RudderServo] Loaded 2017-12-08T13:52:22.470Z,1512741142.470 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-12-08T13:52:22.482Z,1512741142.482 [ThrusterServo] Loaded 2017-12-08T13:52:22.482Z,1512741142.482 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-12-08T13:52:22.482Z,1512741142.482 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-12-08T13:52:22.483Z,1512741142.483 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-12-08T13:52:22.607Z,1512741142.607 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-12-08T13:52:22.608Z,1512741142.608 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-12-08T13:52:22.646Z,1512741142.646 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-12-08T13:52:22.650Z,1512741142.650 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-12-08T13:52:22.651Z,1512741142.651 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-12-08T13:52:22.658Z,1512741142.658 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-12-08T13:52:22.659Z,1512741142.659 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B174E0 2017-12-08T13:52:22.660Z,1512741142.660 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 857 2017-12-08T13:52:22.664Z,1512741142.664 [Supervisor](INFO): Main Thread ID is 764 2017-12-08T13:52:22.665Z,1512741142.665 [Supervisor](DEBUG): Running supervisor. 2017-12-08T13:52:22.665Z,1512741142.665 [CommandLine ThreadHandler](INFO): Handler Thread ID is 858 2017-12-08T13:52:22.668Z,1512741142.668 [controlThread ThreadHandler](INFO): Handler Thread ID is 859 2017-12-08T13:52:22.668Z,1512741142.668 [controlThread](DEBUG): Initializing ControlThread 2017-12-08T13:52:22.669Z,1512741142.669 [SBIT](INFO): Initialize SBIT Component. 2017-12-08T13:52:22.670Z,1512741142.670 [SBIT](IMPORTANT): git: 2017-11-20-3-gb0389ee 2017-12-08T13:52:22.670Z,1512741142.670 [SBIT](INFO): git hash: b0389eed17bddc8251b1231910f9148d930dbf7d 2017-12-08T13:52:22.672Z,1512741142.672 [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-08T13:52:22.672Z,1512741142.672 [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-08T13:52:22.673Z,1512741142.673 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2017-12-08T13:52:22.674Z,1512741142.674 [IBIT](INFO): Initialize IBIT Component. 2017-12-08T13:52:22.675Z,1512741142.675 [CBIT](DEBUG): Initialize CBIT Component. 2017-12-08T13:52:22.675Z,1512741142.675 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-12-08T13:52:22.675Z,1512741142.675 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2017-12-08T13:52:22.676Z,1512741142.676 [logger ThreadHandler](INFO): Handler Thread ID is 860 2017-12-08T13:52:22.704Z,1512741142.704 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 861 2017-12-08T13:52:22.704Z,1512741142.704 [CTD_Seabird](INFO): Initializing 2017-12-08T13:52:22.748Z,1512741142.748 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 863 2017-12-08T13:52:22.750Z,1512741142.750 [WetLabsBB2FL](INFO): Powering down 2017-12-08T13:52:22.760Z,1512741142.760 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-12-08T13:52:22.762Z,1512741142.762 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-12-08T13:52:22.763Z,1512741142.763 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-12-08T13:52:22.764Z,1512741142.764 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-12-08T13:52:22.764Z,1512741142.764 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-12-08T13:52:22.764Z,1512741142.764 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-12-08T13:52:22.765Z,1512741142.765 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-12-08T13:52:22.765Z,1512741142.765 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-12-08T13:52:22.766Z,1512741142.766 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-12-08T13:52:22.767Z,1512741142.767 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-12-08T13:52:22.768Z,1512741142.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-08T13:52:22.769Z,1512741142.769 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-08T13:52:22.769Z,1512741142.769 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-08T13:52:22.770Z,1512741142.770 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-08T13:52:22.770Z,1512741142.770 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-08T13:52:22.771Z,1512741142.771 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-08T13:52:22.771Z,1512741142.771 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-12-08T13:52:22.772Z,1512741142.772 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-12-08T13:52:22.772Z,1512741142.772 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-12-08T13:52:22.772Z,1512741142.772 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-12-08T13:52:22.773Z,1512741142.773 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-12-08T13:52:22.790Z,1512741142.790 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-12-08T13:52:22.820Z,1512741142.820 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 864 2017-12-08T13:52:22.831Z,1512741142.831 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-08T13:52:22.840Z,1512741142.840 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 865 2017-12-08T13:52:22.846Z,1512741142.846 [Radio_Surface](INFO): Powering up 2017-12-08T13:52:22.860Z,1512741142.860 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 866 2017-12-08T13:52:22.861Z,1512741142.861 [Rowe_600LCM](INFO): Initializing 2017-12-08T13:52:22.862Z,1512741142.862 [Rowe_600LCM](INFO): Checking LCM 2017-12-08T13:52:22.929Z,1512741142.929 [MissionManager](DEBUG): 2017-12-08T13:52:22.930Z,1512741142.930 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 867 2017-12-08T13:52:22.945Z,1512741142.945 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-12-08T13:52:23.002Z,1512741143.002 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-12-08T13:52:23.002Z,1512741143.002 [CTD_Seabird](INFO): Powering down 2017-12-08T13:52:23.045Z,1512741143.045 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-12-08T13:52:23.045Z,1512741143.045 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-12-08T13:52:23.045Z,1512741143.045 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-12-08T13:52:23.046Z,1512741143.046 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-12-08T13:52:23.046Z,1512741143.046 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-12-08T13:52:23.046Z,1512741143.046 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-12-08T13:52:23.046Z,1512741143.046 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-12-08T13:52:23.046Z,1512741143.046 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-12-08T13:52:23.047Z,1512741143.047 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-12-08T13:52:23.047Z,1512741143.047 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-12-08T13:52:23.047Z,1512741143.047 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-12-08T13:52:23.047Z,1512741143.047 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-12-08T13:52:23.060Z,1512741143.060 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-12-08T13:52:23.060Z,1512741143.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-12-08T13:52:23.060Z,1512741143.060 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-12-08T13:52:23.060Z,1512741143.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-12-08T13:52:23.249Z,1512741143.249 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-12-08T13:52:23.250Z,1512741143.250 [Default:A.Wait](DEBUG): Construct Wait. 2017-12-08T13:52:23.252Z,1512741143.252 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-08T13:52:23.312Z,1512741143.312 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-12-08T13:52:23.315Z,1512741143.315 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-12-08T13:52:23.341Z,1512741143.341 [Default:E.Execute](DEBUG): Construct Execute. 2017-12-08T13:52:23.448Z,1512741143.448 [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-08T13:52:23.461Z,1512741143.461 [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-08T13:52:23.557Z,1512741143.557 [DataOverHttps](DEBUG): dashIP=mivegas.soest.hawaii.edu does not start with a digit so assuming it is a host name 2017-12-08T13:52:23.666Z,1512741143.666 [Rowe_600LCM](INFO): LCM OK 2017-12-08T13:52:23.666Z,1512741143.666 [Rowe_600LCM](INFO): Powering up 2017-12-08T13:52:23.679Z,1512741143.679 [DataOverHttps] Hardware Fault, FailCount= 1 2017-12-08T13:52:23.679Z,1512741143.679 [DataOverHttps](ERROR): Hardware Fault 2017-12-08T13:52:23.680Z,1512741143.680 [DataOverHttps](FAULT): Error resolving dash IP address & port: mivegas.soest.hawaii.edu:80. Error is Temporary failure in name resolution 2017-12-08T13:52:24.170Z,1512741144.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:24.170Z,1512741144.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T13:52:24.304Z,1512741144.304 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T13:52:24.342Z,1512741144.342 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:24.348Z,1512741144.348 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:24.486Z,1512741144.486 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-12-08T13:52:24.490Z,1512741144.490 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-12-08T13:52:24.517Z,1512741144.517 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-12-08T13:52:24.522Z,1512741144.522 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-12-08T13:52:24.536Z,1512741144.536 [MassServo](DEBUG): Initializing EZServoServo. 2017-12-08T13:52:24.542Z,1512741144.542 [MassServo](DEBUG): Initializing MassServo. 2017-12-08T13:52:24.556Z,1512741144.556 [RudderServo](DEBUG): Initializing EZServoServo. 2017-12-08T13:52:24.562Z,1512741144.562 [RudderServo](DEBUG): Initializing RudderServo. 2017-12-08T13:52:24.574Z,1512741144.574 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-12-08T13:52:24.582Z,1512741144.582 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-12-08T13:52:24.631Z,1512741144.631 [CommandLine](FAULT): Scheduling is paused 2017-12-08T13:52:24.632Z,1512741144.632 [CBIT](INFO): Critical error at 20171208T135222 2017-12-08T13:52:24.632Z,1512741144.632 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-12-08T13:52:24.635Z,1512741144.635 [CBIT](ERROR): Hardware Fault in component: DataOverHttps 2017-12-08T13:52:24.636Z,1512741144.636 [CBIT](FAULT): Hardware Fault in component: DataOverHttps 2017-12-08T13:52:24.842Z,1512741144.842 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2017-12-08T13:52:24.842Z,1512741144.842 [DropWeight] Hardware Fault, FailCount= 1 2017-12-08T13:52:24.843Z,1512741144.843 [DropWeight](ERROR): Hardware Fault 2017-12-08T13:52:24.895Z,1512741144.895 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:24.896Z,1512741144.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T13:52:24.897Z,1512741144.897 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T13:52:24.897Z,1512741144.897 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:24.898Z,1512741144.898 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:24.932Z,1512741144.932 [CBIT](INFO): Critical error at 20171208T135224 2017-12-08T13:52:24.943Z,1512741144.943 [CBIT](ERROR): Hardware Fault in component: DropWeight 2017-12-08T13:52:24.943Z,1512741144.943 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2017-12-08T13:52:25.122Z,1512741145.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:25.123Z,1512741145.123 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T13:52:25.124Z,1512741145.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T13:52:25.127Z,1512741145.127 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:25.128Z,1512741145.128 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:25.472Z,1512741145.472 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-12-08T13:52:25.472Z,1512741145.472 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-12-08T13:52:25.472Z,1512741145.472 [BuoyancyServo] Communications Fault, FailCount= 1 2017-12-08T13:52:25.472Z,1512741145.472 [BuoyancyServo](ERROR): Communications Fault 2017-12-08T13:52:25.678Z,1512741145.678 [CBIT](INFO): Critical error at 20171208T135224 2017-12-08T13:52:25.680Z,1512741145.680 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-12-08T13:52:25.773Z,1512741145.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:25.773Z,1512741145.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T13:52:25.774Z,1512741145.774 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T13:52:25.775Z,1512741145.775 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:25.796Z,1512741145.796 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:25.810Z,1512741145.810 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-12-08T13:52:25.811Z,1512741145.811 [BuoyancyServo](INFO): Powering down 2017-12-08T13:52:26.144Z,1512741146.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:26.144Z,1512741146.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T13:52:26.145Z,1512741146.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T13:52:26.146Z,1512741146.146 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:26.147Z,1512741146.147 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:26.525Z,1512741146.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:26.525Z,1512741146.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T13:52:26.526Z,1512741146.526 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T13:52:26.533Z,1512741146.533 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:26.534Z,1512741146.534 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:26.920Z,1512741146.920 [Aanderaa_O2](INFO): Powering down 2017-12-08T13:52:27.044Z,1512741147.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:27.044Z,1512741147.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T13:52:27.045Z,1512741147.045 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T13:52:27.054Z,1512741147.054 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:27.055Z,1512741147.055 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:27.312Z,1512741147.312 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-08T13:52:27.358Z,1512741147.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T13:52:27.358Z,1512741147.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-12-08T13:52:27.360Z,1512741147.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-08T13:52:27.360Z,1512741147.360 [DeadReckonUsingSpeedCalculator](DEBUG):