2018-03-09T01:17:41.284Z,1520558261.284 [Supervisor](DEBUG): Initializing supervisor. 2018-03-09T01:17:41.287Z,1520558261.287 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-03-09T01:17:41.287Z,1520558261.287 [SyncHandler](INFO): Protected caller Thread ID is 1782 2018-03-09T01:17:41.288Z,1520558261.288 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-03-09T01:17:41.289Z,1520558261.289 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-03-09T01:17:41.289Z,1520558261.289 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1783 2018-03-09T01:17:41.292Z,1520558261.292 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-03-09T01:17:41.303Z,1520558261.303 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-03-09T01:17:41.304Z,1520558261.304 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-03-09T01:17:41.305Z,1520558261.305 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1784 2018-03-09T01:17:41.305Z,1520558261.305 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-03-09T01:17:41.306Z,1520558261.306 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-03-09T01:17:41.307Z,1520558261.307 [logger ThreadHandler](INFO): Protected caller Thread ID is 1785 2018-03-09T01:17:41.309Z,1520558261.309 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-03-09T01:17:41.309Z,1520558261.309 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-03-09T01:17:41.311Z,1520558261.311 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-03-09T01:17:41.670Z,1520558261.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-03-09T01:17:41.671Z,1520558261.671 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-03-09T01:17:41.854Z,1520558261.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-03-09T01:17:41.855Z,1520558261.855 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-03-09T01:17:42.112Z,1520558262.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-03-09T01:17:42.112Z,1520558262.112 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-03-09T01:17:42.247Z,1520558262.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-03-09T01:17:42.247Z,1520558262.247 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-03-09T01:17:42.341Z,1520558262.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-03-09T01:17:42.342Z,1520558262.342 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-03-09T01:17:42.766Z,1520558262.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-03-09T01:17:42.766Z,1520558262.766 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-03-09T01:17:43.083Z,1520558263.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-03-09T01:17:43.084Z,1520558263.084 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-03-09T01:17:43.487Z,1520558263.487 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-03-09T01:17:43.487Z,1520558263.487 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-03-09T01:17:43.586Z,1520558263.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-03-09T01:17:43.587Z,1520558263.587 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-03-09T01:17:43.680Z,1520558263.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-03-09T01:17:43.681Z,1520558263.681 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-03-09T01:17:43.874Z,1520558263.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-03-09T01:17:43.874Z,1520558263.874 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-03-09T01:17:44.067Z,1520558264.067 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-03-09T01:17:44.068Z,1520558264.068 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-03-09T01:17:44.380Z,1520558264.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-03-09T01:17:44.381Z,1520558264.381 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-03-09T01:17:44.514Z,1520558264.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-03-09T01:17:44.515Z,1520558264.515 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-03-09T01:17:44.594Z,1520558264.594 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2018-03-09T01:17:44.595Z,1520558264.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2018-03-09T01:17:44.692Z,1520558264.692 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2018-03-09T01:17:44.779Z,1520558264.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2018-03-09T01:17:44.869Z,1520558264.869 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2018-03-09T01:17:44.950Z,1520558264.950 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2018-03-09T01:17:45.139Z,1520558265.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2018-03-09T01:17:45.554Z,1520558265.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2018-03-09T01:17:45.742Z,1520558265.742 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2018-03-09T01:17:46.067Z,1520558266.067 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-03-09T01:17:46.068Z,1520558266.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2018-03-09T01:17:46.148Z,1520558266.148 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2018-03-09T01:17:46.234Z,1520558266.234 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2018-03-09T01:17:46.330Z,1520558266.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2018-03-09T01:17:46.448Z,1520558266.448 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-03-09T01:17:46.459Z,1520558266.459 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-03-09T01:17:46.714Z,1520558266.714 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-03-09T01:17:46.715Z,1520558266.715 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-03-09T01:17:46.759Z,1520558266.759 [DepthRateCalculator] Loaded 2018-03-09T01:17:46.759Z,1520558266.759 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-03-09T01:17:46.765Z,1520558266.765 [PitchRateCalculator] Loaded 2018-03-09T01:17:46.766Z,1520558266.766 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-03-09T01:17:46.781Z,1520558266.781 [SpeedCalculator] Loaded 2018-03-09T01:17:46.781Z,1520558266.781 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-03-09T01:17:46.802Z,1520558266.802 [TempGradientCalculator] Loaded 2018-03-09T01:17:46.802Z,1520558266.802 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-03-09T01:17:46.817Z,1520558266.817 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-03-09T01:17:46.817Z,1520558266.817 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-03-09T01:17:46.823Z,1520558266.823 [YawRateCalculator] Loaded 2018-03-09T01:17:46.823Z,1520558266.823 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-03-09T01:17:46.862Z,1520558266.862 [ElevatorOffsetCalculator] Loaded 2018-03-09T01:17:46.862Z,1520558266.862 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-03-09T01:17:46.863Z,1520558266.863 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-03-09T01:17:46.863Z,1520558266.863 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-03-09T01:17:46.953Z,1520558266.953 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-03-09T01:17:46.953Z,1520558266.953 [StratificationFrontDetector](DEBUG): (re)initializing 2018-03-09T01:17:46.953Z,1520558266.953 [StratificationFrontDetector] Loaded 2018-03-09T01:17:46.953Z,1520558266.953 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-03-09T01:17:46.954Z,1520558266.954 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-03-09T01:17:46.954Z,1520558266.954 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-03-09T01:17:47.069Z,1520558267.069 [BuoyancyServo] Loaded 2018-03-09T01:17:47.070Z,1520558267.070 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-03-09T01:17:47.084Z,1520558267.084 [ElevatorServo] Loaded 2018-03-09T01:17:47.084Z,1520558267.084 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-03-09T01:17:47.098Z,1520558267.098 [MassServo] Loaded 2018-03-09T01:17:47.098Z,1520558267.098 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-03-09T01:17:47.113Z,1520558267.113 [RudderServo] Loaded 2018-03-09T01:17:47.113Z,1520558267.113 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-03-09T01:17:47.127Z,1520558267.127 [ThrusterServo] Loaded 2018-03-09T01:17:47.127Z,1520558267.127 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-03-09T01:17:47.127Z,1520558267.127 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-03-09T01:17:47.128Z,1520558267.128 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-03-09T01:17:47.142Z,1520558267.142 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-03-09T01:17:47.143Z,1520558267.143 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-03-09T01:17:47.246Z,1520558267.246 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-03-09T01:17:47.247Z,1520558267.247 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-03-09T01:17:47.890Z,1520558267.890 [DataOverHttps] Loaded 2018-03-09T01:17:47.891Z,1520558267.891 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-03-09T01:17:47.904Z,1520558267.904 [Depth_Keller] Loaded 2018-03-09T01:17:47.904Z,1520558267.904 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-03-09T01:17:47.909Z,1520558267.909 [DropWeight] Loaded 2018-03-09T01:17:47.909Z,1520558267.909 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-03-09T01:17:48.007Z,1520558268.007 [NAL9602] Loaded 2018-03-09T01:17:48.008Z,1520558268.008 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-03-09T01:17:48.024Z,1520558268.024 [Onboard] Loaded 2018-03-09T01:17:48.024Z,1520558268.024 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-03-09T01:17:48.034Z,1520558268.034 [Radio_Surface] Loaded 2018-03-09T01:17:48.034Z,1520558268.034 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-03-09T01:17:48.035Z,1520558268.035 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408714E0 2018-03-09T01:17:48.036Z,1520558268.036 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1867 2018-03-09T01:17:48.161Z,1520558268.161 [DAT] Loaded 2018-03-09T01:17:48.162Z,1520558268.162 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2018-03-09T01:17:48.207Z,1520558268.207 [PNI_TCM] Loaded 2018-03-09T01:17:48.208Z,1520558268.208 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-03-09T01:17:49.980Z,1520558269.980 [BPC1] Loaded 2018-03-09T01:17:49.980Z,1520558269.980 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-03-09T01:17:49.980Z,1520558269.980 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-03-09T01:17:49.981Z,1520558269.981 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-03-09T01:17:50.121Z,1520558270.121 [SBIT](DEBUG): Construct Startup Built In Test. 2018-03-09T01:17:50.142Z,1520558270.142 [SBIT] Loaded 2018-03-09T01:17:50.143Z,1520558270.143 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-03-09T01:17:50.143Z,1520558270.143 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-03-09T01:17:50.154Z,1520558270.154 [IBIT] Loaded 2018-03-09T01:17:50.154Z,1520558270.154 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-03-09T01:17:50.157Z,1520558270.157 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-03-09T01:17:50.304Z,1520558270.304 [CBIT] Loaded 2018-03-09T01:17:50.305Z,1520558270.305 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-03-09T01:17:50.305Z,1520558270.305 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-03-09T01:17:50.306Z,1520558270.306 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-03-09T01:17:50.582Z,1520558270.582 [Aanderaa_O2] Loaded 2018-03-09T01:17:50.582Z,1520558270.582 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-03-09T01:17:50.665Z,1520558270.665 [CTD_NeilBrown] Loaded 2018-03-09T01:17:50.665Z,1520558270.665 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2018-03-09T01:17:50.668Z,1520558270.668 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409314E0 2018-03-09T01:17:50.668Z,1520558270.668 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1868 2018-03-09T01:17:50.714Z,1520558270.714 [CTD_Seabird] Loaded 2018-03-09T01:17:50.714Z,1520558270.714 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-03-09T01:17:50.715Z,1520558270.715 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409614E0 2018-03-09T01:17:50.716Z,1520558270.716 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1869 2018-03-09T01:17:50.731Z,1520558270.731 [ESPComponent] Loaded 2018-03-09T01:17:50.732Z,1520558270.732 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-03-09T01:17:50.745Z,1520558270.745 [PAR_Licor] Loaded 2018-03-09T01:17:50.745Z,1520558270.745 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-03-09T01:17:50.794Z,1520558270.794 [WetLabsBB2FL] Loaded 2018-03-09T01:17:50.794Z,1520558270.794 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-03-09T01:17:50.795Z,1520558270.795 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409914E0 2018-03-09T01:17:50.796Z,1520558270.796 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1870 2018-03-09T01:17:50.796Z,1520558270.796 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-03-09T01:17:50.797Z,1520558270.797 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-03-09T01:17:50.869Z,1520558270.869 [VerticalControl](DEBUG): Construct VerticalControl. 2018-03-09T01:17:50.951Z,1520558270.951 [VerticalControl] Loaded 2018-03-09T01:17:50.951Z,1520558270.951 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-03-09T01:17:50.951Z,1520558270.951 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-03-09T01:17:51.007Z,1520558271.007 [HorizontalControl] Loaded 2018-03-09T01:17:51.007Z,1520558271.007 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-03-09T01:17:51.008Z,1520558271.008 [SpeedControl](DEBUG): Construct SpeedControl. 2018-03-09T01:17:51.009Z,1520558271.009 [SpeedControl] Loaded 2018-03-09T01:17:51.010Z,1520558271.010 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-03-09T01:17:51.010Z,1520558271.010 [LoopControl](DEBUG): Construct LoopControl. 2018-03-09T01:17:51.011Z,1520558271.011 [LoopControl] Loaded 2018-03-09T01:17:51.011Z,1520558271.011 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-03-09T01:17:51.012Z,1520558271.012 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-03-09T01:17:51.012Z,1520558271.012 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-03-09T01:17:51.039Z,1520558271.039 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-03-09T01:17:51.040Z,1520558271.040 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-03-09T01:17:51.132Z,1520558271.132 [DeadReckonUsingSpeedCalculator] Loaded 2018-03-09T01:17:51.133Z,1520558271.133 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-03-09T01:17:51.187Z,1520558271.187 [DeadReckonWithRespectToSeafloor] Loaded 2018-03-09T01:17:51.188Z,1520558271.188 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2018-03-09T01:17:51.204Z,1520558271.204 [NavChart] Loaded 2018-03-09T01:17:51.204Z,1520558271.204 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-03-09T01:17:51.208Z,1520558271.208 [UniversalFixResidualReporter] Loaded 2018-03-09T01:17:51.208Z,1520558271.208 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-03-09T01:17:51.208Z,1520558271.208 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-03-09T01:17:51.212Z,1520558271.212 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-03-09T01:17:51.213Z,1520558271.213 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-03-09T01:17:51.219Z,1520558271.219 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-03-09T01:17:51.223Z,1520558271.223 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1D4E0 2018-03-09T01:17:51.223Z,1520558271.223 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1871 2018-03-09T01:17:51.228Z,1520558271.228 [Supervisor](INFO): Main Thread ID is 1781 2018-03-09T01:17:51.228Z,1520558271.228 [Supervisor](DEBUG): Running supervisor. 2018-03-09T01:17:51.228Z,1520558271.228 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1872 2018-03-09T01:17:51.231Z,1520558271.231 [controlThread ThreadHandler](INFO): Handler Thread ID is 1873 2018-03-09T01:17:51.232Z,1520558271.232 [controlThread](DEBUG): Initializing ControlThread 2018-03-09T01:17:51.232Z,1520558271.232 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-03-09T01:17:51.233Z,1520558271.233 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-03-09T01:17:51.233Z,1520558271.233 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-03-09T01:17:51.234Z,1520558271.234 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-03-09T01:17:51.234Z,1520558271.234 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-03-09T01:17:51.235Z,1520558271.235 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-03-09T01:17:51.235Z,1520558271.235 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-03-09T01:17:51.243Z,1520558271.243 [SBIT](INFO): Initialize SBIT Component. 2018-03-09T01:17:51.244Z,1520558271.244 [SBIT](IMPORTANT): git: 2018-03-07 2018-03-09T01:17:51.244Z,1520558271.244 [SBIT](INFO): git hash: 0556b25932ead1454ecace5636ac3e6af3df01f4 2018-03-09T01:17:51.244Z,1520558271.244 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-03-09T01:17:51.245Z,1520558271.245 [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-09T01:17:51.246Z,1520558271.246 [SBIT](INFO): Beginning SBIT in 29.000000 seconds. 2018-03-09T01:17:51.247Z,1520558271.247 [IBIT](INFO): Initialize IBIT Component. 2018-03-09T01:17:51.248Z,1520558271.248 [CBIT](DEBUG): Initialize CBIT Component. 2018-03-09T01:17:51.248Z,1520558271.248 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-03-09T01:17:51.249Z,1520558271.249 [logger ThreadHandler](INFO): Handler Thread ID is 1874 2018-03-09T01:17:51.269Z,1520558271.269 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1875 2018-03-09T01:17:51.275Z,1520558271.275 [Radio_Surface](INFO): Powering up 2018-03-09T01:17:51.281Z,1520558271.281 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1876 2018-03-09T01:17:51.282Z,1520558271.282 [CTD_NeilBrown](INFO): Powering down 2018-03-09T01:17:51.309Z,1520558271.309 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1877 2018-03-09T01:17:51.310Z,1520558271.310 [CTD_Seabird](INFO): Initializing 2018-03-09T01:17:51.310Z,1520558271.310 [CTD_Seabird](INFO): Checking LCM 2018-03-09T01:17:51.357Z,1520558271.357 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-03-09T01:17:51.406Z,1520558271.406 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-03-09T01:17:51.407Z,1520558271.407 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-03-09T01:17:51.407Z,1520558271.407 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-03-09T01:17:51.407Z,1520558271.407 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-09T01:17:51.408Z,1520558271.408 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-09T01:17:51.408Z,1520558271.408 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-09T01:17:51.409Z,1520558271.409 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-09T01:17:51.409Z,1520558271.409 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-09T01:17:51.410Z,1520558271.410 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-09T01:17:51.410Z,1520558271.410 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-03-09T01:17:51.410Z,1520558271.410 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-03-09T01:17:51.412Z,1520558271.412 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-03-09T01:17:51.424Z,1520558271.424 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1878 2018-03-09T01:17:51.425Z,1520558271.425 [WetLabsBB2FL](INFO): Powering down 2018-03-09T01:17:51.428Z,1520558271.428 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-09T01:17:51.461Z,1520558271.461 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1879 2018-03-09T01:17:51.467Z,1520558271.467 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-03-09T01:17:51.467Z,1520558271.467 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-03-09T01:17:51.468Z,1520558271.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-03-09T01:17:51.468Z,1520558271.468 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-03-09T01:17:51.468Z,1520558271.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-03-09T01:17:51.468Z,1520558271.468 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-03-09T01:17:51.468Z,1520558271.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-03-09T01:17:51.469Z,1520558271.469 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-03-09T01:17:51.469Z,1520558271.469 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-03-09T01:17:51.470Z,1520558271.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-03-09T01:17:51.470Z,1520558271.470 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-03-09T01:17:51.470Z,1520558271.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-03-09T01:17:51.470Z,1520558271.470 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-03-09T01:17:51.470Z,1520558271.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-03-09T01:17:51.470Z,1520558271.470 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-03-09T01:17:51.471Z,1520558271.471 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-03-09T01:17:51.613Z,1520558271.613 [MissionManager](DEBUG): 2018-03-09T01:17:51.614Z,1520558271.614 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-03-09T01:17:51.691Z,1520558271.691 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-03-09T01:17:51.694Z,1520558271.694 [Default:A.Wait](DEBUG): Construct Wait. 2018-03-09T01:17:51.696Z,1520558271.696 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-09T01:17:51.744Z,1520558271.744 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-03-09T01:17:51.770Z,1520558271.770 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-03-09T01:17:51.814Z,1520558271.814 [Default:E.Execute](DEBUG): Construct Execute. 2018-03-09T01:17:51.850Z,1520558271.850 [CTD_NeilBrown](FAULT): LCB fault: Software Overcurrent. 2018-03-09T01:17:51.850Z,1520558271.850 [CTD_NeilBrown] Hardware Fault, FailCount= 1 2018-03-09T01:17:51.850Z,1520558271.850 [CTD_NeilBrown](ERROR): Hardware Fault 2018-03-09T01:17:51.872Z,1520558271.872 [CTD_Seabird](INFO): LCM OK 2018-03-09T01:17:51.873Z,1520558271.873 [CTD_Seabird](INFO): Powering up 2018-03-09T01:17:51.852Z,1520558271.852 [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-09T01:17:51.882Z,1520558271.882 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,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-09T01:17:51.893Z,1520558271.893 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-03-09T01:17:51.930Z,1520558271.930 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2018-03-09T01:17:52.017Z,1520558272.017 [DAT](INFO): Powering up 2018-03-09T01:17:52.018Z,1520558272.018 [DAT](DEBUG): Initializing DAT. 2018-03-09T01:17:52.306Z,1520558272.306 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-03-09T01:17:52.424Z,1520558272.424 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:17:52.697Z,1520558272.697 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:17:52.821Z,1520558272.821 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-03-09T01:17:52.829Z,1520558272.829 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-03-09T01:17:52.851Z,1520558272.851 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-03-09T01:17:52.857Z,1520558272.857 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-03-09T01:17:52.863Z,1520558272.863 [MassServo](DEBUG): Initializing EZServoServo. 2018-03-09T01:17:52.869Z,1520558272.869 [MassServo](DEBUG): Initializing MassServo. 2018-03-09T01:17:52.891Z,1520558272.891 [RudderServo](DEBUG): Initializing EZServoServo. 2018-03-09T01:17:52.895Z,1520558272.895 [RudderServo](DEBUG): Initializing RudderServo. 2018-03-09T01:17:52.926Z,1520558272.926 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-03-09T01:17:52.933Z,1520558272.933 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-03-09T01:17:52.981Z,1520558272.981 [CBIT](ERROR): Hardware Fault in component: CTD_NeilBrown 2018-03-09T01:17:52.981Z,1520558272.981 [CBIT](FAULT): Hardware Fault in component: CTD_NeilBrown 2018-03-09T01:17:53.304Z,1520558273.304 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:17:53.313Z,1520558273.313 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:17:56.626Z,1520558276.626 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-03-09T01:17:56.752Z,1520558276.752 [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-09T01:17:56.905Z,1520558276.905 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd 2018-03-09T01:17:57.280Z,1520558277.280 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-03-09T01:17:57.280Z,1520558277.280 [CTD_Seabird](INFO): Powering down 2018-03-09T01:18:02.579Z,1520558282.579 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:02.580Z,1520558282.580 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:02.934Z,1520558282.934 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:02.935Z,1520558282.935 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:03.425Z,1520558283.425 [Aanderaa_O2](INFO): Powering down 2018-03-09T01:18:03.510Z,1520558283.510 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:03.511Z,1520558283.511 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:03.673Z,1520558283.673 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003860 2018-03-09T01:18:03.749Z,1520558283.749 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:03.749Z,1520558283.749 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:04.114Z,1520558284.114 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:04.115Z,1520558284.115 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:04.567Z,1520558284.567 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:04.569Z,1520558284.569 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-03-09T01:18:05.305Z,1520558285.305 [DAT](INFO): setting local address to 2 2018-03-09T01:18:05.692Z,1520558285.692 [DAT](INFO): set local address to 2 2018-03-09T01:18:18.074Z,1520558298.074 [NAL9602](INFO): Powering up NAL9602 2018-03-09T01:18:20.985Z,1520558300.985 [SBIT](IMPORTANT): Beginning Startup BIT 2018-03-09T01:18:20.996Z,1520558300.996 [CBIT](IMPORTANT): Beginning ground fault scan 2018-03-09T01:18:29.022Z,1520558309.022 [NAL9602](INFO): NAL9602 initialized 2018-03-09T01:18:31.973Z,1520558311.973 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 4.657929 CHAN A1 (24V): -0.006075 CHAN A2 (12V): 4.697929 CHAN A3 (5V): -0.001727 CHAN B0 (3.3V): -0.000171 CHAN B1 (3.15aV): -0.000601 CHAN B2 (3.15bV): 0.000186 CHAN B3 (GND): -0.000701 OPEN: -0.000368 Full Scale Calc: 4.765 mA, -1.589 mA 2018-03-09T01:19:14.516Z,1520558354.516 [SBIT](IMPORTANT): SBIT PASSED 2018-03-09T01:19:14.912Z,1520558354.912 [MissionManager](IMPORTANT): Started mission Startup 2018-03-09T01:19:14.913Z,1520558354.913 [Startup] Running Loop=1 2018-03-09T01:19:14.913Z,1520558354.913 [Startup](DEBUG): Aggregate::initialize Startup 2018-03-09T01:19:14.913Z,1520558354.913 [Startup:A.GoToSurface] Running Loop=1 2018-03-09T01:19:14.913Z,1520558354.913 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-03-09T01:19:14.914Z,1520558354.914 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-03-09T01:19:14.915Z,1520558354.915 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-03-09T01:19:14.915Z,1520558354.915 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-03-09T01:19:14.915Z,1520558354.915 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 30.000000 seconds. 2018-03-09T01:19:14.916Z,1520558354.916 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-03-09T01:19:14.946Z,1520558354.946 [Startup:StartupSatComms] Running Loop=1 2018-03-09T01:19:14.946Z,1520558354.946 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-03-09T01:19:14.946Z,1520558354.946 [Startup:StartupSatComms:A] Running Loop=1 2018-03-09T01:19:15.314Z,1520558355.314 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-03-09T01:19:49.291Z,1520558389.291 [CommandLine](IMPORTANT): got command gfscan 2018-03-09T01:19:49.365Z,1520558389.365 [CBIT](IMPORTANT): Beginning ground fault scan 2018-03-09T01:20:02.632Z,1520558402.632 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.134071 CHAN A1 (24V): -0.004107 CHAN A2 (12V): -0.001980 CHAN A3 (5V): -0.001444 CHAN B0 (3.3V): -0.000728 CHAN B1 (3.15aV): -0.000360 CHAN B2 (3.15bV): 0.000096 CHAN B3 (GND): -0.000600 OPEN: -0.000585 Full Scale Calc: 4.765 mA, -1.589 mA 2018-03-09T01:20:15.000Z,1520558415.000 [Startup:StartupSatComms:A](INFO): Timed out from 2018-03-09T01:19:14.9Z 2018-03-09T01:20:15.000Z,1520558415.000 [Startup:StartupSatComms:A] Stopped 2018-03-09T01:20:15.001Z,1520558415.001 [Startup:StartupSatComms:B] Running Loop=1 2018-03-09T01:20:15.412Z,1520558415.412 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-03-09T01:20:19.355Z,1520558419.355 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180301T172538/Courier0076.lzma 2018-03-09T01:20:20.122Z,1520558420.122 [DataOverHttps](INFO): Moved sent file to Logs/20180301T172538/Courier0076.lzma.bak 2018-03-09T01:20:20.122Z,1520558420.122 [DataOverHttps](INFO): SBD MOMSN=7990861 2018-03-09T01:20:28.919Z,1520558428.919 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180309T011741/Courier0000.lzma 2018-03-09T01:20:29.685Z,1520558429.685 [DataOverHttps](INFO): Moved sent file to Logs/20180309T011741/Courier0000.lzma.bak 2018-03-09T01:20:29.685Z,1520558429.685 [DataOverHttps](INFO): SBD MOMSN=7990863 2018-03-09T01:20:38.494Z,1520558438.494 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20180301T172538/Express0077.lzma 2018-03-09T01:20:39.286Z,1520558439.286 [DataOverHttps](INFO): Moved sent file to Logs/20180301T172538/Express0077.lzma.bak 2018-03-09T01:20:39.286Z,1520558439.286 [DataOverHttps](INFO): SBD MOMSN=7990866 2018-03-09T01:20:48.489Z,1520558448.489 [DataOverHttps](INFO): Sending 683 bytes from file Logs/20180309T011741/Express0001.lzma 2018-03-09T01:20:49.253Z,1520558449.253 [DataOverHttps](INFO): Moved sent file to Logs/20180309T011741/Express0001.lzma.bak 2018-03-09T01:20:49.254Z,1520558449.254 [DataOverHttps](INFO): SBD MOMSN=7990870 2018-03-09T01:20:49.895Z,1520558449.895 [Startup:StartupSatComms:B] Stopped 2018-03-09T01:20:49.895Z,1520558449.895 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-03-09T01:20:49.895Z,1520558449.895 [Startup:StartupSatComms] Stopped 2018-03-09T01:20:49.895Z,1520558449.895 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-03-09T01:20:49.896Z,1520558449.896 [Startup](INFO): Completed Startup 2018-03-09T01:20:49.896Z,1520558449.896 [MissionManager](INFO): Startup is completed. 2018-03-09T01:20:49.896Z,1520558449.896 [MissionManager](INFO): Uninitializing Mission Startup 2018-03-09T01:20:49.897Z,1520558449.897 [Startup] Stopped 2018-03-09T01:20:49.897Z,1520558449.897 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-03-09T01:20:49.897Z,1520558449.897 [Startup:A.GoToSurface] Stopped 2018-03-09T01:20:49.897Z,1520558449.897 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-03-09T01:20:50.129Z,1520558450.129 [MissionManager](IMPORTANT): Started mission Default 2018-03-09T01:20:50.130Z,1520558450.130 [Default] Running Loop=1 2018-03-09T01:20:50.130Z,1520558450.130 [Default](DEBUG): Aggregate::initialize Default 2018-03-09T01:20:50.130Z,1520558450.130 [Default:B.GoToSurface] Running Loop=1 2018-03-09T01:20:50.130Z,1520558450.130 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-03-09T01:20:50.130Z,1520558450.130 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-03-09T01:20:50.130Z,1520558450.130 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-03-09T01:20:50.131Z,1520558450.131 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-03-09T01:20:50.131Z,1520558450.131 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 30.000000 seconds. 2018-03-09T01:20:50.131Z,1520558450.131 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-03-09T01:20:50.132Z,1520558450.132 [Default:A.Wait] Running Loop=1 2018-03-09T01:20:50.132Z,1520558450.132 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-03-09T01:20:53.565Z,1520558453.565 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-09T01:20:53.565Z,1520558453.565 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-03-09T01:20:53.565Z,1520558453.565 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-03-09T01:20:53.567Z,1520558453.567 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-09T01:20:53.567Z,1520558453.567 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-03-09T01:20:53.567Z,1520558453.567 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-03-09T01:20:53.607Z,1520558453.607 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-03-09T01:20:53.607Z,1520558453.607 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-03-09T01:20:53.715Z,1520558453.715 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-03-09T01:20:53.715Z,1520558453.715 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-03-09T01:20:53.715Z,1520558453.715 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-03-09T01:20:53.716Z,1520558453.716 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-03-09T01:20:54.178Z,1520558454.178 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-09T01:20:54.179Z,1520558454.179 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-09T01:20:54.179Z,1520558454.179 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-09T01:20:54.180Z,1520558454.180 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-09T01:20:54.180Z,1520558454.180 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-09T01:20:54.180Z,1520558454.180 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-09T01:21:03.282Z,1520558463.282 [Default:A.Wait](INFO): Done Waiting. 2018-03-09T01:21:03.282Z,1520558463.282 [Default:A.Wait] Stopped 2018-03-09T01:21:03.282Z,1520558463.282 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-03-09T01:21:03.693Z,1520558463.693 [Default:CheckIn] Running Loop=1 2018-03-09T01:21:03.693Z,1520558463.693 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-03-09T01:21:03.693Z,1520558463.693 [Default:CheckIn:Read_GPS] Running Loop=1 2018-03-09T01:21:04.097Z,1520558464.097 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-03-09T01:21:04.930Z,1520558464.930 [CommandLine](IMPORTANT): got command report mod VerticalControl.massPositionAction millimeter 2018-03-09T01:21:05.271Z,1520558465.271 [Reporter](INFO): VerticalControl.massPositionAction -0.002500 m 2018-03-09T01:23:32.786Z,1520558612.786 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-03-09T01:23:54.350Z,1520558634.350 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-09T01:23:54.350Z,1520558634.350 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-03-09T01:23:54.350Z,1520558634.350 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-03-09T01:23:54.351Z,1520558634.351 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-09T01:23:54.352Z,1520558634.352 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2018-03-09T01:23:54.352Z,1520558634.352 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-03-09T01:23:54.364Z,1520558634.364 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-03-09T01:23:54.364Z,1520558634.364 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-03-09T01:23:54.518Z,1520558634.518 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-03-09T01:23:54.518Z,1520558634.518 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-03-09T01:23:54.518Z,1520558634.518 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-03-09T01:23:54.518Z,1520558634.518 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2018-03-09T01:23:54.827Z,1520558634.827 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-09T01:23:54.827Z,1520558634.827 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-09T01:23:54.828Z,1520558634.828 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-09T01:23:54.828Z,1520558634.828 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-09T01:23:54.829Z,1520558634.829 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-09T01:23:54.829Z,1520558634.829 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-09T01:25:03.639Z,1520558703.639 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 3.000000 meter 2018-03-09T01:25:03.640Z,1520558703.640 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2018-03-09T01:25:03.874Z,1520558703.874 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,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-09T01:25:04.287Z,1520558704.287 [Reporter](INFO): VerticalControl.massPositionAction -0.035000 m 2018-03-09T01:25:04.653Z,1520558704.653 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -35.00 mm (1 active estimators). 2018-03-09T01:25:06.233Z,1520558706.233 [Radio_Surface](INFO): Powering down 2018-03-09T01:25:12.298Z,1520558712.298 [Radio_Surface](INFO): Powering up 2018-03-09T01:25:17.954Z,1520558717.954 [Radio_Surface](INFO): Powering down 2018-03-09T01:25:32.662Z,1520558732.662 [CommandLine](IMPORTANT): got command report touch platform_mass_position millimeter 2018-03-09T01:25:32.826Z,1520558732.826 [Reporter](INFO): platform_mass_position -0.023290 m 2018-03-09T01:25:33.226Z,1520558733.226 [Reporter](INFO): platform_mass_position -0.023603 m 2018-03-09T01:25:33.618Z,1520558733.618 [Reporter](INFO): platform_mass_position -0.023908 m 2018-03-09T01:25:33.943Z,1520558733.943 [Default:B.GoToSurface](ERROR): Pitch down timeout. Pitch: -0.76 2018-03-09T01:25:34.022Z,1520558734.022 [Reporter](INFO): platform_mass_position -0.024225 m 2018-03-09T01:25:34.126Z,1520558734.126 [Radio_Surface](INFO): Powering up 2018-03-09T01:25:34.687Z,1520558734.687 [Reporter](INFO): platform_mass_position -0.024670 m 2018-03-09T01:25:34.819Z,1520558734.819 [Reporter](INFO): platform_mass_position -0.024873 m 2018-03-09T01:25:35.259Z,1520558735.259 [Reporter](INFO): platform_mass_position -0.025215 m 2018-03-09T01:25:35.631Z,1520558735.631 [Reporter](INFO): platform_mass_position -0.025510 m 2018-03-09T01:25:36.146Z,1520558736.146 [Reporter](INFO): platform_mass_position -0.025908 m 2018-03-09T01:25:36.407Z,1520558736.407 [Reporter](INFO): platform_mass_position -0.026118 m 2018-03-09T01:25:36.815Z,1520558736.815 [Reporter](INFO): platform_mass_position -0.026435 m 2018-03-09T01:25:37.216Z,1520558737.216 [Reporter](INFO): platform_mass_position -0.026751 m 2018-03-09T01:25:37.611Z,1520558737.611 [Reporter](INFO): platform_mass_position -0.027062 m 2018-03-09T01:25:38.007Z,1520558738.007 [Reporter](INFO): platform_mass_position -0.027372 m 2018-03-09T01:25:38.411Z,1520558738.411 [Reporter](INFO): platform_mass_position -0.027689 m 2018-03-09T01:25:38.795Z,1520558738.795 [Reporter](INFO): platform_mass_position -0.027986 m 2018-03-09T01:25:39.211Z,1520558739.211 [Reporter](INFO): platform_mass_position -0.028316 m 2018-03-09T01:25:39.623Z,1520558739.623 [Reporter](INFO): platform_mass_position -0.028639 m 2018-03-09T01:25:40.023Z,1520558740.023 [Reporter](INFO): platform_mass_position -0.028952 m 2018-03-09T01:25:40.415Z,1520558740.415 [Reporter](INFO): platform_mass_position -0.029260 m 2018-03-09T01:25:40.812Z,1520558740.812 [Reporter](INFO): platform_mass_position -0.029570 m 2018-03-09T01:25:41.204Z,1520558741.204 [Reporter](INFO): platform_mass_position -0.029877 m 2018-03-09T01:25:41.608Z,1520558741.608 [Reporter](INFO): platform_mass_position -0.030194 m 2018-03-09T01:25:42.007Z,1520558742.007 [Reporter](INFO): platform_mass_position -0.030507 m 2018-03-09T01:25:42.407Z,1520558742.407 [Reporter](INFO): platform_mass_position -0.030821 m 2018-03-09T01:25:42.819Z,1520558742.819 [Reporter](INFO): platform_mass_position -0.031137 m 2018-03-09T01:25:43.219Z,1520558743.219 [Reporter](INFO): platform_mass_position -0.031457 m 2018-03-09T01:25:43.619Z,1520558743.619 [Reporter](INFO): platform_mass_position -0.031771 m 2018-03-09T01:25:44.012Z,1520558744.012 [Reporter](INFO): platform_mass_position -0.032078 m 2018-03-09T01:25:44.392Z,1520558744.392 [Reporter](INFO): platform_mass_position -0.032376 m 2018-03-09T01:25:44.813Z,1520558744.813 [Reporter](INFO): platform_mass_position -0.032705 m 2018-03-09T01:25:45.191Z,1520558745.191 [Reporter](INFO): platform_mass_position -0.033003 m 2018-03-09T01:25:45.611Z,1520558745.611 [Reporter](INFO): platform_mass_position -0.033323 m 2018-03-09T01:25:46.021Z,1520558746.021 [Reporter](INFO): platform_mass_position -0.033652 m 2018-03-09T01:25:46.427Z,1520558746.427 [Reporter](INFO): platform_mass_position -0.033972 m 2018-03-09T01:25:46.835Z,1520558746.835 [Reporter](INFO): platform_mass_position -0.034292 m 2018-03-09T01:25:47.200Z,1520558747.200 [Reporter](INFO): platform_mass_position -0.034577 m 2018-03-09T01:25:47.607Z,1520558747.607 [Reporter](INFO): platform_mass_position -0.034897 m 2018-03-09T01:25:48.015Z,1520558748.015 [Reporter](INFO): platform_mass_position -0.034999 m 2018-03-09T01:25:58.855Z,1520558758.855 [CommandLine](IMPORTANT): got command maintain clear 2018-03-09T01:25:59.159Z,1520558759.159 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,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-09T01:25:59.548Z,1520558759.548 [Reporter](INFO): VerticalControl.massPositionAction -0.002500 m 2018-03-09T01:26:01.547Z,1520558761.547 [Reporter](INFO): platform_mass_position -0.034999 m 2018-03-09T01:26:01.691Z,1520558761.691 [Reporter](INFO): platform_mass_position -0.034914 m 2018-03-09T01:26:02.051Z,1520558762.051 [Reporter](INFO): platform_mass_position -0.034624 m 2018-03-09T01:26:02.451Z,1520558762.451 [Reporter](INFO): platform_mass_position -0.034312 m 2018-03-09T01:26:02.859Z,1520558762.859 [Reporter](INFO): platform_mass_position -0.033991 m 2018-03-09T01:26:03.267Z,1520558763.267 [Reporter](INFO): platform_mass_position -0.033680 m 2018-03-09T01:26:03.684Z,1520558763.684 [Reporter](INFO): platform_mass_position -0.033345 m 2018-03-09T01:26:04.011Z,1520558764.011 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-03-09T01:21:03.7Z 2018-03-09T01:26:04.011Z,1520558764.011 [Default:CheckIn:Read_GPS] Stopped 2018-03-09T01:26:04.011Z,1520558764.011 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-03-09T01:26:04.076Z,1520558764.076 [Reporter](INFO): platform_mass_position -0.033038 m 2018-03-09T01:26:04.411Z,1520558764.411 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-03-09T01:26:04.476Z,1520558764.476 [Reporter](INFO): platform_mass_position -0.032725 m 2018-03-09T01:26:04.976Z,1520558764.976 [Reporter](INFO): platform_mass_position -0.032342 m 2018-03-09T01:26:05.259Z,1520558765.259 [Reporter](INFO): platform_mass_position -0.032110 m 2018-03-09T01:26:05.656Z,1520558765.656 [Reporter](INFO): platform_mass_position -0.031803 m 2018-03-09T01:26:06.064Z,1520558766.064 [Reporter](INFO): platform_mass_position -0.031480 m 2018-03-09T01:26:06.471Z,1520558766.471 [Reporter](INFO): platform_mass_position -0.031160 m 2018-03-09T01:26:06.868Z,1520558766.868 [Reporter](INFO): platform_mass_position -0.030850 m 2018-03-09T01:26:07.268Z,1520558767.268 [Reporter](INFO): platform_mass_position -0.030540 m 2018-03-09T01:26:07.679Z,1520558767.679 [Reporter](INFO): platform_mass_position -0.030213 m 2018-03-09T01:26:08.067Z,1520558768.067 [Reporter](INFO): platform_mass_position -0.029913 m 2018-03-09T01:26:08.484Z,1520558768.484 [Reporter](INFO): platform_mass_position -0.029592 m 2018-03-09T01:26:08.867Z,1520558768.867 [Reporter](INFO): platform_mass_position -0.029282 m 2018-03-09T01:26:09.267Z,1520558769.267 [Reporter](INFO): platform_mass_position -0.028969 m 2018-03-09T01:26:09.679Z,1520558769.679 [Reporter](INFO): platform_mass_position -0.028652 m 2018-03-09T01:26:10.079Z,1520558770.079 [Reporter](INFO): platform_mass_position -0.028332 m 2018-03-09T01:26:10.467Z,1520558770.467 [Reporter](INFO): platform_mass_position -0.028031 m 2018-03-09T01:26:10.876Z,1520558770.876 [Reporter](INFO): platform_mass_position -0.027709 m 2018-03-09T01:26:11.267Z,1520558771.267 [Reporter](INFO): platform_mass_position -0.027401 m 2018-03-09T01:26:11.671Z,1520558771.671 [Reporter](INFO): platform_mass_position -0.027088 m 2018-03-09T01:26:12.064Z,1520558772.064 [Reporter](INFO): platform_mass_position -0.026777 m 2018-03-09T01:26:12.456Z,1520558772.456 [Reporter](INFO): platform_mass_position -0.026470 m 2018-03-09T01:26:12.871Z,1520558772.871 [Reporter](INFO): platform_mass_position -0.026147 m 2018-03-09T01:26:13.271Z,1520558773.271 [Reporter](INFO): platform_mass_position -0.025834 m 2018-03-09T01:26:13.671Z,1520558773.671 [Reporter](INFO): platform_mass_position -0.025517 m 2018-03-09T01:26:14.216Z,1520558774.216 [Reporter](INFO): platform_mass_position -0.025091 m 2018-03-09T01:26:14.478Z,1520558774.478 [Reporter](INFO): platform_mass_position -0.024893 m 2018-03-09T01:26:14.872Z,1520558774.872 [Reporter](INFO): platform_mass_position -0.024576 m 2018-03-09T01:26:15.268Z,1520558775.268 [Reporter](INFO): platform_mass_position -0.024266 m 2018-03-09T01:26:15.679Z,1520558775.679 [Reporter](INFO): platform_mass_position -0.023946 m 2018-03-09T01:26:16.079Z,1520558776.079 [Reporter](INFO): platform_mass_position -0.023633 m 2018-03-09T01:26:16.479Z,1520558776.479 [Reporter](INFO): platform_mass_position -0.023319 m 2018-03-09T01:26:16.879Z,1520558776.879 [Reporter](INFO): platform_mass_position -0.023006 m 2018-03-09T01:26:17.251Z,1520558777.251 [Reporter](INFO): platform_mass_position -0.022711 m 2018-03-09T01:26:17.667Z,1520558777.667 [Reporter](INFO): platform_mass_position -0.022385 m 2018-03-09T01:26:18.051Z,1520558778.051 [Reporter](INFO): platform_mass_position -0.022084 m 2018-03-09T01:26:18.467Z,1520558778.467 [Reporter](INFO): platform_mass_position -0.021758 m 2018-03-09T01:26:18.879Z,1520558778.879 [Reporter](INFO): platform_mass_position -0.021435 m 2018-03-09T01:26:19.279Z,1520558779.279 [Reporter](INFO): platform_mass_position -0.021122 m 2018-03-09T01:26:19.671Z,1520558779.671 [Reporter](INFO): platform_mass_position -0.020814 m 2018-03-09T01:26:20.055Z,1520558780.055 [Reporter](INFO): platform_mass_position -0.020514 m 2018-03-09T01:26:20.467Z,1520558780.467 [Reporter](INFO): platform_mass_position -0.020195 m 2018-03-09T01:26:20.867Z,1520558780.867 [Reporter](INFO): platform_mass_position -0.019880 m 2018-03-09T01:26:21.251Z,1520558781.251 [Reporter](INFO): platform_mass_position -0.019577 m 2018-03-09T01:26:21.552Z,1520558781.552 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-03-09T01:26:21.699Z,1520558781.699 [Reporter](INFO): platform_mass_position -0.019227 m 2018-03-09T01:26:22.060Z,1520558782.060 [Reporter](INFO): platform_mass_position -0.018942 m 2018-03-09T01:26:22.467Z,1520558782.467 [Reporter](INFO): platform_mass_position -0.018623 m 2018-03-09T01:26:22.871Z,1520558782.871 [Reporter](INFO): platform_mass_position -0.018309 m 2018-03-09T01:26:23.255Z,1520558783.255 [Reporter](INFO): platform_mass_position -0.018008 m 2018-03-09T01:26:23.676Z,1520558783.676 [Reporter](INFO): platform_mass_position -0.017676 m 2018-03-09T01:26:24.068Z,1520558784.068 [Reporter](INFO): platform_mass_position -0.017369 m 2018-03-09T01:26:24.460Z,1520558784.460 [Reporter](INFO): platform_mass_position -0.017061 m 2018-03-09T01:26:24.871Z,1520558784.871 [Reporter](INFO): platform_mass_position -0.016742 m 2018-03-09T01:26:25.271Z,1520558785.271 [Reporter](INFO): platform_mass_position -0.016428 m 2018-03-09T01:26:25.667Z,1520558785.667 [Reporter](INFO): platform_mass_position -0.016118 m 2018-03-09T01:26:26.067Z,1520558786.067 [Reporter](INFO): platform_mass_position -0.015801 m 2018-03-09T01:26:26.451Z,1520558786.451 [Reporter](INFO): platform_mass_position -0.015500 m 2018-03-09T01:26:26.851Z,1520558786.851 [Reporter](INFO): platform_mass_position -0.015186 m 2018-03-09T01:26:27.248Z,1520558787.248 [Reporter](INFO): platform_mass_position -0.014876 m 2018-03-09T01:26:27.683Z,1520558787.683 [Reporter](INFO): platform_mass_position -0.014535 m 2018-03-09T01:26:28.083Z,1520558788.083 [Reporter](INFO): platform_mass_position -0.014221 m 2018-03-09T01:26:28.487Z,1520558788.487 [Reporter](INFO): platform_mass_position -0.013904 m 2018-03-09T01:26:28.867Z,1520558788.867 [Reporter](INFO): platform_mass_position -0.013607 m 2018-03-09T01:26:29.259Z,1520558789.259 [Reporter](INFO): platform_mass_position -0.013299 m 2018-03-09T01:26:29.659Z,1520558789.659 [Reporter](INFO): platform_mass_position -0.012986 m 2018-03-09T01:26:30.055Z,1520558790.055 [Reporter](INFO): platform_mass_position -0.012675 m 2018-03-09T01:26:30.464Z,1520558790.464 [Reporter](INFO): platform_mass_position -0.012355 m 2018-03-09T01:26:30.879Z,1520558790.879 [Reporter](INFO): platform_mass_position -0.012029 m 2018-03-09T01:26:31.260Z,1520558791.260 [Reporter](INFO): platform_mass_position -0.011732 m 2018-03-09T01:26:31.679Z,1520558791.679 [Reporter](INFO): platform_mass_position -0.011403 m 2018-03-09T01:26:32.056Z,1520558792.056 [Reporter](INFO): platform_mass_position -0.011108 m 2018-03-09T01:26:32.467Z,1520558792.467 [Reporter](INFO): platform_mass_position -0.010785 m 2018-03-09T01:26:32.867Z,1520558792.867 [Reporter](INFO): platform_mass_position -0.010471 m 2018-03-09T01:26:33.255Z,1520558793.255 [Reporter](INFO): platform_mass_position -0.010170 m 2018-03-09T01:26:33.671Z,1520558793.671 [Reporter](INFO): platform_mass_position -0.009847 m 2018-03-09T01:26:34.079Z,1520558794.079 [Reporter](INFO): platform_mass_position -0.009528 m 2018-03-09T01:26:34.479Z,1520558794.479 [Reporter](INFO): platform_mass_position -0.009214 m 2018-03-09T01:26:34.867Z,1520558794.867 [Reporter](INFO): platform_mass_position -0.008904 m 2018-03-09T01:26:35.264Z,1520558795.264 [Reporter](INFO): platform_mass_position -0.008593 m 2018-03-09T01:26:35.651Z,1520558795.651 [Reporter](INFO): platform_mass_position -0.008289 m 2018-03-09T01:26:36.083Z,1520558796.083 [Reporter](INFO): platform_mass_position -0.007951 m 2018-03-09T01:26:36.465Z,1520558796.465 [Reporter](INFO): platform_mass_position -0.007653 m 2018-03-09T01:26:36.879Z,1520558796.879 [Reporter](INFO): platform_mass_position -0.007330 m 2018-03-09T01:26:37.287Z,1520558797.287 [Reporter](INFO): platform_mass_position -0.007007 m 2018-03-09T01:26:37.691Z,1520558797.691 [Reporter](INFO): platform_mass_position -0.006693 m 2018-03-09T01:26:38.055Z,1520558798.055 [Reporter](INFO): platform_mass_position -0.006406 m 2018-03-09T01:26:38.451Z,1520558798.451 [Reporter](INFO): platform_mass_position -0.006094 m 2018-03-09T01:26:38.871Z,1520558798.871 [Reporter](INFO): platform_mass_position -0.005769 m 2018-03-09T01:26:39.255Z,1520558799.255 [Reporter](INFO): platform_mass_position -0.005465 m 2018-03-09T01:26:39.671Z,1520558799.671 [Reporter](INFO): platform_mass_position -0.005141 m 2018-03-09T01:26:40.091Z,1520558800.091 [Reporter](INFO): platform_mass_position -0.004816 m 2018-03-09T01:26:40.459Z,1520558800.459 [Reporter](INFO): platform_mass_position -0.004521 m 2018-03-09T01:26:40.855Z,1520558800.855 [Reporter](INFO): platform_mass_position -0.004210 m 2018-03-09T01:26:41.267Z,1520558801.267 [Reporter](INFO): platform_mass_position -0.003887 m 2018-03-09T01:26:41.655Z,1520558801.655 [Reporter](INFO): platform_mass_position -0.003587 m 2018-03-09T01:26:42.071Z,1520558802.071 [Reporter](INFO): platform_mass_position -0.003257 m 2018-03-09T01:26:42.459Z,1520558802.459 [Reporter](INFO): platform_mass_position -0.002953 m 2018-03-09T01:26:42.875Z,1520558802.875 [Reporter](INFO): platform_mass_position -0.002627 m 2018-03-09T01:26:43.271Z,1520558803.271 [Reporter](INFO): platform_mass_position -0.002501 m 2018-03-09T01:26:52.352Z,1520558812.352 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-03-09T01:26:55.217Z,1520558815.217 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-09T01:26:55.217Z,1520558815.217 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-03-09T01:26:55.217Z,1520558815.217 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-03-09T01:26:55.219Z,1520558815.219 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-09T01:26:55.219Z,1520558815.219 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2018-03-09T01:26:55.219Z,1520558815.219 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-03-09T01:26:55.231Z,1520558815.231 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-03-09T01:26:55.231Z,1520558815.231 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-03-09T01:26:55.624Z,1520558815.624 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-03-09T01:26:55.624Z,1520558815.624 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-03-09T01:26:55.624Z,1520558815.624 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-03-09T01:26:55.624Z,1520558815.624 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2018-03-09T01:26:55.991Z,1520558815.991 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-09T01:26:55.991Z,1520558815.991 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-09T01:26:55.991Z,1520558815.991 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-09T01:26:55.992Z,1520558815.992 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-09T01:26:55.992Z,1520558815.992 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-09T01:26:55.992Z,1520558815.992 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-09T01:27:06.482Z,1520558826.482 [CommandLine](IMPORTANT): got command configSet VerticalControl.massBackOnGoToSurface 0.000000 bool 2018-03-09T01:27:16.066Z,1520558836.066 [CommandLine](IMPORTANT): got command report touch platform_mass_position millimeter 2018-03-09T01:27:22.752Z,1520558842.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-03-09T01:27:25.275Z,1520558845.275 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 3.000000 meter 2018-03-09T01:27:25.276Z,1520558845.276 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2018-03-09T01:27:25.639Z,1520558845.639 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,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-09T01:27:26.035Z,1520558846.035 [Reporter](INFO): VerticalControl.massPositionAction -0.035000 m 2018-03-09T01:27:26.098Z,1520558846.098 [Radio_Surface](INFO): Powering down 2018-03-09T01:27:28.090Z,1520558848.090 [Reporter](INFO): platform_mass_position -0.002501 m 2018-03-09T01:27:28.327Z,1520558848.327 [Reporter](INFO): platform_mass_position -0.002664 m 2018-03-09T01:27:28.778Z,1520558848.778 [Reporter](INFO): platform_mass_position -0.003017 m 2018-03-09T01:27:29.062Z,1520558849.062 [Reporter](INFO): platform_mass_position -0.003241 m 2018-03-09T01:27:29.438Z,1520558849.438 [Reporter](INFO): platform_mass_position -0.003536 m 2018-03-09T01:27:29.838Z,1520558849.838 [Reporter](INFO): platform_mass_position -0.003846 m 2018-03-09T01:27:30.246Z,1520558850.246 [Reporter](INFO): platform_mass_position -0.004169 m 2018-03-09T01:27:30.634Z,1520558850.634 [Reporter](INFO): platform_mass_position -0.004473 m 2018-03-09T01:27:31.070Z,1520558851.070 [Reporter](INFO): platform_mass_position -0.004815 m 2018-03-09T01:27:31.450Z,1520558851.450 [Reporter](INFO): platform_mass_position -0.005112 m 2018-03-09T01:27:31.842Z,1520558851.842 [Reporter](INFO): platform_mass_position -0.005420 m 2018-03-09T01:27:32.246Z,1520558852.246 [Reporter](INFO): platform_mass_position -0.005737 m 2018-03-09T01:27:32.627Z,1520558852.627 [Reporter](INFO): platform_mass_position -0.006031 m 2018-03-09T01:27:33.038Z,1520558853.038 [Reporter](INFO): platform_mass_position -0.006357 m 2018-03-09T01:27:33.430Z,1520558853.430 [Reporter](INFO): platform_mass_position -0.006664 m 2018-03-09T01:27:33.902Z,1520558853.902 [Reporter](INFO): platform_mass_position -0.007035 m 2018-03-09T01:27:34.286Z,1520558854.286 [Reporter](INFO): platform_mass_position -0.007335 m 2018-03-09T01:27:34.759Z,1520558854.759 [Reporter](INFO): platform_mass_position -0.007705 m 2018-03-09T01:27:35.034Z,1520558855.034 [Reporter](INFO): platform_mass_position -0.007919 m 2018-03-09T01:27:35.428Z,1520558855.428 [Reporter](INFO): platform_mass_position -0.008229 m 2018-03-09T01:27:35.850Z,1520558855.850 [Reporter](INFO): platform_mass_position -0.008562 m 2018-03-09T01:27:36.254Z,1520558856.254 [Reporter](INFO): platform_mass_position -0.008878 m 2018-03-09T01:27:36.650Z,1520558856.650 [Reporter](INFO): platform_mass_position -0.009189 m 2018-03-09T01:27:37.043Z,1520558857.043 [Reporter](INFO): platform_mass_position -0.009496 m 2018-03-09T01:27:37.446Z,1520558857.446 [Reporter](INFO): platform_mass_position -0.009812 m 2018-03-09T01:27:37.842Z,1520558857.842 [Reporter](INFO): platform_mass_position -0.010120 m 2018-03-09T01:27:38.236Z,1520558858.236 [Reporter](INFO): platform_mass_position -0.010430 m 2018-03-09T01:27:38.654Z,1520558858.654 [Reporter](INFO): platform_mass_position -0.010756 m 2018-03-09T01:27:39.054Z,1520558859.054 [Reporter](INFO): platform_mass_position -0.011069 m 2018-03-09T01:27:39.506Z,1520558859.506 [Reporter](INFO): platform_mass_position -0.011427 m 2018-03-09T01:27:39.874Z,1520558859.874 [Reporter](INFO): platform_mass_position -0.011715 m 2018-03-09T01:27:40.390Z,1520558860.390 [Reporter](INFO): platform_mass_position -0.012117 m 2018-03-09T01:27:40.655Z,1520558860.655 [Reporter](INFO): platform_mass_position -0.012327 m 2018-03-09T01:27:41.047Z,1520558861.047 [Reporter](INFO): platform_mass_position -0.012634 m 2018-03-09T01:27:41.426Z,1520558861.426 [Reporter](INFO): platform_mass_position -0.012932 m 2018-03-09T01:27:41.851Z,1520558861.851 [Reporter](INFO): platform_mass_position -0.013261 m 2018-03-09T01:27:42.246Z,1520558862.246 [Reporter](INFO): platform_mass_position -0.013575 m 2018-03-09T01:27:42.634Z,1520558862.634 [Reporter](INFO): platform_mass_position -0.013879 m 2018-03-09T01:27:43.046Z,1520558863.046 [Reporter](INFO): platform_mass_position -0.014201 m 2018-03-09T01:27:43.462Z,1520558863.462 [Reporter](INFO): platform_mass_position -0.014527 m 2018-03-09T01:27:43.914Z,1520558863.914 [Reporter](INFO): platform_mass_position -0.014880 m 2018-03-09T01:27:44.243Z,1520558864.243 [Reporter](INFO): platform_mass_position -0.015139 m 2018-03-09T01:27:44.762Z,1520558864.762 [Reporter](INFO): platform_mass_position -0.015545 m 2018-03-09T01:27:45.027Z,1520558865.027 [Reporter](INFO): platform_mass_position -0.015754 m 2018-03-09T01:27:45.442Z,1520558865.442 [Reporter](INFO): platform_mass_position -0.016080 m 2018-03-09T01:27:45.854Z,1520558865.854 [Reporter](INFO): platform_mass_position -0.016403 m 2018-03-09T01:27:46.262Z,1520558866.262 [Reporter](INFO): platform_mass_position -0.016719 m 2018-03-09T01:27:46.626Z,1520558866.626 [Reporter](INFO): platform_mass_position -0.017008 m 2018-03-09T01:27:47.022Z,1520558867.022 [Reporter](INFO): platform_mass_position -0.017318 m 2018-03-09T01:27:47.434Z,1520558867.434 [Reporter](INFO): platform_mass_position -0.017641 m 2018-03-09T01:27:47.830Z,1520558867.830 [Reporter](INFO): platform_mass_position -0.017951 m 2018-03-09T01:27:48.226Z,1520558868.226 [Reporter](INFO): platform_mass_position -0.018262 m 2018-03-09T01:27:48.722Z,1520558868.722 [Reporter](INFO): platform_mass_position -0.018644 m 2018-03-09T01:27:49.090Z,1520558869.090 [Reporter](INFO): platform_mass_position -0.018933 m 2018-03-09T01:27:49.566Z,1520558869.566 [Reporter](INFO): platform_mass_position -0.019312 m 2018-03-09T01:27:49.875Z,1520558869.875 [Reporter](INFO): platform_mass_position -0.019550 m 2018-03-09T01:27:50.238Z,1520558870.238 [Reporter](INFO): platform_mass_position -0.019838 m 2018-03-09T01:27:50.634Z,1520558870.634 [Reporter](INFO): platform_mass_position -0.020149 m 2018-03-09T01:27:51.038Z,1520558871.038 [Reporter](INFO): platform_mass_position -0.020466 m 2018-03-09T01:27:51.421Z,1520558871.421 [CBIT](DEBUG): EFC running - data check-sum false 2018-03-09T01:27:51.423Z,1520558871.423 [Reporter](INFO): platform_mass_position -0.020767 m 2018-03-09T01:27:51.876Z,1520558871.876 [Reporter](INFO): platform_mass_position -0.021118 m 2018-03-09T01:27:52.262Z,1520558872.262 [Reporter](INFO): platform_mass_position -0.021425 m 2018-03-09T01:27:52.638Z,1520558872.638 [Reporter](INFO): platform_mass_position -0.021720 m 2018-03-09T01:27:53.022Z,1520558873.022 [Reporter](INFO): platform_mass_position -0.022021 m 2018-03-09T01:27:53.296Z,1520558873.296 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-03-09T01:27:53.526Z,1520558873.526 [Reporter](INFO): platform_mass_position -0.022416 m 2018-03-09T01:27:53.878Z,1520558873.878 [Reporter](INFO): platform_mass_position -0.022692 m 2018-03-09T01:27:54.306Z,1520558874.306 [Reporter](INFO): platform_mass_position -0.023026 m 2018-03-09T01:27:54.770Z,1520558874.770 [Reporter](INFO): platform_mass_position -0.023391 m 2018-03-09T01:27:55.061Z,1520558875.061 [Reporter](INFO): platform_mass_position -0.023617 m 2018-03-09T01:27:55.470Z,1520558875.470 [Reporter](INFO): platform_mass_position -0.023940 m 2018-03-09T01:27:55.779Z,1520558875.779 [Default:B.GoToSurface](ERROR): Pitch down timeout. Pitch: -0.72 2018-03-09T01:27:55.871Z,1520558875.871 [Reporter](INFO): platform_mass_position -0.024249 m 2018-03-09T01:27:56.006Z,1520558876.006 [Radio_Surface](INFO): Powering up 2018-03-09T01:27:56.097Z,1520558876.097 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.316116 2018-03-09T01:27:56.355Z,1520558876.355 [Reporter](INFO): platform_mass_position -0.024560 m 2018-03-09T01:27:56.639Z,1520558876.639 [Reporter](INFO): platform_mass_position -0.024877 m 2018-03-09T01:27:57.116Z,1520558877.116 [Reporter](INFO): platform_mass_position -0.025250 m 2018-03-09T01:27:57.447Z,1520558877.447 [Reporter](INFO): platform_mass_position -0.025510 m 2018-03-09T01:27:57.835Z,1520558877.835 [Reporter](INFO): platform_mass_position -0.025815 m 2018-03-09T01:27:58.300Z,1520558878.300 [Reporter](INFO): platform_mass_position -0.026178 m 2018-03-09T01:27:58.723Z,1520558878.723 [Reporter](INFO): platform_mass_position -0.026510 m 2018-03-09T01:27:59.023Z,1520558879.023 [Reporter](INFO): platform_mass_position -0.026746 m 2018-03-09T01:27:59.420Z,1520558879.420 [Reporter](INFO): platform_mass_position -0.027056 m 2018-03-09T01:27:59.803Z,1520558879.803 [Reporter](INFO): platform_mass_position -0.027357 m 2018-03-09T01:28:00.215Z,1520558880.215 [Reporter](INFO): platform_mass_position -0.027673 m 2018-03-09T01:28:00.619Z,1520558880.619 [Reporter](INFO): platform_mass_position -0.027997 m 2018-03-09T01:28:01.007Z,1520558881.007 [Reporter](INFO): platform_mass_position -0.028301 m 2018-03-09T01:28:01.235Z,1520558881.235 [CommandLine](IMPORTANT): got command maintain clear 2018-03-09T01:28:01.415Z,1520558881.415 [Reporter](INFO): platform_mass_position -0.028620 m 2018-03-09T01:28:01.421Z,1520558881.421 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,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-09T01:28:01.855Z,1520558881.855 [Reporter](INFO): VerticalControl.massPositionAction -0.002500 m 2018-03-09T01:28:01.855Z,1520558881.855 [Reporter](INFO): platform_mass_position -0.028928 m 2018-03-09T01:28:02.255Z,1520558882.255 [Reporter](INFO): platform_mass_position -0.028979 m 2018-03-09T01:28:02.607Z,1520558882.607 [Reporter](INFO): platform_mass_position -0.028724 m 2018-03-09T01:28:03.015Z,1520558883.015 [Reporter](INFO): platform_mass_position -0.028405 m 2018-03-09T01:28:03.391Z,1520558883.391 [Reporter](INFO): platform_mass_position -0.028110 m 2018-03-09T01:28:03.831Z,1520558883.831 [Reporter](INFO): platform_mass_position -0.027768 m 2018-03-09T01:28:04.195Z,1520558884.195 [Reporter](INFO): platform_mass_position -0.027480 m 2018-03-09T01:28:04.623Z,1520558884.623 [Reporter](INFO): platform_mass_position -0.027144 m 2018-03-09T01:28:04.995Z,1520558884.995 [Reporter](INFO): platform_mass_position -0.026853 m 2018-03-09T01:28:05.408Z,1520558885.408 [Reporter](INFO): platform_mass_position -0.026530 m 2018-03-09T01:28:05.800Z,1520558885.800 [Reporter](INFO): platform_mass_position -0.026223 m 2018-03-09T01:28:06.211Z,1520558886.211 [Reporter](INFO): platform_mass_position -0.025900 m 2018-03-09T01:28:06.607Z,1520558886.607 [Reporter](INFO): platform_mass_position -0.025590 m 2018-03-09T01:28:07.011Z,1520558887.011 [Reporter](INFO): platform_mass_position -0.025273 m 2018-03-09T01:28:07.411Z,1520558887.411 [Reporter](INFO): platform_mass_position -0.024959 m 2018-03-09T01:28:07.820Z,1520558887.820 [Reporter](INFO): platform_mass_position -0.024639 m 2018-03-09T01:28:08.219Z,1520558888.219 [Reporter](INFO): platform_mass_position -0.024326 m 2018-03-09T01:28:08.607Z,1520558888.607 [Reporter](INFO): platform_mass_position -0.024022 m 2018-03-09T01:28:09.012Z,1520558889.012 [Reporter](INFO): platform_mass_position -0.023705 m 2018-03-09T01:28:09.391Z,1520558889.391 [Reporter](INFO): platform_mass_position -0.023407 m 2018-03-09T01:28:09.819Z,1520558889.819 [Reporter](INFO): platform_mass_position -0.023072 m 2018-03-09T01:28:09.865Z,1520558889.865 [CommandLine](IMPORTANT): got command report clear 2018-03-09T01:28:26.102Z,1520558906.102 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2018-03-09T01:28:26.625Z,1520558906.625 [NAL9602](ERROR): received: 2018-03-09T01:28:26.625Z,1520558906.625 [NAL9602] Data Fault, FailCount= 1 2018-03-09T01:28:26.625Z,1520558906.625 [NAL9602](ERROR): Data Fault 2018-03-09T01:28:26.746Z,1520558906.746 [CBIT](ERROR): Data Fault in component: NAL9602 2018-03-09T01:28:26.752Z,1520558906.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-03-09T01:28:26.777Z,1520558906.777 [NAL9602](INFO): Powering down 2018-03-09T01:28:27.657Z,1520558907.657 [CBIT](INFO): Clearing failed state for component NAL9602 2018-03-09T01:28:27.658Z,1520558907.658 [NAL9602] No Fault, FailCount= 1 2018-03-09T01:28:36.788Z,1520558916.788 [CommandLine](IMPORTANT): got command get VerticalControl.massBackOnGoToSurface 2018-03-09T01:28:36.789Z,1520558916.789 [CommandLine](IMPORTANT): VerticalControl.massBackOnGoToSurface 0 bool 2018-03-09T01:28:57.158Z,1520558937.158 [NAL9602](INFO): Powering up NAL9602 2018-03-09T01:29:08.362Z,1520558948.362 [NAL9602](INFO): NAL9602 initialized 2018-03-09T01:29:09.159Z,1520558949.159 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-03-09T01:29:09.159Z,1520558949.159 [NAL9602] Data Fault, FailCount= 2 2018-03-09T01:29:09.159Z,1520558949.159 [NAL9602](ERROR): Data Fault 2018-03-09T01:29:09.257Z,1520558949.257 [CBIT](ERROR): Data Fault in component: NAL9602 2018-03-09T01:29:09.558Z,1520558949.558 [NAL9602](INFO): Powering down 2018-03-09T01:29:10.441Z,1520558950.441 [CBIT](INFO): Clearing failed state for component NAL9602 2018-03-09T01:29:10.441Z,1520558950.441 [NAL9602] No Fault, FailCount= 2 2018-03-09T01:29:27.152Z,1520558967.152 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-03-09T01:29:39.962Z,1520558979.962 [NAL9602](INFO): Powering up NAL9602 2018-03-09T01:29:51.158Z,1520558991.158 [NAL9602](INFO): NAL9602 initialized 2018-03-09T01:29:56.023Z,1520558996.023 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-09T01:29:56.023Z,1520558996.023 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-03-09T01:29:56.023Z,1520558996.023 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-03-09T01:29:56.029Z,1520558996.029 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-03-09T01:29:56.029Z,1520558996.029 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2018-03-09T01:29:56.029Z,1520558996.029 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-03-09T01:29:56.064Z,1520558996.064 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-03-09T01:29:56.064Z,1520558996.064 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-03-09T01:29:56.445Z,1520558996.445 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-03-09T01:29:56.445Z,1520558996.445 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-03-09T01:29:56.445Z,1520558996.445 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-03-09T01:29:56.446Z,1520558996.446 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2018-03-09T01:29:56.812Z,1520558996.812 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-09T01:29:56.812Z,1520558996.812 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-09T01:29:56.812Z,1520558996.812 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-09T01:29:56.813Z,1520558996.813 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-03-09T01:29:56.813Z,1520558996.813 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-03-09T01:29:56.814Z,1520558996.814 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-03-09T01:30:26.752Z,1520559026.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-03-09T01:31:01.515Z,1520559061.515 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002371 2018-03-09T01:31:05.514Z,1520559065.514 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180309T011741/Courier0004.lzma 2018-03-09T01:31:06.281Z,1520559066.281 [DataOverHttps](INFO): Moved sent file to Logs/20180309T011741/Courier0004.lzma.bak 2018-03-09T01:31:06.282Z,1520559066.282 [DataOverHttps](INFO): SBD MOMSN=7990953 2018-03-09T01:31:15.230Z,1520559075.230 [DataOverHttps](INFO): Sending 321 bytes from file Logs/20180309T011741/Express0005.lzma 2018-03-09T01:31:15.997Z,1520559075.997 [DataOverHttps](INFO): Moved sent file to Logs/20180309T011741/Express0005.lzma.bak 2018-03-09T01:31:15.998Z,1520559075.998 [DataOverHttps](INFO): SBD MOMSN=7990957 2018-03-09T01:31:16.642Z,1520559076.642 [Default:CheckIn:Read_Iridium] Stopped 2018-03-09T01:31:16.642Z,1520559076.642 [Default:CheckIn:C.Wait] Running Loop=1 2018-03-09T01:31:16.642Z,1520559076.642 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-03-09T01:31:45.702Z,1520559105.702 [CommandLine](IMPORTANT): got command get VerticalControl.massBackOnGoToSurface 2018-03-09T01:31:45.702Z,1520559105.702 [CommandLine](IMPORTANT): VerticalControl.massBackOnGoToSurface 0 bool 2018-03-09T01:32:21.543Z,1520559141.543 [CommandLine](IMPORTANT): got command configSet VerticalControl.massBackOnGoToSurface 0.000000 bool persist 2018-03-09T01:32:27.348Z,1520559147.348 [CommandLine](IMPORTANT): got command restart application 2018-03-09T01:32:28.357Z,1520559148.357 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2018-03-09T01:32:28.360Z,1520559148.360 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-09T01:32:28.360Z,1520559148.360 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:28.425Z,1520559148.425 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-03-09T01:32:28.425Z,1520559148.425 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:28.426Z,1520559148.426 [CommandLine](INFO): Join timeout helper Thread ID is 1922 2018-03-09T01:32:28.426Z,1520559148.426 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-03-09T01:32:28.426Z,1520559148.426 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:28.426Z,1520559148.426 [NavChartDb](INFO): Join timeout helper Thread ID is 1923 2018-03-09T01:32:28.753Z,1520559148.753 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-09T01:32:28.753Z,1520559148.753 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:28.769Z,1520559148.769 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2018-03-09T01:32:28.769Z,1520559148.769 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:28.769Z,1520559148.769 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1924 2018-03-09T01:32:28.969Z,1520559148.969 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-09T01:32:28.969Z,1520559148.969 [WetLabsBB2FL](INFO): Powering down 2018-03-09T01:32:28.970Z,1520559148.970 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:28.989Z,1520559148.989 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2018-03-09T01:32:28.989Z,1520559148.989 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:28.989Z,1520559148.989 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1925 2018-03-09T01:32:29.201Z,1520559149.201 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-09T01:32:29.358Z,1520559149.358 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-03-09T01:32:29.359Z,1520559149.359 [CTD_Seabird](INFO): Powering down 2018-03-09T01:32:29.360Z,1520559149.360 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:29.373Z,1520559149.373 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2018-03-09T01:32:29.373Z,1520559149.373 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:29.374Z,1520559149.374 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1927 2018-03-09T01:32:29.374Z,1520559149.374 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-09T01:32:29.374Z,1520559149.374 [CTD_NeilBrown](INFO): Powering down 2018-03-09T01:32:29.375Z,1520559149.375 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:29.377Z,1520559149.377 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-03-09T01:32:29.377Z,1520559149.377 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:29.377Z,1520559149.377 [Radio_Surface](INFO): Join timeout helper Thread ID is 1928 2018-03-09T01:32:29.657Z,1520559149.657 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-09T01:32:29.657Z,1520559149.657 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:29.659Z,1520559149.659 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-03-09T01:32:29.659Z,1520559149.659 [logger ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:29.659Z,1520559149.659 [logger](INFO): Join timeout helper Thread ID is 1929 2018-03-09T01:32:29.681Z,1520559149.681 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-09T01:32:29.681Z,1520559149.681 [logger ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:29.688Z,1520559149.688 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-03-09T01:32:29.688Z,1520559149.688 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:29.688Z,1520559149.688 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-03-09T01:32:29.688Z,1520559149.688 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:29.689Z,1520559149.689 [controlThread](INFO): Join timeout helper Thread ID is 1930 2018-03-09T01:32:29.731Z,1520559149.731 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-03-09T01:32:29.732Z,1520559149.732 [controlThread](DEBUG): Uninitializing ControlThread 2018-03-09T01:32:29.732Z,1520559149.732 [NAL9602](INFO): Powering down 2018-03-09T01:32:29.734Z,1520559149.734 [DAT](INFO): Powering down 2018-03-09T01:32:29.853Z,1520559149.853 [PNI_TCM](INFO): Powering down 2018-03-09T01:32:29.941Z,1520559149.941 [Aanderaa_O2](INFO): Powering down 2018-03-09T01:32:29.943Z,1520559149.943 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-03-09T01:32:29.944Z,1520559149.944 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-03-09T01:32:29.945Z,1520559149.945 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-03-09T01:32:29.945Z,1520559149.945 [MissionManager](INFO): Uninitializing Mission Default 2018-03-09T01:32:29.946Z,1520559149.946 [Default] Stopped 2018-03-09T01:32:29.946Z,1520559149.946 [Default](DEBUG): Aggregate::uninitialize Default 2018-03-09T01:32:29.946Z,1520559149.946 [Default:B.GoToSurface] Stopped 2018-03-09T01:32:29.946Z,1520559149.946 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-03-09T01:32:29.946Z,1520559149.946 [Default:CheckIn] Stopped 2018-03-09T01:32:29.946Z,1520559149.946 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-03-09T01:32:29.946Z,1520559149.946 [Default:CheckIn:C.Wait] Stopped 2018-03-09T01:32:29.946Z,1520559149.946 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-03-09T01:32:29.949Z,1520559149.949 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-03-09T01:32:29.950Z,1520559149.950 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-03-09T01:32:29.950Z,1520559149.950 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-03-09T01:32:29.950Z,1520559149.950 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-03-09T01:32:29.950Z,1520559149.950 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-03-09T01:32:29.951Z,1520559149.951 [BuoyancyServo](INFO): Powering down 2018-03-09T01:32:29.965Z,1520559149.965 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-03-09T01:32:29.965Z,1520559149.965 [ElevatorServo](INFO): Powering down 2018-03-09T01:32:29.966Z,1520559149.966 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-03-09T01:32:29.966Z,1520559149.966 [MassServo](INFO): Powering down 2018-03-09T01:32:29.967Z,1520559149.967 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-03-09T01:32:29.967Z,1520559149.967 [RudderServo](INFO): Powering down 2018-03-09T01:32:29.968Z,1520559149.968 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-03-09T01:32:29.968Z,1520559149.968 [ThrusterServo](INFO): Powering down 2018-03-09T01:32:29.968Z,1520559149.968 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-03-09T01:32:29.969Z,1520559149.969 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-03-09T01:32:29.969Z,1520559149.969 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-03-09T01:32:29.970Z,1520559149.970 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:30.091Z,1520559150.091 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:30.126Z,1520559150.126 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:30.130Z,1520559150.130 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:30.133Z,1520559150.133 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:30.159Z,1520559150.159 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-03-09T01:32:30.212Z,1520559150.212 [logger ThreadHandler](INFO): Thread cancelled.