2018-03-19T19:29:09.917Z,1521487749.917 [Supervisor](DEBUG): Initializing supervisor. 2018-03-19T19:29:09.920Z,1521487749.920 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-03-19T19:29:09.921Z,1521487749.921 [SyncHandler](INFO): Protected caller Thread ID is 801 2018-03-19T19:29:09.921Z,1521487749.921 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-03-19T19:29:09.922Z,1521487749.922 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-03-19T19:29:09.922Z,1521487749.922 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 802 2018-03-19T19:29:09.925Z,1521487749.925 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-03-19T19:29:09.936Z,1521487749.936 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-03-19T19:29:09.937Z,1521487749.937 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-03-19T19:29:09.937Z,1521487749.937 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 803 2018-03-19T19:29:09.938Z,1521487749.938 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-03-19T19:29:09.938Z,1521487749.938 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-03-19T19:29:09.939Z,1521487749.939 [logger ThreadHandler](INFO): Protected caller Thread ID is 804 2018-03-19T19:29:09.941Z,1521487749.941 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-03-19T19:29:09.941Z,1521487749.941 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-03-19T19:29:09.945Z,1521487749.945 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-03-19T19:29:10.248Z,1521487750.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-03-19T19:29:10.249Z,1521487750.249 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-03-19T19:29:10.434Z,1521487750.434 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-03-19T19:29:10.435Z,1521487750.435 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-03-19T19:29:10.695Z,1521487750.695 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-03-19T19:29:10.697Z,1521487750.697 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-03-19T19:29:10.833Z,1521487750.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-03-19T19:29:10.834Z,1521487750.834 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-03-19T19:29:10.931Z,1521487750.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-03-19T19:29:10.932Z,1521487750.932 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-03-19T19:29:11.356Z,1521487751.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-03-19T19:29:11.357Z,1521487751.357 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-03-19T19:29:11.676Z,1521487751.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-03-19T19:29:11.678Z,1521487751.678 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-03-19T19:29:12.085Z,1521487752.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-03-19T19:29:12.087Z,1521487752.087 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-03-19T19:29:12.231Z,1521487752.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-03-19T19:29:12.232Z,1521487752.232 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-03-19T19:29:12.327Z,1521487752.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-03-19T19:29:12.328Z,1521487752.328 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-03-19T19:29:12.523Z,1521487752.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-03-19T19:29:12.524Z,1521487752.524 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-03-19T19:29:12.722Z,1521487752.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-03-19T19:29:12.723Z,1521487752.723 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-03-19T19:29:13.428Z,1521487753.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-03-19T19:29:13.429Z,1521487753.429 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-03-19T19:29:13.564Z,1521487753.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-03-19T19:29:13.565Z,1521487753.565 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-03-19T19:29:13.647Z,1521487753.647 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2018-03-19T19:29:13.650Z,1521487753.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2018-03-19T19:29:13.749Z,1521487753.749 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2018-03-19T19:29:13.839Z,1521487753.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2018-03-19T19:29:13.930Z,1521487753.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2018-03-19T19:29:14.015Z,1521487754.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2018-03-19T19:29:14.157Z,1521487754.157 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2018-03-19T19:29:14.321Z,1521487754.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2018-03-19T19:29:14.397Z,1521487754.397 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2018-03-19T19:29:14.617Z,1521487754.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-03-19T19:29:14.617Z,1521487754.617 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2018-03-19T19:29:14.700Z,1521487754.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2018-03-19T19:29:14.789Z,1521487754.789 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2018-03-19T19:29:14.886Z,1521487754.886 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2018-03-19T19:29:15.007Z,1521487755.007 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-03-19T19:29:15.014Z,1521487755.014 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-03-19T19:29:15.291Z,1521487755.291 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-03-19T19:29:15.292Z,1521487755.292 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-03-19T19:29:15.349Z,1521487755.349 [DepthRateCalculator] Loaded 2018-03-19T19:29:15.349Z,1521487755.349 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-03-19T19:29:15.355Z,1521487755.355 [PitchRateCalculator] Loaded 2018-03-19T19:29:15.355Z,1521487755.355 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-03-19T19:29:15.371Z,1521487755.371 [SpeedCalculator] Loaded 2018-03-19T19:29:15.371Z,1521487755.371 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-03-19T19:29:15.391Z,1521487755.391 [TempGradientCalculator] Loaded 2018-03-19T19:29:15.392Z,1521487755.392 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-03-19T19:29:15.406Z,1521487755.406 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-03-19T19:29:15.407Z,1521487755.407 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-03-19T19:29:15.412Z,1521487755.412 [YawRateCalculator] Loaded 2018-03-19T19:29:15.412Z,1521487755.412 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-03-19T19:29:15.451Z,1521487755.451 [ElevatorOffsetCalculator] Loaded 2018-03-19T19:29:15.451Z,1521487755.451 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-03-19T19:29:15.452Z,1521487755.452 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-03-19T19:29:15.452Z,1521487755.452 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-03-19T19:29:15.572Z,1521487755.572 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-03-19T19:29:15.572Z,1521487755.572 [StratificationFrontDetector](DEBUG): (re)initializing 2018-03-19T19:29:15.572Z,1521487755.572 [StratificationFrontDetector] Loaded 2018-03-19T19:29:15.572Z,1521487755.572 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-03-19T19:29:15.573Z,1521487755.573 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-03-19T19:29:15.573Z,1521487755.573 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-03-19T19:29:15.705Z,1521487755.705 [BuoyancyServo] Loaded 2018-03-19T19:29:15.706Z,1521487755.706 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-03-19T19:29:15.720Z,1521487755.720 [ElevatorServo] Loaded 2018-03-19T19:29:15.720Z,1521487755.720 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-03-19T19:29:15.735Z,1521487755.735 [MassServo] Loaded 2018-03-19T19:29:15.735Z,1521487755.735 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-03-19T19:29:15.749Z,1521487755.749 [RudderServo] Loaded 2018-03-19T19:29:15.750Z,1521487755.750 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-03-19T19:29:15.763Z,1521487755.763 [ThrusterServo] Loaded 2018-03-19T19:29:15.763Z,1521487755.763 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-03-19T19:29:15.764Z,1521487755.764 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-03-19T19:29:15.765Z,1521487755.765 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-03-19T19:29:15.788Z,1521487755.788 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-03-19T19:29:15.789Z,1521487755.789 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-03-19T19:29:15.913Z,1521487755.913 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-03-19T19:29:15.914Z,1521487755.914 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-03-19T19:29:16.444Z,1521487756.444 [DataOverHttps] Loaded 2018-03-19T19:29:16.445Z,1521487756.445 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-03-19T19:29:16.458Z,1521487756.458 [Depth_Keller] Loaded 2018-03-19T19:29:16.458Z,1521487756.458 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-03-19T19:29:16.463Z,1521487756.463 [DropWeight] Loaded 2018-03-19T19:29:16.463Z,1521487756.463 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-03-19T19:29:16.559Z,1521487756.559 [NAL9602] Loaded 2018-03-19T19:29:16.559Z,1521487756.559 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-03-19T19:29:16.575Z,1521487756.575 [Onboard] Loaded 2018-03-19T19:29:16.576Z,1521487756.576 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-03-19T19:29:16.586Z,1521487756.586 [Radio_Surface] Loaded 2018-03-19T19:29:16.586Z,1521487756.586 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-03-19T19:29:16.587Z,1521487756.587 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408724E0 2018-03-19T19:29:16.587Z,1521487756.587 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 887 2018-03-19T19:29:16.739Z,1521487756.739 [PNI_TCM] Loaded 2018-03-19T19:29:16.739Z,1521487756.739 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-03-19T19:29:18.443Z,1521487758.443 [BPC1] Loaded 2018-03-19T19:29:18.444Z,1521487758.444 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-03-19T19:29:18.444Z,1521487758.444 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-03-19T19:29:18.446Z,1521487758.446 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-03-19T19:29:18.896Z,1521487758.896 [SBIT](DEBUG): Construct Startup Built In Test. 2018-03-19T19:29:18.947Z,1521487758.947 [SBIT] Loaded 2018-03-19T19:29:18.947Z,1521487758.947 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-03-19T19:29:18.948Z,1521487758.948 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-03-19T19:29:19.002Z,1521487759.002 [IBIT] Loaded 2018-03-19T19:29:19.002Z,1521487759.002 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-03-19T19:29:19.005Z,1521487759.005 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-03-19T19:29:19.155Z,1521487759.155 [CBIT] Loaded 2018-03-19T19:29:19.155Z,1521487759.155 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-03-19T19:29:19.156Z,1521487759.156 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-03-19T19:29:19.156Z,1521487759.156 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-03-19T19:29:19.339Z,1521487759.339 [Aanderaa_O2] Loaded 2018-03-19T19:29:19.340Z,1521487759.340 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-03-19T19:29:19.398Z,1521487759.398 [CANONSampler] Loaded 2018-03-19T19:29:19.398Z,1521487759.398 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2018-03-19T19:29:19.600Z,1521487759.600 [CTD_NeilBrown] Loaded 2018-03-19T19:29:19.600Z,1521487759.600 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2018-03-19T19:29:19.601Z,1521487759.601 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409324E0 2018-03-19T19:29:19.601Z,1521487759.601 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 888 2018-03-19T19:29:19.647Z,1521487759.647 [CTD_Seabird] Loaded 2018-03-19T19:29:19.647Z,1521487759.647 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-03-19T19:29:19.648Z,1521487759.648 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409624E0 2018-03-19T19:29:19.648Z,1521487759.648 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 889 2018-03-19T19:29:19.662Z,1521487759.662 [PAR_Licor] Loaded 2018-03-19T19:29:19.662Z,1521487759.662 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-03-19T19:29:19.711Z,1521487759.711 [WetLabsBB2FL] Loaded 2018-03-19T19:29:19.711Z,1521487759.711 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-03-19T19:29:19.712Z,1521487759.712 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409924E0 2018-03-19T19:29:19.712Z,1521487759.712 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 890 2018-03-19T19:29:19.713Z,1521487759.713 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-03-19T19:29:19.714Z,1521487759.714 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-03-19T19:29:19.800Z,1521487759.800 [VerticalControl](DEBUG): Construct VerticalControl. 2018-03-19T19:29:19.882Z,1521487759.882 [VerticalControl] Loaded 2018-03-19T19:29:19.883Z,1521487759.883 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-03-19T19:29:19.883Z,1521487759.883 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-03-19T19:29:19.938Z,1521487759.938 [HorizontalControl] Loaded 2018-03-19T19:29:19.939Z,1521487759.939 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-03-19T19:29:19.939Z,1521487759.939 [SpeedControl](DEBUG): Construct SpeedControl. 2018-03-19T19:29:19.941Z,1521487759.941 [SpeedControl] Loaded 2018-03-19T19:29:19.941Z,1521487759.941 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-03-19T19:29:19.942Z,1521487759.942 [LoopControl](DEBUG): Construct LoopControl. 2018-03-19T19:29:19.943Z,1521487759.943 [LoopControl] Loaded 2018-03-19T19:29:19.943Z,1521487759.943 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-03-19T19:29:19.943Z,1521487759.943 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-03-19T19:29:19.944Z,1521487759.944 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-03-19T19:29:19.981Z,1521487759.981 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-03-19T19:29:19.982Z,1521487759.982 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-03-19T19:29:20.087Z,1521487760.087 [DeadReckonUsingSpeedCalculator] Loaded 2018-03-19T19:29:20.087Z,1521487760.087 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-03-19T19:29:20.141Z,1521487760.141 [DeadReckonWithRespectToSeafloor] Loaded 2018-03-19T19:29:20.141Z,1521487760.141 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2018-03-19T19:29:20.157Z,1521487760.157 [NavChart] Loaded 2018-03-19T19:29:20.158Z,1521487760.158 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-03-19T19:29:20.161Z,1521487760.161 [UniversalFixResidualReporter] Loaded 2018-03-19T19:29:20.162Z,1521487760.162 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-03-19T19:29:20.162Z,1521487760.162 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-03-19T19:29:20.166Z,1521487760.166 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-03-19T19:29:20.166Z,1521487760.166 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-03-19T19:29:20.173Z,1521487760.173 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-03-19T19:29:20.174Z,1521487760.174 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1E4E0 2018-03-19T19:29:20.174Z,1521487760.174 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 891 2018-03-19T19:29:20.178Z,1521487760.178 [Supervisor](INFO): Main Thread ID is 797 2018-03-19T19:29:20.178Z,1521487760.178 [Supervisor](DEBUG): Running supervisor. 2018-03-19T19:29:20.179Z,1521487760.179 [CommandLine ThreadHandler](INFO): Handler Thread ID is 892 2018-03-19T19:29:20.182Z,1521487760.182 [controlThread ThreadHandler](INFO): Handler Thread ID is 893 2018-03-19T19:29:20.182Z,1521487760.182 [controlThread](DEBUG): Initializing ControlThread 2018-03-19T19:29:20.183Z,1521487760.183 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-03-19T19:29:20.183Z,1521487760.183 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-03-19T19:29:20.184Z,1521487760.184 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-03-19T19:29:20.184Z,1521487760.184 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-03-19T19:29:20.185Z,1521487760.185 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-03-19T19:29:20.185Z,1521487760.185 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-03-19T19:29:20.186Z,1521487760.186 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-03-19T19:29:20.191Z,1521487760.191 [SBIT](INFO): Initialize SBIT Component. 2018-03-19T19:29:20.192Z,1521487760.192 [SBIT](IMPORTANT): git: 2018-03-19 2018-03-19T19:29:20.192Z,1521487760.192 [SBIT](INFO): git hash: a0816c0235cbc02280eea7af955e3502bfefb717 2018-03-19T19:29:20.192Z,1521487760.192 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-03-19T19:29:20.194Z,1521487760.194 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-03-19T19:29:20.195Z,1521487760.195 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2018-03-19T19:29:20.195Z,1521487760.195 [IBIT](INFO): Initialize IBIT Component. 2018-03-19T19:29:20.196Z,1521487760.196 [CBIT](DEBUG): Initialize CBIT Component. 2018-03-19T19:29:20.196Z,1521487760.196 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-03-19T19:29:20.197Z,1521487760.197 [logger ThreadHandler](INFO): Handler Thread ID is 894 2018-03-19T19:29:20.217Z,1521487760.217 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 895 2018-03-19T19:29:20.223Z,1521487760.223 [Radio_Surface](INFO): Powering up 2018-03-19T19:29:20.229Z,1521487760.229 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 896 2018-03-19T19:29:20.230Z,1521487760.230 [CTD_NeilBrown](INFO): Powering down 2018-03-19T19:29:20.254Z,1521487760.254 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 897 2018-03-19T19:29:20.254Z,1521487760.254 [CTD_Seabird](INFO): Initializing 2018-03-19T19:29:20.255Z,1521487760.255 [CTD_Seabird](INFO): Checking LCM 2018-03-19T19:29:20.305Z,1521487760.305 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-03-19T19:29:20.307Z,1521487760.307 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-03-19T19:29:20.307Z,1521487760.307 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-03-19T19:29:20.308Z,1521487760.308 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-03-19T19:29:20.308Z,1521487760.308 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-19T19:29:20.309Z,1521487760.309 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-19T19:29:20.309Z,1521487760.309 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-19T19:29:20.310Z,1521487760.310 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-19T19:29:20.310Z,1521487760.310 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-19T19:29:20.381Z,1521487760.381 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-19T19:29:20.382Z,1521487760.382 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-03-19T19:29:20.382Z,1521487760.382 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-03-19T19:29:20.383Z,1521487760.383 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-03-19T19:29:20.393Z,1521487760.393 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 898 2018-03-19T19:29:20.395Z,1521487760.395 [WetLabsBB2FL](INFO): Powering down 2018-03-19T19:29:20.413Z,1521487760.413 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 899 2018-03-19T19:29:20.506Z,1521487760.506 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-19T19:29:20.519Z,1521487760.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-03-19T19:29:20.519Z,1521487760.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-03-19T19:29:20.519Z,1521487760.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-03-19T19:29:20.519Z,1521487760.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-03-19T19:29:20.520Z,1521487760.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-03-19T19:29:20.520Z,1521487760.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-03-19T19:29:20.520Z,1521487760.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-03-19T19:29:20.520Z,1521487760.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-03-19T19:29:20.520Z,1521487760.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-03-19T19:29:20.521Z,1521487760.521 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-03-19T19:29:20.521Z,1521487760.521 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-03-19T19:29:20.521Z,1521487760.521 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-03-19T19:29:20.521Z,1521487760.521 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-03-19T19:29:20.521Z,1521487760.521 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-03-19T19:29:20.522Z,1521487760.522 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-03-19T19:29:20.522Z,1521487760.522 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-03-19T19:29:20.533Z,1521487760.533 [MissionManager](DEBUG): 2018-03-19T19:29:20.534Z,1521487760.534 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-03-19T19:29:20.704Z,1521487760.704 [CTD_Seabird](INFO): LCM OK 2018-03-19T19:29:20.705Z,1521487760.704 [CTD_Seabird](INFO): Powering up 2018-03-19T19:29:20.723Z,1521487760.723 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-03-19T19:29:20.724Z,1521487760.724 [Default:A.Wait](DEBUG): Construct Wait. 2018-03-19T19:29:20.726Z,1521487760.726 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-19T19:29:20.773Z,1521487760.773 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-03-19T19:29:20.775Z,1521487760.775 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-03-19T19:29:20.797Z,1521487760.797 [Default:E.Execute](DEBUG): Construct Execute. 2018-03-19T19:29:20.800Z,1521487760.800 [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 2018-03-19T19:29:20.816Z,1521487760.816 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-03-19T19:29:20.843Z,1521487760.843 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-03-19T19:29:20.882Z,1521487760.882 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2018-03-19T19:29:21.086Z,1521487761.086 [CANONSampler](INFO): Powering down 2018-03-19T19:29:21.200Z,1521487761.200 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-03-19T19:29:21.298Z,1521487761.298 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:21.423Z,1521487761.423 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:21.526Z,1521487761.526 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-03-19T19:29:21.533Z,1521487761.533 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-03-19T19:29:21.545Z,1521487761.545 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-03-19T19:29:21.553Z,1521487761.553 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-03-19T19:29:21.567Z,1521487761.567 [MassServo](DEBUG): Initializing EZServoServo. 2018-03-19T19:29:21.573Z,1521487761.573 [MassServo](DEBUG): Initializing MassServo. 2018-03-19T19:29:21.594Z,1521487761.594 [RudderServo](DEBUG): Initializing EZServoServo. 2018-03-19T19:29:21.601Z,1521487761.601 [RudderServo](DEBUG): Initializing RudderServo. 2018-03-19T19:29:21.607Z,1521487761.607 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-03-19T19:29:21.613Z,1521487761.613 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-03-19T19:29:21.933Z,1521487761.933 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:21.941Z,1521487761.941 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:22.138Z,1521487762.138 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:22.138Z,1521487762.138 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:22.469Z,1521487762.469 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-03-19T19:29:22.469Z,1521487762.469 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-03-19T19:29:22.469Z,1521487762.469 [BuoyancyServo] Communications Fault, FailCount= 1 2018-03-19T19:29:22.469Z,1521487762.469 [BuoyancyServo](ERROR): Communications Fault 2018-03-19T19:29:22.676Z,1521487762.676 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-03-19T19:29:22.769Z,1521487762.769 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:22.770Z,1521487762.770 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:22.775Z,1521487762.775 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-03-19T19:29:22.776Z,1521487762.776 [BuoyancyServo](INFO): Powering down 2018-03-19T19:29:23.131Z,1521487763.131 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:23.132Z,1521487763.132 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:23.552Z,1521487763.552 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:23.561Z,1521487763.561 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:23.957Z,1521487763.957 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:23.958Z,1521487763.958 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:24.441Z,1521487764.441 [Aanderaa_O2](INFO): Powering down 2018-03-19T19:29:24.552Z,1521487764.552 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:24.553Z,1521487764.553 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-19T19:29:24.873Z,1521487764.873 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-03-19T19:29:24.873Z,1521487764.873 [BuoyancyServo] No Fault, FailCount= 1 2018-03-19T19:29:25.162Z,1521487765.162 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-03-19T19:29:25.282Z,1521487765.282 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-03-19T19:29:25.958Z,1521487765.958 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-03-19T19:29:26.113Z,1521487766.113 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null & 2018-03-19T19:29:26.219Z,1521487766.219 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd 2018-03-19T19:29:26.545Z,1521487766.545 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-03-19T19:29:26.545Z,1521487766.545 [CTD_Seabird](INFO): Powering down 2018-03-19T19:29:44.014Z,1521487784.014 [SBIT](IMPORTANT): Beginning Startup BIT 2018-03-19T19:29:44.026Z,1521487784.026 [CBIT](IMPORTANT): Beginning ground fault scan 2018-03-19T19:29:46.823Z,1521487786.823 [NAL9602](INFO): Powering up NAL9602 2018-03-19T19:29:54.919Z,1521487794.919 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 4.657929 CHAN A1 (24V): -0.005042 CHAN A2 (12V): -0.002120 CHAN A3 (5V): 4.753929 CHAN B0 (3.3V): 0.001468 CHAN B1 (3.15aV): -0.000134 CHAN B2 (3.15bV): 0.000476 CHAN B3 (GND): 0.000090 OPEN: -0.000488 Full Scale Calc: 4.765 mA, -1.589 mA 2018-03-19T19:29:58.022Z,1521487798.022 [NAL9602](INFO): NAL9602 initialized 2018-03-19T19:30:38.035Z,1521487838.035 [SBIT](IMPORTANT): SBIT PASSED 2018-03-19T19:30:38.133Z,1521487838.133 [CommandLine](IMPORTANT): got command configSet list 2018-03-19T19:30:38.134Z,1521487838.134 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-03-19T19:30:38.135Z,1521487838.135 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool; 2018-03-19T19:30:38.415Z,1521487838.415 [MissionManager](IMPORTANT): Started mission Startup 2018-03-19T19:30:38.416Z,1521487838.416 [Startup] Running Loop=1 2018-03-19T19:30:38.416Z,1521487838.416 [Startup](DEBUG): Aggregate::initialize Startup 2018-03-19T19:30:38.416Z,1521487838.416 [Startup:A.GoToSurface] Running Loop=1 2018-03-19T19:30:38.416Z,1521487838.416 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-03-19T19:30:38.417Z,1521487838.417 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-03-19T19:30:38.417Z,1521487838.417 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-03-19T19:30:38.417Z,1521487838.417 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-03-19T19:30:38.418Z,1521487838.418 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-03-19T19:30:38.418Z,1521487838.418 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-03-19T19:30:38.423Z,1521487838.423 [Startup:StartupSatComms] Running Loop=1 2018-03-19T19:30:38.424Z,1521487838.424 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-03-19T19:30:38.424Z,1521487838.424 [Startup:StartupSatComms:A] Running Loop=1 2018-03-19T19:30:38.821Z,1521487838.821 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-03-19T19:31:38.804Z,1521487898.804 [Startup:StartupSatComms:A](INFO): Timed out from 2018-03-19T19:30:38.4Z 2018-03-19T19:31:38.804Z,1521487898.804 [Startup:StartupSatComms:A] Stopped 2018-03-19T19:31:38.804Z,1521487898.804 [Startup:StartupSatComms:B] Running Loop=1 2018-03-19T19:31:39.200Z,1521487899.200 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-03-19T19:32:02.770Z,1521487922.770 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004328 2018-03-19T19:32:06.731Z,1521487926.731 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180319T192909/Courier0000.lzma 2018-03-19T19:32:07.529Z,1521487927.529 [DataOverHttps](INFO): Moved sent file to Logs/20180319T192909/Courier0000.lzma.bak 2018-03-19T19:32:07.530Z,1521487927.530 [DataOverHttps](INFO): SBD MOMSN=8138222 2018-03-19T19:32:16.304Z,1521487936.304 [DataOverHttps](INFO): Sending 644 bytes from file Logs/20180319T192909/Express0001.lzma 2018-03-19T19:32:17.093Z,1521487937.093 [DataOverHttps](INFO): Moved sent file to Logs/20180319T192909/Express0001.lzma.bak 2018-03-19T19:32:17.093Z,1521487937.093 [DataOverHttps](INFO): SBD MOMSN=8138225 2018-03-19T19:32:17.559Z,1521487937.559 [Startup:StartupSatComms:B] Stopped 2018-03-19T19:32:17.559Z,1521487937.559 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-03-19T19:32:17.559Z,1521487937.559 [Startup:StartupSatComms] Stopped 2018-03-19T19:32:17.559Z,1521487937.559 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-03-19T19:32:17.560Z,1521487937.560 [Startup](INFO): Completed Startup 2018-03-19T19:32:17.560Z,1521487937.560 [MissionManager](INFO): Startup is completed. 2018-03-19T19:32:17.560Z,1521487937.560 [MissionManager](INFO): Uninitializing Mission Startup 2018-03-19T19:32:17.560Z,1521487937.560 [Startup] Stopped 2018-03-19T19:32:17.560Z,1521487937.560 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-03-19T19:32:17.560Z,1521487937.560 [Startup:A.GoToSurface] Stopped 2018-03-19T19:32:17.560Z,1521487937.560 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-03-19T19:32:17.957Z,1521487937.957 [MissionManager](IMPORTANT): Started mission Default 2018-03-19T19:32:17.957Z,1521487937.957 [Default] Running Loop=1 2018-03-19T19:32:17.957Z,1521487937.957 [Default](DEBUG): Aggregate::initialize Default 2018-03-19T19:32:17.957Z,1521487937.957 [Default:B.GoToSurface] Running Loop=1 2018-03-19T19:32:17.957Z,1521487937.957 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-03-19T19:32:17.958Z,1521487937.958 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-03-19T19:32:17.958Z,1521487937.958 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-03-19T19:32:17.958Z,1521487937.958 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-03-19T19:32:17.959Z,1521487937.959 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-03-19T19:32:17.959Z,1521487937.959 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-03-19T19:32:17.959Z,1521487937.959 [Default:A.Wait] Running Loop=1 2018-03-19T19:32:17.959Z,1521487937.959 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-03-19T19:32:21.060Z,1521487941.060 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-19T19:32:21.060Z,1521487941.060 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-03-19T19:32:21.060Z,1521487941.060 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-03-19T19:32:21.061Z,1521487941.061 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-19T19:32:21.062Z,1521487941.062 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-03-19T19:32:21.062Z,1521487941.062 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-03-19T19:32:21.109Z,1521487941.109 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-03-19T19:32:21.109Z,1521487941.109 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-03-19T19:32:21.226Z,1521487941.226 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-03-19T19:32:21.226Z,1521487941.226 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-03-19T19:32:21.227Z,1521487941.227 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-03-19T19:32:21.227Z,1521487941.227 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-03-19T19:32:21.571Z,1521487941.571 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-19T19:32:21.572Z,1521487941.572 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-19T19:32:21.573Z,1521487941.573 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-19T19:32:21.573Z,1521487941.573 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-19T19:32:21.574Z,1521487941.574 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-19T19:32:21.574Z,1521487941.574 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-19T19:32:31.175Z,1521487951.175 [Default:A.Wait](INFO): Done Waiting. 2018-03-19T19:32:31.175Z,1521487951.175 [Default:A.Wait] Stopped 2018-03-19T19:32:31.176Z,1521487951.176 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-03-19T19:32:31.575Z,1521487951.575 [Default:CheckIn] Running Loop=1 2018-03-19T19:32:31.575Z,1521487951.575 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-03-19T19:32:31.575Z,1521487951.575 [Default:CheckIn:Read_GPS] Running Loop=1 2018-03-19T19:32:31.978Z,1521487951.978 [Default:CheckIn:Read_GPS](DEBUG):