2017-04-25T20:42:00.794Z,1493152920.794 [Supervisor](DEBUG): Initializing supervisor. 2017-04-25T20:42:00.797Z,1493152920.797 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-04-25T20:42:00.798Z,1493152920.798 [SyncHandler](INFO): Protected caller Thread ID is 769 2017-04-25T20:42:00.798Z,1493152920.798 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-04-25T20:42:00.799Z,1493152920.799 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-04-25T20:42:00.800Z,1493152920.800 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770 2017-04-25T20:42:00.803Z,1493152920.803 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-04-25T20:42:00.815Z,1493152920.815 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-04-25T20:42:00.817Z,1493152920.816 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-04-25T20:42:00.817Z,1493152920.817 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771 2017-04-25T20:42:00.818Z,1493152920.818 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-04-25T20:42:00.819Z,1493152920.819 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-04-25T20:42:00.819Z,1493152920.819 [logger ThreadHandler](INFO): Protected caller Thread ID is 772 2017-04-25T20:42:00.821Z,1493152920.821 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-04-25T20:42:00.822Z,1493152920.822 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-04-25T20:42:00.826Z,1493152920.826 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-04-25T20:42:00.913Z,1493152920.913 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-04-25T20:42:01.555Z,1493152921.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-04-25T20:42:01.558Z,1493152921.557 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-04-25T20:42:01.747Z,1493152921.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-04-25T20:42:01.748Z,1493152921.748 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-04-25T20:42:01.952Z,1493152921.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-04-25T20:42:01.953Z,1493152921.953 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-04-25T20:42:02.055Z,1493152922.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-04-25T20:42:02.057Z,1493152922.057 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-04-25T20:42:02.254Z,1493152922.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-04-25T20:42:02.256Z,1493152922.256 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-04-25T20:42:02.728Z,1493152922.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-04-25T20:42:02.728Z,1493152922.728 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-04-25T20:42:03.018Z,1493152923.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-04-25T20:42:03.019Z,1493152923.019 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-04-25T20:42:03.105Z,1493152923.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-04-25T20:42:03.106Z,1493152923.106 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-04-25T20:42:03.249Z,1493152923.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-04-25T20:42:03.250Z,1493152923.250 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-04-25T20:42:03.450Z,1493152923.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-04-25T20:42:03.450Z,1493152923.450 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-04-25T20:42:03.985Z,1493152923.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-04-25T20:42:03.986Z,1493152923.986 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-04-25T20:42:08.823Z,1493152928.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-04-25T20:42:08.824Z,1493152928.824 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-04-25T20:42:09.174Z,1493152929.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-04-25T20:42:09.175Z,1493152929.175 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-04-25T20:42:09.288Z,1493152929.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-04-25T20:42:09.290Z,1493152929.290 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/ 2017-04-25T20:42:09.294Z,1493152929.295 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg 2017-04-25T20:42:09.383Z,1493152929.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg 2017-04-25T20:42:09.553Z,1493152929.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg 2017-04-25T20:42:09.790Z,1493152929.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-04-25T20:42:09.791Z,1493152929.791 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg 2017-04-25T20:42:09.892Z,1493152929.892 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg 2017-04-25T20:42:10.004Z,1493152930.004 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg 2017-04-25T20:42:10.113Z,1493152930.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg 2017-04-25T20:42:10.259Z,1493152930.260 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg 2017-04-25T20:42:10.426Z,1493152930.426 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg 2017-04-25T20:42:10.537Z,1493152930.537 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg 2017-04-25T20:42:10.627Z,1493152930.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg 2017-04-25T20:42:10.728Z,1493152930.728 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/root/ 2017-04-25T20:42:10.729Z,1493152930.729 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-04-25T20:42:10.732Z,1493152930.732 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-04-25T20:42:10.804Z,1493152930.804 [VerticalControl](DEBUG): Construct VerticalControl. 2017-04-25T20:42:10.973Z,1493152930.973 [VerticalControl] Loaded 2017-04-25T20:42:10.974Z,1493152930.974 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-04-25T20:42:10.974Z,1493152930.974 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-04-25T20:42:11.047Z,1493152931.047 [HorizontalControl] Loaded 2017-04-25T20:42:11.047Z,1493152931.047 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-04-25T20:42:11.048Z,1493152931.048 [SpeedControl](DEBUG): Construct SpeedControl. 2017-04-25T20:42:11.054Z,1493152931.054 [SpeedControl] Loaded 2017-04-25T20:42:11.054Z,1493152931.054 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-04-25T20:42:11.055Z,1493152931.055 [LoopControl](DEBUG): Construct LoopControl. 2017-04-25T20:42:11.056Z,1493152931.056 [LoopControl] Loaded 2017-04-25T20:42:11.056Z,1493152931.056 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-04-25T20:42:11.056Z,1493152931.056 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-04-25T20:42:11.057Z,1493152931.057 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-04-25T20:42:11.218Z,1493152931.218 [SBIT](DEBUG): Construct Startup Built In Test. 2017-04-25T20:42:11.230Z,1493152931.230 [SBIT] Loaded 2017-04-25T20:42:11.230Z,1493152931.230 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-04-25T20:42:11.231Z,1493152931.231 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-04-25T20:42:11.261Z,1493152931.261 [IBIT] Loaded 2017-04-25T20:42:11.261Z,1493152931.261 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-04-25T20:42:11.264Z,1493152931.264 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-04-25T20:42:11.416Z,1493152931.416 [CBIT] Loaded 2017-04-25T20:42:11.416Z,1493152931.416 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-04-25T20:42:11.417Z,1493152931.417 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-04-25T20:42:11.417Z,1493152931.417 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-04-25T20:42:11.459Z,1493152931.459 [DepthRateCalculator] Loaded 2017-04-25T20:42:11.460Z,1493152931.460 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-04-25T20:42:11.465Z,1493152931.465 [PitchRateCalculator] Loaded 2017-04-25T20:42:11.466Z,1493152931.466 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-04-25T20:42:11.477Z,1493152931.477 [SpeedCalculator] Loaded 2017-04-25T20:42:11.477Z,1493152931.477 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-04-25T20:42:11.500Z,1493152931.500 [TempGradientCalculator] Loaded 2017-04-25T20:42:11.500Z,1493152931.500 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-04-25T20:42:11.517Z,1493152931.517 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-04-25T20:42:11.517Z,1493152931.517 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-04-25T20:42:11.523Z,1493152931.523 [YawRateCalculator] Loaded 2017-04-25T20:42:11.523Z,1493152931.523 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-04-25T20:42:11.524Z,1493152931.523 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-04-25T20:42:11.524Z,1493152931.524 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-04-25T20:42:11.647Z,1493152931.647 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-04-25T20:42:11.647Z,1493152931.647 [StratificationFrontDetector](DEBUG): (re)initializing 2017-04-25T20:42:11.647Z,1493152931.647 [StratificationFrontDetector] Loaded 2017-04-25T20:42:11.648Z,1493152931.648 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-04-25T20:42:11.672Z,1493152931.672 [DepAvgTempFrontDetector] Loaded 2017-04-25T20:42:11.672Z,1493152931.672 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread. 2017-04-25T20:42:11.673Z,1493152931.673 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-04-25T20:42:11.674Z,1493152931.674 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-04-25T20:42:11.963Z,1493152931.963 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-04-25T20:42:11.964Z,1493152931.964 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-04-25T20:42:12.087Z,1493152932.087 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-04-25T20:42:12.087Z,1493152932.087 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-04-25T20:42:12.144Z,1493152932.144 [DeadReckonUsingSpeedCalculator] Loaded 2017-04-25T20:42:12.144Z,1493152932.144 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-04-25T20:42:12.201Z,1493152932.201 [DeadReckonWithRespectToSeafloor] Loaded 2017-04-25T20:42:12.201Z,1493152932.201 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2017-04-25T20:42:12.219Z,1493152932.219 [NavChart] Loaded 2017-04-25T20:42:12.219Z,1493152932.219 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-04-25T20:42:12.224Z,1493152932.224 [UniversalFixResidualReporter] Loaded 2017-04-25T20:42:12.224Z,1493152932.224 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-04-25T20:42:12.224Z,1493152932.224 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-04-25T20:42:12.226Z,1493152932.226 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-04-25T20:42:12.235Z,1493152932.236 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-04-25T20:42:12.236Z,1493152932.236 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-04-25T20:42:12.431Z,1493152932.431 [Aanderaa_O2] Loaded 2017-04-25T20:42:12.431Z,1493152932.431 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-04-25T20:42:12.447Z,1493152932.447 [PAR_Licor] Loaded 2017-04-25T20:42:12.447Z,1493152932.447 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-04-25T20:42:12.499Z,1493152932.499 [WetLabsBB2FL] Loaded 2017-04-25T20:42:12.499Z,1493152932.499 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-04-25T20:42:12.500Z,1493152932.500 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407734E0 2017-04-25T20:42:12.501Z,1493152932.501 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 853 2017-04-25T20:42:12.501Z,1493152932.501 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-04-25T20:42:12.502Z,1493152932.502 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-04-25T20:42:13.132Z,1493152933.132 [DataOverHttps] Loaded 2017-04-25T20:42:13.133Z,1493152933.133 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-04-25T20:42:13.147Z,1493152933.147 [Depth_Keller] Loaded 2017-04-25T20:42:13.147Z,1493152933.147 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-04-25T20:42:13.153Z,1493152933.152 [DropWeight] Loaded 2017-04-25T20:42:13.153Z,1493152933.153 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-04-25T20:42:13.248Z,1493152933.248 [NAL9602] Loaded 2017-04-25T20:42:13.248Z,1493152933.248 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-04-25T20:42:13.258Z,1493152933.258 [Onboard] Loaded 2017-04-25T20:42:13.258Z,1493152933.258 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-04-25T20:42:13.266Z,1493152933.266 [Radio_Surface] Loaded 2017-04-25T20:42:13.266Z,1493152933.266 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-04-25T20:42:13.267Z,1493152933.267 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409BE4E0 2017-04-25T20:42:13.267Z,1493152933.267 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 854 2017-04-25T20:42:13.407Z,1493152933.407 [DAT] Loaded 2017-04-25T20:42:13.407Z,1493152933.408 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2017-04-25T20:42:13.414Z,1493152933.414 [SCPI] Loaded 2017-04-25T20:42:13.414Z,1493152933.414 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2017-04-25T20:42:13.609Z,1493152933.609 [PNI_TCM] Loaded 2017-04-25T20:42:13.609Z,1493152933.609 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-04-25T20:42:13.968Z,1493152933.968 [Rowe_600LCM] Loaded 2017-04-25T20:42:13.968Z,1493152933.968 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-04-25T20:42:13.970Z,1493152933.970 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409EE4E0 2017-04-25T20:42:13.970Z,1493152933.970 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 855 2017-04-25T20:42:15.937Z,1493152935.937 [BPC1] Loaded 2017-04-25T20:42:15.938Z,1493152935.938 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-04-25T20:42:15.938Z,1493152935.938 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-04-25T20:42:15.939Z,1493152935.939 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-04-25T20:42:16.066Z,1493152936.066 [BuoyancyServo] Loaded 2017-04-25T20:42:16.067Z,1493152936.067 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-04-25T20:42:16.079Z,1493152936.079 [ElevatorServo] Loaded 2017-04-25T20:42:16.079Z,1493152936.079 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-04-25T20:42:16.091Z,1493152936.091 [MassServo] Loaded 2017-04-25T20:42:16.091Z,1493152936.091 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-04-25T20:42:16.104Z,1493152936.103 [RudderServo] Loaded 2017-04-25T20:42:16.104Z,1493152936.104 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-04-25T20:42:16.116Z,1493152936.115 [ThrusterServo] Loaded 2017-04-25T20:42:16.116Z,1493152936.116 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-04-25T20:42:16.116Z,1493152936.116 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-04-25T20:42:16.117Z,1493152936.117 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-04-25T20:42:16.252Z,1493152936.252 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-04-25T20:42:16.252Z,1493152936.252 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-04-25T20:42:16.293Z,1493152936.293 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-04-25T20:42:16.297Z,1493152936.297 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-04-25T20:42:16.298Z,1493152936.298 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-04-25T20:42:16.305Z,1493152936.305 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-04-25T20:42:16.306Z,1493152936.306 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A9C4E0 2017-04-25T20:42:16.307Z,1493152936.307 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 856 2017-04-25T20:42:16.312Z,1493152936.312 [Supervisor](INFO): Main Thread ID is 764 2017-04-25T20:42:16.312Z,1493152936.312 [Supervisor](DEBUG): Running supervisor. 2017-04-25T20:42:16.312Z,1493152936.312 [CommandLine ThreadHandler](INFO): Handler Thread ID is 857 2017-04-25T20:42:16.315Z,1493152936.315 [controlThread ThreadHandler](INFO): Handler Thread ID is 858 2017-04-25T20:42:16.316Z,1493152936.316 [controlThread](DEBUG): Initializing ControlThread 2017-04-25T20:42:16.317Z,1493152936.317 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-04-25T20:42:16.319Z,1493152936.319 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-04-25T20:42:16.319Z,1493152936.319 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-04-25T20:42:16.320Z,1493152936.320 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-04-25T20:42:16.320Z,1493152936.320 [SBIT](INFO): Initialize SBIT Component. 2017-04-25T20:42:16.321Z,1493152936.321 [SBIT](IMPORTANT): git: 2017-04-12-7-gd58c26f 2017-04-25T20:42:16.321Z,1493152936.321 [SBIT](INFO): git hash: d58c26f82a1c7a1583dd69c6e907c094b897b246 2017-04-25T20:42:16.323Z,1493152936.323 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-04-25T20:42:16.323Z,1493152936.323 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016 2017-04-25T20:42:16.324Z,1493152936.324 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2017-04-25T20:42:16.324Z,1493152936.325 [IBIT](INFO): Initialize IBIT Component. 2017-04-25T20:42:16.325Z,1493152936.325 [CBIT](DEBUG): Initialize CBIT Component. 2017-04-25T20:42:16.326Z,1493152936.326 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-04-25T20:42:16.326Z,1493152936.326 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2017-04-25T20:42:16.327Z,1493152936.327 [logger ThreadHandler](INFO): Handler Thread ID is 859 2017-04-25T20:42:16.355Z,1493152936.355 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 860 2017-04-25T20:42:16.357Z,1493152936.357 [WetLabsBB2FL](INFO): Powering down 2017-04-25T20:42:16.391Z,1493152936.391 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 861 2017-04-25T20:42:16.397Z,1493152936.397 [Radio_Surface](INFO): Powering up 2017-04-25T20:42:16.403Z,1493152936.403 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 862 2017-04-25T20:42:16.404Z,1493152936.404 [Rowe_600LCM](INFO): Initializing 2017-04-25T20:42:16.405Z,1493152936.405 [Rowe_600LCM](INFO): Checking LCM 2017-04-25T20:42:16.405Z,1493152936.405 [Rowe_600LCM](INFO): LCM OK 2017-04-25T20:42:16.405Z,1493152936.405 [Rowe_600LCM](INFO): Powering up 2017-04-25T20:42:16.407Z,1493152936.407 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-04-25T20:42:16.408Z,1493152936.408 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-04-25T20:42:16.408Z,1493152936.408 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-04-25T20:42:16.408Z,1493152936.408 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-04-25T20:42:16.409Z,1493152936.409 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-04-25T20:42:16.410Z,1493152936.410 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-04-25T20:42:16.411Z,1493152936.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-04-25T20:42:16.412Z,1493152936.412 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-04-25T20:42:16.412Z,1493152936.412 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-04-25T20:42:16.413Z,1493152936.413 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-04-25T20:42:16.413Z,1493152936.413 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-04-25T20:42:16.414Z,1493152936.414 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-04-25T20:42:16.414Z,1493152936.414 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-04-25T20:42:16.414Z,1493152936.414 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-04-25T20:42:16.415Z,1493152936.415 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-04-25T20:42:16.415Z,1493152936.415 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-04-25T20:42:16.416Z,1493152936.416 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-04-25T20:42:16.429Z,1493152936.429 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-04-25T20:42:16.440Z,1493152936.441 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 863 2017-04-25T20:42:16.451Z,1493152936.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-04-25T20:42:16.451Z,1493152936.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-04-25T20:42:16.451Z,1493152936.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-04-25T20:42:16.451Z,1493152936.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-04-25T20:42:16.452Z,1493152936.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-04-25T20:42:16.452Z,1493152936.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-04-25T20:42:16.452Z,1493152936.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-04-25T20:42:16.452Z,1493152936.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-04-25T20:42:16.452Z,1493152936.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-04-25T20:42:16.453Z,1493152936.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-04-25T20:42:16.453Z,1493152936.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-04-25T20:42:16.453Z,1493152936.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-04-25T20:42:16.453Z,1493152936.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-04-25T20:42:16.454Z,1493152936.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-04-25T20:42:16.454Z,1493152936.454 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-04-25T20:42:16.454Z,1493152936.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-04-25T20:42:16.484Z,1493152936.484 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-04-25T20:42:16.519Z,1493152936.519 [MissionManager](DEBUG): 2017-04-25T20:42:16.520Z,1493152936.520 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-04-25T20:42:16.600Z,1493152936.600 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-04-25T20:42:16.602Z,1493152936.602 [Default:A.Wait](DEBUG): Construct Wait. 2017-04-25T20:42:16.603Z,1493152936.603 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-04-25T20:42:16.626Z,1493152936.625 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-04-25T20:42:16.644Z,1493152936.644 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-04-25T20:42:16.672Z,1493152936.672 [Default:E.Execute](DEBUG): Construct Execute. 2017-04-25T20:42:16.676Z,1493152936.676 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2017-04-25T20:42:16.692Z,1493152936.692 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,SCPI,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DepAvgTempFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-04-25T20:42:16.778Z,1493152936.778 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-04-25T20:42:16.961Z,1493152936.961 [DAT](INFO): Powering up 2017-04-25T20:42:16.962Z,1493152936.962 [DAT](DEBUG): Initializing DAT. 2017-04-25T20:42:17.223Z,1493152937.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:17.223Z,1493152937.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-25T20:42:17.355Z,1493152937.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:17.376Z,1493152937.376 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:17.383Z,1493152937.383 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:17.503Z,1493152937.503 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-04-25T20:42:17.511Z,1493152937.511 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-04-25T20:42:17.533Z,1493152937.533 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-04-25T20:42:17.539Z,1493152937.539 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-04-25T20:42:17.561Z,1493152937.561 [MassServo](DEBUG): Initializing EZServoServo. 2017-04-25T20:42:17.567Z,1493152937.567 [MassServo](DEBUG): Initializing MassServo. 2017-04-25T20:42:17.573Z,1493152937.573 [RudderServo](DEBUG): Initializing EZServoServo. 2017-04-25T20:42:17.579Z,1493152937.579 [RudderServo](DEBUG): Initializing RudderServo. 2017-04-25T20:42:17.601Z,1493152937.601 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-04-25T20:42:17.608Z,1493152937.608 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-04-25T20:42:17.640Z,1493152937.639 [CommandLine](FAULT): Scheduling is paused 2017-04-25T20:42:17.640Z,1493152937.640 [CBIT](INFO): Critical error at 20170425T204216 2017-04-25T20:42:17.640Z,1493152937.640 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-04-25T20:42:17.976Z,1493152937.976 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:17.976Z,1493152937.976 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-25T20:42:17.977Z,1493152937.977 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:17.978Z,1493152937.978 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:17.979Z,1493152937.978 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:18.339Z,1493152938.339 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-04-25T20:42:18.339Z,1493152938.339 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-04-25T20:42:18.339Z,1493152938.339 [BuoyancyServo] Communications Fault, FailCount= 1 2017-04-25T20:42:18.339Z,1493152938.339 [BuoyancyServo](ERROR): Communications Fault 2017-04-25T20:42:18.607Z,1493152938.606 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-04-25T20:42:18.629Z,1493152938.629 [NAL9602](INFO): Powering up NAL9602 2017-04-25T20:42:18.679Z,1493152938.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:18.680Z,1493152938.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-25T20:42:18.681Z,1493152938.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:18.681Z,1493152938.681 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:18.682Z,1493152938.682 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:18.688Z,1493152938.688 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-04-25T20:42:18.690Z,1493152938.690 [BuoyancyServo](INFO): Powering down 2017-04-25T20:42:19.067Z,1493152939.067 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:19.067Z,1493152939.067 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-25T20:42:19.068Z,1493152939.068 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:19.069Z,1493152939.069 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:19.070Z,1493152939.070 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:19.484Z,1493152939.484 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:19.484Z,1493152939.484 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-25T20:42:19.485Z,1493152939.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:19.486Z,1493152939.486 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:19.487Z,1493152939.487 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:19.935Z,1493152939.935 [Aanderaa_O2](INFO): Powering down 2017-04-25T20:42:20.052Z,1493152940.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:20.052Z,1493152940.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-25T20:42:20.053Z,1493152940.053 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:20.054Z,1493152940.054 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:20.055Z,1493152940.055 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:20.283Z,1493152940.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:20.283Z,1493152940.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-25T20:42:20.284Z,1493152940.284 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:20.285Z,1493152940.285 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:20.288Z,1493152940.288 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-25T20:42:20.694Z,1493152940.694 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-04-25T20:42:20.786Z,1493152940.786 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-25T20:42:20.800Z,1493152940.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:20.874Z,1493152940.874 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-04-25T20:42:20.988Z,1493152940.988 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2017-04-25T20:42:20.988Z,1493152940.988 [BuoyancyServo] No Fault, FailCount= 1 2017-04-25T20:42:21.148Z,1493152941.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-25T20:42:21.149Z,1493152941.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:21.181Z,1493152941.181 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-04-25T20:42:21.181Z,1493152941.181 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-04-25T20:42:21.570Z,1493152941.570 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-25T20:42:21.571Z,1493152941.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:21.609Z,1493152941.609 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-04-25T20:42:21.610Z,1493152941.610 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-04-25T20:42:21.611Z,1493152941.611 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-04-25T20:42:21.908Z,1493152941.908 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:22.312Z,1493152942.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:22.699Z,1493152942.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:23.082Z,1493152943.082 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:23.462Z,1493152943.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:23.910Z,1493152943.910 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:24.299Z,1493152944.299 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:24.702Z,1493152944.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:25.095Z,1493152945.095 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:25.510Z,1493152945.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:25.922Z,1493152945.922 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:26.282Z,1493152946.282 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:26.689Z,1493152946.689 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:27.054Z,1493152947.054 [DAT](DEBUG): 2017-04-25T20:42:27.106Z,1493152947.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:27.489Z,1493152947.489 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:27.878Z,1493152947.878 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:28.242Z,1493152948.242 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2017-04-25T20:42:28.243Z,1493152948.243 [DAT](DEBUG): MF Frequency Band 2017-04-25T20:42:28.244Z,1493152948.244 [DAT](DEBUG): Directional Acoustic Transponder version 8.10.9 2017-04-25T20:42:28.244Z,1493152948.244 [DAT](DEBUG): Apr 25 2017 20:42:25 2017-04-25T20:42:28.289Z,1493152948.289 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:28.766Z,1493152948.766 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:29.042Z,1493152949.042 [DAT](DEBUG): WARNING: battery low 2017-04-25T20:42:29.042Z,1493152949.042 [DAT](DEBUG): Features enabled [Bearing] 2017-04-25T20:42:29.068Z,1493152949.068 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:29.493Z,1493152949.493 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:29.825Z,1493152949.825 [NAL9602](INFO): NAL9602 initialized 2017-04-25T20:42:29.842Z,1493152949.842 [DAT](INFO): entering command mode 2017-04-25T20:42:29.889Z,1493152949.889 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:30.268Z,1493152950.268 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:30.346Z,1493152950.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:30.668Z,1493152950.668 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:30.771Z,1493152950.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:31.079Z,1493152951.079 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:31.175Z,1493152951.175 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:31.463Z,1493152951.464 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:31.510Z,1493152951.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:31.854Z,1493152951.854 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:31.926Z,1493152951.926 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:32.241Z,1493152952.241 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:32.317Z,1493152952.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:32.642Z,1493152952.642 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:32.768Z,1493152952.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:33.095Z,1493152953.095 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:33.138Z,1493152953.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:33.442Z,1493152953.442 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:33.499Z,1493152953.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:33.842Z,1493152953.842 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:33.872Z,1493152953.872 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:34.257Z,1493152954.257 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:34.297Z,1493152954.297 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:34.641Z,1493152954.641 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-25T20:42:34.744Z,1493152954.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-25T20:42:35.041Z,1493152955.041 [DAT](D