2017-11-14T19:14:02.294Z,1510686842.294 [Supervisor](DEBUG): Initializing supervisor. 2017-11-14T19:14:02.296Z,1510686842.296 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2017-11-14T19:14:02.297Z,1510686842.297 [SyncHandler](INFO): Protected caller Thread ID is 800 2017-11-14T19:14:02.297Z,1510686842.297 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-11-14T19:14:02.298Z,1510686842.298 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2017-11-14T19:14:02.299Z,1510686842.299 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 801 2017-11-14T19:14:02.301Z,1510686842.301 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-11-14T19:14:02.312Z,1510686842.312 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-11-14T19:14:02.313Z,1510686842.313 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2017-11-14T19:14:02.313Z,1510686842.313 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 802 2017-11-14T19:14:02.314Z,1510686842.314 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-11-14T19:14:02.315Z,1510686842.315 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2017-11-14T19:14:02.315Z,1510686842.315 [logger ThreadHandler](INFO): Protected caller Thread ID is 803 2017-11-14T19:14:02.317Z,1510686842.317 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-11-14T19:14:02.318Z,1510686842.318 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-11-14T19:14:02.322Z,1510686842.322 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-11-14T19:14:02.418Z,1510686842.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-11-14T19:14:02.419Z,1510686842.419 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-11-14T19:14:02.517Z,1510686842.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-11-14T19:14:02.519Z,1510686842.519 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-11-14T19:14:02.698Z,1510686842.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-11-14T19:14:02.699Z,1510686842.699 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-11-14T19:14:02.949Z,1510686842.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-11-14T19:14:02.951Z,1510686842.951 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-11-14T19:14:03.257Z,1510686843.257 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-11-14T19:14:03.258Z,1510686843.258 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-11-14T19:14:03.700Z,1510686843.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-11-14T19:14:03.701Z,1510686843.701 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-11-14T19:14:04.018Z,1510686844.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-11-14T19:14:04.019Z,1510686844.019 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-11-14T19:14:04.416Z,1510686844.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-11-14T19:14:04.418Z,1510686844.418 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-11-14T19:14:04.500Z,1510686844.500 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-11-14T19:14:04.778Z,1510686844.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-11-14T19:14:04.779Z,1510686844.779 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-11-14T19:14:04.910Z,1510686844.910 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-11-14T19:14:04.910Z,1510686844.910 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-11-14T19:14:05.109Z,1510686845.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-11-14T19:14:05.111Z,1510686845.111 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-11-14T19:14:05.301Z,1510686845.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-11-14T19:14:05.302Z,1510686845.302 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-11-14T19:14:05.397Z,1510686845.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-11-14T19:14:05.398Z,1510686845.398 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-11-14T19:14:05.542Z,1510686845.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-11-14T19:14:05.544Z,1510686845.544 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2017-11-14T19:14:06.027Z,1510686846.027 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2017-11-14T19:14:06.657Z,1510686846.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-11-14T19:14:06.658Z,1510686846.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2017-11-14T19:14:06.737Z,1510686846.737 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2017-11-14T19:14:06.877Z,1510686846.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2017-11-14T19:14:06.969Z,1510686846.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2017-11-14T19:14:07.054Z,1510686847.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2017-11-14T19:14:07.188Z,1510686847.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2017-11-14T19:14:07.349Z,1510686847.349 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2017-11-14T19:14:07.446Z,1510686847.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2017-11-14T19:14:07.538Z,1510686847.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2017-11-14T19:14:07.634Z,1510686847.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2017-11-14T19:14:07.717Z,1510686847.717 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2017-11-14T19:14:07.727Z,1510686847.727 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-11-14T19:14:07.853Z,1510686847.853 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-11-14T19:14:07.854Z,1510686847.854 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-11-14T19:14:07.878Z,1510686847.878 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-11-14T19:14:07.880Z,1510686847.880 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-11-14T19:14:07.915Z,1510686847.915 [DepthRateCalculator] Loaded 2017-11-14T19:14:07.916Z,1510686847.916 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-11-14T19:14:07.921Z,1510686847.921 [PitchRateCalculator] Loaded 2017-11-14T19:14:07.922Z,1510686847.922 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-11-14T19:14:07.937Z,1510686847.937 [SpeedCalculator] Loaded 2017-11-14T19:14:07.937Z,1510686847.937 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-11-14T19:14:07.958Z,1510686847.958 [TempGradientCalculator] Loaded 2017-11-14T19:14:07.958Z,1510686847.958 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-11-14T19:14:07.974Z,1510686847.974 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-11-14T19:14:07.974Z,1510686847.974 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-11-14T19:14:07.979Z,1510686847.979 [YawRateCalculator] Loaded 2017-11-14T19:14:07.980Z,1510686847.980 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-11-14T19:14:07.980Z,1510686847.980 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-11-14T19:14:07.981Z,1510686847.981 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-11-14T19:14:08.017Z,1510686848.017 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-11-14T19:14:08.018Z,1510686848.018 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-11-14T19:14:08.510Z,1510686848.510 [AcousticModem_Benthos_ATM900] Loaded 2017-11-14T19:14:08.511Z,1510686848.511 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2017-11-14T19:14:08.649Z,1510686848.649 [DataOverHttps] Loaded 2017-11-14T19:14:08.649Z,1510686848.649 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-11-14T19:14:08.663Z,1510686848.663 [Depth_Keller] Loaded 2017-11-14T19:14:08.663Z,1510686848.663 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-11-14T19:14:08.668Z,1510686848.668 [DropWeight] Loaded 2017-11-14T19:14:08.668Z,1510686848.668 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-11-14T19:14:08.684Z,1510686848.684 [Onboard] Loaded 2017-11-14T19:14:08.684Z,1510686848.684 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-11-14T19:14:08.695Z,1510686848.695 [Radio_Surface] Loaded 2017-11-14T19:14:08.695Z,1510686848.695 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-11-14T19:14:08.696Z,1510686848.696 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A44E0 2017-11-14T19:14:08.696Z,1510686848.696 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 883 2017-11-14T19:14:08.778Z,1510686848.778 [Rowe_600LCM] Loaded 2017-11-14T19:14:08.779Z,1510686848.779 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-11-14T19:14:08.780Z,1510686848.780 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D44E0 2017-11-14T19:14:08.780Z,1510686848.780 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 885 2017-11-14T19:14:08.781Z,1510686848.781 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-11-14T19:14:08.782Z,1510686848.782 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-11-14T19:14:08.933Z,1510686848.933 [SBIT](DEBUG): Construct Startup Built In Test. 2017-11-14T19:14:08.959Z,1510686848.959 [SBIT] Loaded 2017-11-14T19:14:08.960Z,1510686848.960 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-11-14T19:14:08.960Z,1510686848.960 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-11-14T19:14:08.981Z,1510686848.981 [IBIT] Loaded 2017-11-14T19:14:08.981Z,1510686848.981 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-11-14T19:14:08.984Z,1510686848.984 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-11-14T19:14:09.130Z,1510686849.130 [CBIT] Loaded 2017-11-14T19:14:09.131Z,1510686849.131 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-11-14T19:14:09.131Z,1510686849.131 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-11-14T19:14:09.132Z,1510686849.132 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-11-14T19:14:09.301Z,1510686849.301 [Aanderaa_O2] Loaded 2017-11-14T19:14:09.302Z,1510686849.302 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-11-14T19:14:09.378Z,1510686849.378 [CTD_NeilBrown] Loaded 2017-11-14T19:14:09.378Z,1510686849.378 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2017-11-14T19:14:09.379Z,1510686849.379 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408944E0 2017-11-14T19:14:09.380Z,1510686849.380 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 886 2017-11-14T19:14:09.411Z,1510686849.411 [CTD_Seabird] Loaded 2017-11-14T19:14:09.411Z,1510686849.411 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-11-14T19:14:09.412Z,1510686849.412 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408C44E0 2017-11-14T19:14:09.413Z,1510686849.413 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 887 2017-11-14T19:14:09.429Z,1510686849.429 [ESPComponent] Loaded 2017-11-14T19:14:09.429Z,1510686849.429 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2017-11-14T19:14:09.443Z,1510686849.443 [PAR_Licor] Loaded 2017-11-14T19:14:09.443Z,1510686849.443 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-11-14T19:14:09.490Z,1510686849.490 [WetLabsBB2FL] Loaded 2017-11-14T19:14:09.490Z,1510686849.490 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-11-14T19:14:09.491Z,1510686849.491 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408F44E0 2017-11-14T19:14:09.492Z,1510686849.492 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 888 2017-11-14T19:14:09.492Z,1510686849.492 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-11-14T19:14:09.493Z,1510686849.493 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-11-14T19:14:09.768Z,1510686849.768 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-11-14T19:14:09.770Z,1510686849.770 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-11-14T19:14:09.855Z,1510686849.855 [VerticalControl](DEBUG): Construct VerticalControl. 2017-11-14T19:14:09.953Z,1510686849.953 [VerticalControl] Loaded 2017-11-14T19:14:09.954Z,1510686849.954 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-11-14T19:14:09.954Z,1510686849.954 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-11-14T19:14:10.014Z,1510686850.014 [HorizontalControl] Loaded 2017-11-14T19:14:10.014Z,1510686850.014 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-11-14T19:14:10.015Z,1510686850.015 [SpeedControl](DEBUG): Construct SpeedControl. 2017-11-14T19:14:10.020Z,1510686850.020 [SpeedControl] Loaded 2017-11-14T19:14:10.020Z,1510686850.020 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-11-14T19:14:10.021Z,1510686850.021 [LoopControl](DEBUG): Construct LoopControl. 2017-11-14T19:14:10.021Z,1510686850.021 [LoopControl] Loaded 2017-11-14T19:14:10.022Z,1510686850.022 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-11-14T19:14:10.022Z,1510686850.022 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-11-14T19:14:10.023Z,1510686850.023 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-11-14T19:14:10.140Z,1510686850.140 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-11-14T19:14:10.140Z,1510686850.140 [StratificationFrontDetector](DEBUG): (re)initializing 2017-11-14T19:14:10.141Z,1510686850.141 [StratificationFrontDetector] Loaded 2017-11-14T19:14:10.141Z,1510686850.141 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-11-14T19:14:10.141Z,1510686850.141 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-11-14T19:14:10.142Z,1510686850.142 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-11-14T19:14:10.259Z,1510686850.259 [BuoyancyServo] Loaded 2017-11-14T19:14:10.259Z,1510686850.259 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-11-14T19:14:10.270Z,1510686850.270 [ElevatorServo] Loaded 2017-11-14T19:14:10.271Z,1510686850.270 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-11-14T19:14:10.281Z,1510686850.281 [MassServo] Loaded 2017-11-14T19:14:10.281Z,1510686850.281 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-11-14T19:14:10.292Z,1510686850.292 [RudderServo] Loaded 2017-11-14T19:14:10.292Z,1510686850.292 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-11-14T19:14:10.303Z,1510686850.303 [ThrusterServo] Loaded 2017-11-14T19:14:10.303Z,1510686850.303 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-11-14T19:14:10.303Z,1510686850.303 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-11-14T19:14:10.304Z,1510686850.304 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-11-14T19:14:10.417Z,1510686850.417 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-11-14T19:14:10.417Z,1510686850.417 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-11-14T19:14:10.470Z,1510686850.470 [DeadReckonUsingSpeedCalculator] Loaded 2017-11-14T19:14:10.470Z,1510686850.470 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-11-14T19:14:10.486Z,1510686850.486 [NavChart] Loaded 2017-11-14T19:14:10.486Z,1510686850.486 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-11-14T19:14:10.491Z,1510686850.491 [UniversalFixResidualReporter] Loaded 2017-11-14T19:14:10.491Z,1510686850.491 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-11-14T19:14:10.491Z,1510686850.491 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-11-14T19:14:10.495Z,1510686850.495 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-11-14T19:14:10.496Z,1510686850.496 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-11-14T19:14:10.502Z,1510686850.502 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-11-14T19:14:10.503Z,1510686850.503 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0 2017-11-14T19:14:10.504Z,1510686850.504 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 889 2017-11-14T19:14:10.508Z,1510686850.508 [Supervisor](INFO): Main Thread ID is 796 2017-11-14T19:14:10.508Z,1510686850.508 [Supervisor](DEBUG): Running supervisor. 2017-11-14T19:14:10.509Z,1510686850.509 [CommandLine ThreadHandler](INFO): Handler Thread ID is 890 2017-11-14T19:14:10.512Z,1510686850.512 [controlThread ThreadHandler](INFO): Handler Thread ID is 891 2017-11-14T19:14:10.512Z,1510686850.512 [controlThread](DEBUG): Initializing ControlThread 2017-11-14T19:14:10.513Z,1510686850.513 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-11-14T19:14:10.513Z,1510686850.513 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-11-14T19:14:10.514Z,1510686850.514 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-11-14T19:14:10.514Z,1510686850.514 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-11-14T19:14:10.514Z,1510686850.514 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-11-14T19:14:10.515Z,1510686850.515 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-11-14T19:14:10.519Z,1510686850.519 [SBIT](INFO): Initialize SBIT Component. 2017-11-14T19:14:10.519Z,1510686850.519 [SBIT](IMPORTANT): git: 2017-10-26-27-g28caa4f 2017-11-14T19:14:10.519Z,1510686850.519 [SBIT](INFO): git hash: 28caa4f52d4014465ae2cbbbbc56c9b8302a605b 2017-11-14T19:14:10.520Z,1510686850.520 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2017-11-14T19:14:10.521Z,1510686850.521 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #17 PREEMPT Fri Oct 6 17:22:04 PDT 2017 2017-11-14T19:14:10.522Z,1510686850.522 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2017-11-14T19:14:10.522Z,1510686850.522 [IBIT](INFO): Initialize IBIT Component. 2017-11-14T19:14:10.523Z,1510686850.523 [CBIT](DEBUG): Initialize CBIT Component. 2017-11-14T19:14:10.523Z,1510686850.523 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2017-11-14T19:14:10.524Z,1510686850.524 [logger ThreadHandler](INFO): Handler Thread ID is 892 2017-11-14T19:14:10.541Z,1510686850.541 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 893 2017-11-14T19:14:10.547Z,1510686850.547 [Radio_Surface](INFO): Powering up 2017-11-14T19:14:10.553Z,1510686850.553 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 894 2017-11-14T19:14:10.554Z,1510686850.554 [Rowe_600LCM](INFO): Initializing 2017-11-14T19:14:10.554Z,1510686850.554 [Rowe_600LCM](INFO): Checking LCM 2017-11-14T19:14:10.613Z,1510686850.613 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-11-14T19:14:10.615Z,1510686850.615 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-11-14T19:14:10.615Z,1510686850.615 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-11-14T19:14:10.616Z,1510686850.616 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-11-14T19:14:10.618Z,1510686850.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-11-14T19:14:10.619Z,1510686850.619 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-11-14T19:14:10.663Z,1510686850.663 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-11-14T19:14:10.664Z,1510686850.664 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-11-14T19:14:10.664Z,1510686850.664 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-11-14T19:14:10.665Z,1510686850.665 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-11-14T19:14:10.665Z,1510686850.665 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-11-14T19:14:10.666Z,1510686850.666 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-11-14T19:14:10.667Z,1510686850.667 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-11-14T19:14:10.667Z,1510686850.667 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 895 2017-11-14T19:14:10.669Z,1510686850.669 [CTD_NeilBrown](INFO): Powering down 2017-11-14T19:14:10.679Z,1510686850.679 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 896 2017-11-14T19:14:10.690Z,1510686850.690 [CTD_Seabird](INFO): Initializing 2017-11-14T19:14:10.840Z,1510686850.840 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 898 2017-11-14T19:14:10.841Z,1510686850.841 [WetLabsBB2FL](INFO): Powering down 2017-11-14T19:14:10.870Z,1510686850.870 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 899 2017-11-14T19:14:10.877Z,1510686850.877 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-11-14T19:14:10.878Z,1510686850.878 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-11-14T19:14:10.878Z,1510686850.878 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-11-14T19:14:10.878Z,1510686850.878 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-11-14T19:14:10.878Z,1510686850.878 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-11-14T19:14:10.878Z,1510686850.878 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-11-14T19:14:10.878Z,1510686850.878 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-11-14T19:14:10.879Z,1510686850.879 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-11-14T19:14:10.879Z,1510686850.879 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-11-14T19:14:10.879Z,1510686850.879 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-11-14T19:14:10.879Z,1510686850.879 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-11-14T19:14:10.879Z,1510686850.879 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-11-14T19:14:10.880Z,1510686850.880 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-11-14T19:14:10.880Z,1510686850.880 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-11-14T19:14:10.880Z,1510686850.880 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-11-14T19:14:10.880Z,1510686850.880 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-11-14T19:14:10.919Z,1510686850.919 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-11-14T19:14:10.919Z,1510686850.919 [CTD_Seabird](INFO): Powering down 2017-11-14T19:14:11.071Z,1510686851.071 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-11-14T19:14:11.094Z,1510686851.094 [MissionManager](DEBUG): 2017-11-14T19:14:11.095Z,1510686851.095 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-11-14T19:14:11.194Z,1510686851.194 [Rowe_600LCM](INFO): LCM OK 2017-11-14T19:14:11.194Z,1510686851.194 [Rowe_600LCM](INFO): Powering up 2017-11-14T19:14:11.282Z,1510686851.282 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-11-14T19:14:11.283Z,1510686851.283 [Default:A.Wait](DEBUG): Construct Wait. 2017-11-14T19:14:11.309Z,1510686851.309 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-11-14T19:14:11.334Z,1510686851.334 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-11-14T19:14:11.337Z,1510686851.337 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-11-14T19:14:11.355Z,1510686851.355 [Default:E.Execute](DEBUG): Construct Execute. 2017-11-14T19:14:11.358Z,1510686851.358 [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-11-14T19:14:11.377Z,1510686851.377 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,Onboard,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-11-14T19:14:11.401Z,1510686851.401 [AcousticModem_Benthos_ATM900](INFO): Powering up 2017-11-14T19:14:11.401Z,1510686851.401 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2017-11-14T19:14:11.428Z,1510686851.428 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-11-14T19:14:11.707Z,1510686851.707 [ESPComponent](INFO): powering down ESP 2017-11-14T19:14:12.206Z,1510686852.206 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:12.486Z,1510686852.486 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:12.589Z,1510686852.589 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-11-14T19:14:12.597Z,1510686852.597 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-11-14T19:14:12.611Z,1510686852.611 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-11-14T19:14:12.617Z,1510686852.617 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-11-14T19:14:12.631Z,1510686852.631 [MassServo](DEBUG): Initializing EZServoServo. 2017-11-14T19:14:12.641Z,1510686852.641 [MassServo](DEBUG): Initializing MassServo. 2017-11-14T19:14:12.662Z,1510686852.662 [RudderServo](DEBUG): Initializing EZServoServo. 2017-11-14T19:14:12.669Z,1510686852.669 [RudderServo](DEBUG): Initializing RudderServo. 2017-11-14T19:14:12.682Z,1510686852.682 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-11-14T19:14:12.689Z,1510686852.689 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-11-14T19:14:12.727Z,1510686852.727 [CommandLine](FAULT): Scheduling is paused 2017-11-14T19:14:12.727Z,1510686852.727 [CBIT](INFO): Critical error at 20171114T191410 2017-11-14T19:14:12.727Z,1510686852.727 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-11-14T19:14:12.909Z,1510686852.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:12.917Z,1510686852.917 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:13.214Z,1510686853.214 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:13.215Z,1510686853.215 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:13.561Z,1510686853.561 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-11-14T19:14:13.561Z,1510686853.561 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-11-14T19:14:13.561Z,1510686853.561 [BuoyancyServo] Communications Fault, FailCount= 1 2017-11-14T19:14:13.561Z,1510686853.561 [BuoyancyServo](ERROR): Communications Fault 2017-11-14T19:14:13.768Z,1510686853.768 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-11-14T19:14:13.821Z,1510686853.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:13.822Z,1510686853.822 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:13.834Z,1510686853.834 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-11-14T19:14:13.835Z,1510686853.835 [BuoyancyServo](INFO): Powering down 2017-11-14T19:14:14.228Z,1510686854.228 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:14.245Z,1510686854.245 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:14.609Z,1510686854.609 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:14.621Z,1510686854.621 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:15.034Z,1510686855.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:15.036Z,1510686855.036 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:15.159Z,1510686855.159 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-11-14T19:14:15.240Z,1510686855.240 [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-11-14T19:14:15.437Z,1510686855.437 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:15.440Z,1510686855.440 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:15.447Z,1510686855.447 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-11-14T19:14:15.448Z,1510686855.448 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-11-14T19:14:15.448Z,1510686855.448 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-11-14T19:14:15.845Z,1510686855.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:15.846Z,1510686855.846 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:15.899Z,1510686855.899 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2017-11-14T19:14:15.899Z,1510686855.899 [BuoyancyServo] No Fault, FailCount= 1 2017-11-14T19:14:16.224Z,1510686856.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:16.233Z,1510686856.233 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:16.248Z,1510686856.248 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-11-14T19:14:16.248Z,1510686856.248 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-11-14T19:14:16.625Z,1510686856.625 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:16.626Z,1510686856.626 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:17.029Z,1510686857.029 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:17.030Z,1510686857.030 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:17.433Z,1510686857.433 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:17.434Z,1510686857.434 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:17.830Z,1510686857.830 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:17.831Z,1510686857.831 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:18.225Z,1510686858.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:18.226Z,1510686858.226 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:18.637Z,1510686858.637 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:18.638Z,1510686858.638 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:18.976Z,1510686858.976 [AcousticModem_Benthos_ATM900](DEBUG): 2017-11-14T19:14:19.009Z,1510686859.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:19.010Z,1510686859.010 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:19.445Z,1510686859.445 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:19.446Z,1510686859.446 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:19.848Z,1510686859.848 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:19.854Z,1510686859.854 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:20.179Z,1510686860.179 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2017-11-14T19:14:20.180Z,1510686860.180 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2017-11-14T19:14:20.181Z,1510686860.181 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2017-11-14T19:14:20.241Z,1510686860.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:20.242Z,1510686860.242 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:20.577Z,1510686860.577 [AcousticModem_Benthos_ATM900](DEBUG): Jan 9 2000 01:34:40 2017-11-14T19:14:20.638Z,1510686860.638 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:20.639Z,1510686860.639 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:20.976Z,1510686860.976 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2017-11-14T19:14:21.009Z,1510686861.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:21.010Z,1510686861.010 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:21.433Z,1510686861.433 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:21.434Z,1510686861.434 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:21.776Z,1510686861.776 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2017-11-14T19:14:21.798Z,1510686861.798 [Aanderaa_O2](FAULT): Timed out starting 2017-11-14T19:14:21.798Z,1510686861.798 [Aanderaa_O2] Communications Fault, FailCount= 1 2017-11-14T19:14:21.799Z,1510686861.799 [Aanderaa_O2](ERROR): Communications Fault 2017-11-14T19:14:21.837Z,1510686861.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:21.838Z,1510686861.838 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:21.894Z,1510686861.894 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2017-11-14T19:14:22.176Z,1510686862.176 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2017-11-14T19:14:22.177Z,1510686862.177 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2017-11-14T19:14:22.177Z,1510686862.177 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2017-11-14T19:14:22.200Z,1510686862.200 [Aanderaa_O2](INFO): Powering down 2017-11-14T19:14:22.267Z,1510686862.267 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:22.268Z,1510686862.268 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:22.576Z,1510686862.576 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2017-11-14T19:14:22.647Z,1510686862.647 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:22.657Z,1510686862.657 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:22.976Z,1510686862.976 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2017-11-14T19:14:22.977Z,1510686862.977 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2017-11-14T19:14:23.034Z,1510686863.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:23.035Z,1510686863.035 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:23.101Z,1510686863.101 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2017-11-14T19:14:23.102Z,1510686863.102 [Aanderaa_O2] No Fault, FailCount= 1 2017-11-14T19:14:23.377Z,1510686863.377 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2017-11-14T19:14:23.411Z,1510686863.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:23.412Z,1510686863.412 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:23.818Z,1510686863.818 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:23.819Z,1510686863.819 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:24.236Z,1510686864.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:24.245Z,1510686864.245 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:24.638Z,1510686864.638 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:24.639Z,1510686864.639 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:25.030Z,1510686865.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:25.031Z,1510686865.031 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:25.441Z,1510686865.441 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:25.442Z,1510686865.442 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:25.817Z,1510686865.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:25.818Z,1510686865.818 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:26.238Z,1510686866.238 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:26.239Z,1510686866.239 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:26.633Z,1510686866.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:26.633Z,1510686866.633 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:27.037Z,1510686867.037 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:27.038Z,1510686867.038 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:27.413Z,1510686867.413 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:27.414Z,1510686867.414 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:27.826Z,1510686867.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:27.827Z,1510686867.827 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:28.221Z,1510686868.221 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:28.222Z,1510686868.222 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:28.613Z,1510686868.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:28.614Z,1510686868.614 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:29.044Z,1510686869.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:29.045Z,1510686869.045 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:29.425Z,1510686869.425 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:29.426Z,1510686869.426 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:29.813Z,1510686869.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:29.814Z,1510686869.814 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:30.217Z,1510686870.217 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:30.218Z,1510686870.218 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:30.618Z,1510686870.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:30.619Z,1510686870.619 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:31.022Z,1510686871.022 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:31.023Z,1510686871.023 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:31.440Z,1510686871.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:31.441Z,1510686871.441 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:31.825Z,1510686871.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:31.826Z,1510686871.826 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:32.240Z,1510686872.240 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:32.245Z,1510686872.245 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:32.629Z,1510686872.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:32.630Z,1510686872.630 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:33.021Z,1510686873.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:33.022Z,1510686873.022 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:33.404Z,1510686873.404 [Aanderaa_O2](FAULT): Timed out starting 2017-11-14T19:14:33.405Z,1510686873.405 [Aanderaa_O2] Communications Fault, FailCount= 2 2017-11-14T19:14:33.405Z,1510686873.405 [Aanderaa_O2](ERROR): Communications Fault 2017-11-14T19:14:33.437Z,1510686873.437 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:33.438Z,1510686873.438 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:33.485Z,1510686873.485 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2017-11-14T19:14:33.795Z,1510686873.795 [Aanderaa_O2](INFO): Powering down 2017-11-14T19:14:33.836Z,1510686873.836 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:33.837Z,1510686873.837 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:34.225Z,1510686874.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:34.226Z,1510686874.226 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:34.628Z,1510686874.628 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:34.641Z,1510686874.641 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:34.652Z,1510686874.652 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2017-11-14T19:14:34.652Z,1510686874.652 [Aanderaa_O2] No Fault, FailCount= 2 2017-11-14T19:14:35.025Z,1510686875.025 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:35.026Z,1510686875.026 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:35.440Z,1510686875.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:35.445Z,1510686875.445 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:35.865Z,1510686875.865 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:35.866Z,1510686875.866 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:36.229Z,1510686876.229 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:36.230Z,1510686876.230 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-14T19:14:36.621Z,151068