2017-11-14T21:41:00.062Z,1510695660.062 [Supervisor](DEBUG): Initializing supervisor.
2017-11-14T21:41:00.065Z,1510695660.065 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2017-11-14T21:41:00.065Z,1510695660.065 [SyncHandler](INFO): Protected caller Thread ID is 805
2017-11-14T21:41:00.065Z,1510695660.065 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-11-14T21:41:00.066Z,1510695660.066 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2017-11-14T21:41:00.067Z,1510695660.067 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 806
2017-11-14T21:41:00.069Z,1510695660.069 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-11-14T21:41:00.080Z,1510695660.080 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-11-14T21:41:00.081Z,1510695660.081 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2017-11-14T21:41:00.081Z,1510695660.081 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 807
2017-11-14T21:41:00.082Z,1510695660.082 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-11-14T21:41:00.083Z,1510695660.083 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2017-11-14T21:41:00.083Z,1510695660.083 [logger ThreadHandler](INFO): Protected caller Thread ID is 808
2017-11-14T21:41:00.085Z,1510695660.085 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-11-14T21:41:00.085Z,1510695660.085 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-11-14T21:41:00.090Z,1510695660.090 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-11-14T21:41:00.236Z,1510695660.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-11-14T21:41:00.238Z,1510695660.238 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-11-14T21:41:00.336Z,1510695660.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-11-14T21:41:00.336Z,1510695660.336 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-11-14T21:41:00.515Z,1510695660.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-11-14T21:41:00.516Z,1510695660.516 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-11-14T21:41:00.711Z,1510695660.711 [Config/Science](ERROR): Could not find unit: ppb/count following WetLabsSeaOWL_UV_A.scaleFactorFDOM = NaN
2017-11-14T21:41:00.723Z,1510695660.723 [Config/Science](ERROR): Could not find unit: ppb/count following WetLabsSeaOWL_UV_A.scaleFactorOil = NaN
2017-11-14T21:41:00.796Z,1510695660.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-11-14T21:41:00.798Z,1510695660.798 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-11-14T21:41:01.097Z,1510695661.097 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-11-14T21:41:01.099Z,1510695661.099 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-11-14T21:41:01.519Z,1510695661.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-11-14T21:41:01.521Z,1510695661.521 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-11-14T21:41:01.836Z,1510695661.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-11-14T21:41:01.837Z,1510695661.837 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-11-14T21:41:02.243Z,1510695662.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-11-14T21:41:02.244Z,1510695662.244 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-11-14T21:41:02.324Z,1510695662.324 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-11-14T21:41:02.600Z,1510695662.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-11-14T21:41:02.602Z,1510695662.602 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-11-14T21:41:02.728Z,1510695662.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-11-14T21:41:02.728Z,1510695662.728 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-11-14T21:41:02.924Z,1510695662.924 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-11-14T21:41:02.954Z,1510695662.954 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-11-14T21:41:03.923Z,1510695663.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-11-14T21:41:03.923Z,1510695663.923 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-11-14T21:41:04.046Z,1510695664.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-11-14T21:41:04.047Z,1510695664.047 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-11-14T21:41:04.180Z,1510695664.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-11-14T21:41:04.182Z,1510695664.182 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2017-11-14T21:41:04.185Z,1510695664.185 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2017-11-14T21:41:04.401Z,1510695664.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-11-14T21:41:04.402Z,1510695664.402 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2017-11-14T21:41:04.479Z,1510695664.479 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2017-11-14T21:41:04.615Z,1510695664.615 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2017-11-14T21:41:04.706Z,1510695664.706 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2017-11-14T21:41:04.791Z,1510695664.791 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2017-11-14T21:41:04.921Z,1510695664.921 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2017-11-14T21:41:05.077Z,1510695665.077 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2017-11-14T21:41:05.173Z,1510695665.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2017-11-14T21:41:05.263Z,1510695665.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2017-11-14T21:41:05.358Z,1510695665.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2017-11-14T21:41:05.441Z,1510695665.441 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2017-11-14T21:41:05.445Z,1510695665.445 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-11-14T21:41:05.567Z,1510695665.567 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-11-14T21:41:05.568Z,1510695665.568 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-11-14T21:41:05.591Z,1510695665.591 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-11-14T21:41:05.593Z,1510695665.593 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-11-14T21:41:05.628Z,1510695665.628 [DepthRateCalculator] Loaded
2017-11-14T21:41:05.628Z,1510695665.628 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-11-14T21:41:05.634Z,1510695665.634 [PitchRateCalculator] Loaded
2017-11-14T21:41:05.634Z,1510695665.634 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-11-14T21:41:05.649Z,1510695665.649 [SpeedCalculator] Loaded
2017-11-14T21:41:05.649Z,1510695665.649 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-11-14T21:41:05.669Z,1510695665.669 [TempGradientCalculator] Loaded
2017-11-14T21:41:05.670Z,1510695665.670 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-11-14T21:41:05.685Z,1510695665.685 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-11-14T21:41:05.685Z,1510695665.685 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-11-14T21:41:05.691Z,1510695665.691 [YawRateCalculator] Loaded
2017-11-14T21:41:05.691Z,1510695665.691 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-11-14T21:41:05.691Z,1510695665.691 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-11-14T21:41:05.692Z,1510695665.692 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-11-14T21:41:05.728Z,1510695665.728 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-11-14T21:41:05.729Z,1510695665.729 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-11-14T21:41:06.307Z,1510695666.307 [AcousticModem_Benthos_ATM900] Loaded
2017-11-14T21:41:06.307Z,1510695666.307 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2017-11-14T21:41:06.632Z,1510695666.632 [DataOverHttps] Loaded
2017-11-14T21:41:06.632Z,1510695666.632 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-11-14T21:41:06.645Z,1510695666.645 [Depth_Keller] Loaded
2017-11-14T21:41:06.646Z,1510695666.646 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-11-14T21:41:06.650Z,1510695666.650 [DropWeight] Loaded
2017-11-14T21:41:06.650Z,1510695666.650 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-11-14T21:41:06.744Z,1510695666.744 [NAL9602] Loaded
2017-11-14T21:41:06.744Z,1510695666.744 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-11-14T21:41:06.759Z,1510695666.759 [Onboard] Loaded
2017-11-14T21:41:06.759Z,1510695666.759 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-11-14T21:41:06.769Z,1510695666.769 [Radio_Surface] Loaded
2017-11-14T21:41:06.769Z,1510695666.769 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-11-14T21:41:06.770Z,1510695666.770 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A44E0
2017-11-14T21:41:06.771Z,1510695666.771 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 887
2017-11-14T21:41:06.921Z,1510695666.921 [PNI_TCM] Loaded
2017-11-14T21:41:06.921Z,1510695666.921 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-11-14T21:41:06.980Z,1510695666.980 [Rowe_600LCM] Loaded
2017-11-14T21:41:06.980Z,1510695666.980 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-11-14T21:41:06.981Z,1510695666.981 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D44E0
2017-11-14T21:41:06.982Z,1510695666.982 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 888
2017-11-14T21:41:08.743Z,1510695668.743 [BPC1] Loaded
2017-11-14T21:41:08.743Z,1510695668.743 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-11-14T21:41:08.744Z,1510695668.744 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-11-14T21:41:08.744Z,1510695668.744 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-11-14T21:41:09.149Z,1510695669.149 [SBIT](DEBUG): Construct Startup Built In Test.
2017-11-14T21:41:09.175Z,1510695669.175 [SBIT] Loaded
2017-11-14T21:41:09.175Z,1510695669.175 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-11-14T21:41:09.176Z,1510695669.176 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-11-14T21:41:09.186Z,1510695669.186 [IBIT] Loaded
2017-11-14T21:41:09.187Z,1510695669.187 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-11-14T21:41:09.190Z,1510695669.190 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-11-14T21:41:09.391Z,1510695669.391 [CBIT] Loaded
2017-11-14T21:41:09.391Z,1510695669.391 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-11-14T21:41:09.391Z,1510695669.391 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-11-14T21:41:09.392Z,1510695669.392 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-11-14T21:41:09.452Z,1510695669.452 [Module Loader](ERROR): Cannot load library: Modules/Science.so: undefined symbol: _ZN5Units26PART_PER_BILLION_PER_COUNTE
2017-11-14T21:41:09.452Z,1510695669.452 [Module Loader](ERROR): Could not load the module at Modules/Science.so
2017-11-14T21:41:09.454Z,1510695669.454 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-11-14T21:41:09.718Z,1510695669.718 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-11-14T21:41:09.720Z,1510695669.720 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-11-14T21:41:09.804Z,1510695669.804 [VerticalControl](DEBUG): Construct VerticalControl.
2017-11-14T21:41:09.897Z,1510695669.897 [VerticalControl] Loaded
2017-11-14T21:41:09.898Z,1510695669.898 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-11-14T21:41:09.898Z,1510695669.898 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-11-14T21:41:09.956Z,1510695669.956 [HorizontalControl] Loaded
2017-11-14T21:41:09.956Z,1510695669.956 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-11-14T21:41:09.957Z,1510695669.957 [SpeedControl](DEBUG): Construct SpeedControl.
2017-11-14T21:41:09.962Z,1510695669.962 [SpeedControl] Loaded
2017-11-14T21:41:09.962Z,1510695669.962 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-11-14T21:41:09.963Z,1510695669.963 [LoopControl](DEBUG): Construct LoopControl.
2017-11-14T21:41:09.963Z,1510695669.963 [LoopControl] Loaded
2017-11-14T21:41:09.964Z,1510695669.964 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-11-14T21:41:09.964Z,1510695669.964 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-11-14T21:41:09.966Z,1510695669.966 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-11-14T21:41:10.081Z,1510695670.081 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-11-14T21:41:10.082Z,1510695670.082 [StratificationFrontDetector](DEBUG): (re)initializing
2017-11-14T21:41:10.082Z,1510695670.082 [StratificationFrontDetector] Loaded
2017-11-14T21:41:10.082Z,1510695670.082 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-11-14T21:41:10.083Z,1510695670.083 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-11-14T21:41:10.084Z,1510695670.084 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-11-14T21:41:10.199Z,1510695670.199 [BuoyancyServo] Loaded
2017-11-14T21:41:10.199Z,1510695670.199 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-11-14T21:41:10.210Z,1510695670.210 [ElevatorServo] Loaded
2017-11-14T21:41:10.210Z,1510695670.210 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-11-14T21:41:10.221Z,1510695670.221 [MassServo] Loaded
2017-11-14T21:41:10.221Z,1510695670.221 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-11-14T21:41:10.231Z,1510695670.231 [RudderServo] Loaded
2017-11-14T21:41:10.232Z,1510695670.232 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-11-14T21:41:10.242Z,1510695670.242 [ThrusterServo] Loaded
2017-11-14T21:41:10.242Z,1510695670.242 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-11-14T21:41:10.243Z,1510695670.243 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-11-14T21:41:10.243Z,1510695670.243 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-11-14T21:41:10.353Z,1510695670.353 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-11-14T21:41:10.353Z,1510695670.353 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-11-14T21:41:10.405Z,1510695670.405 [DeadReckonUsingSpeedCalculator] Loaded
2017-11-14T21:41:10.406Z,1510695670.406 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-11-14T21:41:10.421Z,1510695670.421 [NavChart] Loaded
2017-11-14T21:41:10.421Z,1510695670.421 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-11-14T21:41:10.425Z,1510695670.425 [UniversalFixResidualReporter] Loaded
2017-11-14T21:41:10.425Z,1510695670.425 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-11-14T21:41:10.426Z,1510695670.426 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-11-14T21:41:10.429Z,1510695670.429 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-11-14T21:41:10.430Z,1510695670.430 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-11-14T21:41:10.437Z,1510695670.437 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-11-14T21:41:10.438Z,1510695670.438 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 4095D4E0
2017-11-14T21:41:10.438Z,1510695670.438 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 889
2017-11-14T21:41:10.443Z,1510695670.443 [Supervisor](INFO): Main Thread ID is 801
2017-11-14T21:41:10.443Z,1510695670.443 [Supervisor](DEBUG): Running supervisor.
2017-11-14T21:41:10.443Z,1510695670.443 [CommandLine ThreadHandler](INFO): Handler Thread ID is 890
2017-11-14T21:41:10.446Z,1510695670.446 [controlThread ThreadHandler](INFO): Handler Thread ID is 891
2017-11-14T21:41:10.446Z,1510695670.446 [controlThread](DEBUG): Initializing ControlThread
2017-11-14T21:41:10.447Z,1510695670.447 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-11-14T21:41:10.448Z,1510695670.448 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-11-14T21:41:10.448Z,1510695670.448 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-11-14T21:41:10.448Z,1510695670.448 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-11-14T21:41:10.449Z,1510695670.449 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-11-14T21:41:10.449Z,1510695670.449 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-11-14T21:41:10.457Z,1510695670.457 [SBIT](INFO): Initialize SBIT Component.
2017-11-14T21:41:10.458Z,1510695670.458 [SBIT](IMPORTANT): git: 2017-11-14
2017-11-14T21:41:10.458Z,1510695670.458 [SBIT](INFO): git hash: b21bf5b9e4daab436f1bddfb402b4b2343f98d24
2017-11-14T21:41:10.458Z,1510695670.458 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2017-11-14T21:41:10.459Z,1510695670.459 [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-14T21:41:10.460Z,1510695670.460 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2017-11-14T21:41:10.461Z,1510695670.461 [IBIT](INFO): Initialize IBIT Component.
2017-11-14T21:41:10.461Z,1510695670.461 [CBIT](DEBUG): Initialize CBIT Component.
2017-11-14T21:41:10.462Z,1510695670.462 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2017-11-14T21:41:10.462Z,1510695670.462 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-11-14T21:41:10.463Z,1510695670.463 [logger ThreadHandler](INFO): Handler Thread ID is 892
2017-11-14T21:41:10.483Z,1510695670.483 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 893
2017-11-14T21:41:10.489Z,1510695670.489 [Radio_Surface](INFO): Powering up
2017-11-14T21:41:10.495Z,1510695670.495 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 894
2017-11-14T21:41:10.495Z,1510695670.495 [Rowe_600LCM](INFO): Initializing
2017-11-14T21:41:10.496Z,1510695670.496 [Rowe_600LCM](INFO): Checking LCM
2017-11-14T21:41:10.543Z,1510695670.543 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-11-14T21:41:10.544Z,1510695670.544 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-11-14T21:41:10.545Z,1510695670.545 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-11-14T21:41:10.546Z,1510695670.546 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-11-14T21:41:10.591Z,1510695670.591 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-11-14T21:41:10.592Z,1510695670.592 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-11-14T21:41:10.592Z,1510695670.592 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-11-14T21:41:10.592Z,1510695670.592 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-11-14T21:41:10.593Z,1510695670.593 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-11-14T21:41:10.593Z,1510695670.593 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-11-14T21:41:10.594Z,1510695670.594 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-11-14T21:41:10.594Z,1510695670.594 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-11-14T21:41:10.595Z,1510695670.595 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-11-14T21:41:10.605Z,1510695670.605 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 895
2017-11-14T21:41:10.681Z,1510695670.681 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-11-14T21:41:10.681Z,1510695670.681 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-11-14T21:41:10.681Z,1510695670.681 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-11-14T21:41:10.681Z,1510695670.681 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-11-14T21:41:10.681Z,1510695670.681 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-11-14T21:41:10.681Z,1510695670.681 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-11-14T21:41:10.682Z,1510695670.682 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-11-14T21:41:10.682Z,1510695670.682 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-11-14T21:41:10.682Z,1510695670.682 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-11-14T21:41:10.682Z,1510695670.682 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-11-14T21:41:10.683Z,1510695670.683 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-11-14T21:41:10.683Z,1510695670.683 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-11-14T21:41:10.683Z,1510695670.683 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-11-14T21:41:10.683Z,1510695670.683 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-11-14T21:41:10.683Z,1510695670.683 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-11-14T21:41:10.683Z,1510695670.683 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-11-14T21:41:10.696Z,1510695670.696 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-11-14T21:41:10.800Z,1510695670.800 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2017-11-14T21:41:10.801Z,1510695670.801 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-11-14T21:41:10.891Z,1510695670.891 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-11-14T21:41:10.892Z,1510695670.892 [Default:A.Wait](DEBUG): Construct Wait.
2017-11-14T21:41:10.894Z,1510695670.894 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-11-14T21:41:10.936Z,1510695670.936 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-11-14T21:41:10.960Z,1510695670.960 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-11-14T21:41:10.968Z,1510695670.968 [Default:E.Execute](DEBUG): Construct Execute.
2017-11-14T21:41:10.975Z,1510695670.975 [Rowe_600LCM](INFO): LCM OK
2017-11-14T21:41:10.975Z,1510695670.975 [Rowe_600LCM](INFO): Powering up
2017-11-14T21:41:10.980Z,1510695670.980 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Guidance="Tethys/Guidance"
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2017-11-14T21:41:10.985Z,1510695670.985 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Depth_Keller,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-14T21:41:11.017Z,1510695671.017 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-11-14T21:41:11.017Z,1510695671.017 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-11-14T21:41:11.036Z,1510695671.036 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-11-14T21:41:11.643Z,1510695671.643 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:11.750Z,1510695671.750 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:11.844Z,1510695671.844 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-11-14T21:41:11.851Z,1510695671.851 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-11-14T21:41:11.872Z,1510695671.872 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-11-14T21:41:11.879Z,1510695671.879 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-11-14T21:41:11.892Z,1510695671.892 [MassServo](DEBUG): Initializing EZServoServo.
2017-11-14T21:41:11.899Z,1510695671.899 [MassServo](DEBUG): Initializing MassServo.
2017-11-14T21:41:11.911Z,1510695671.911 [RudderServo](DEBUG): Initializing EZServoServo.
2017-11-14T21:41:11.919Z,1510695671.919 [RudderServo](DEBUG): Initializing RudderServo.
2017-11-14T21:41:11.940Z,1510695671.940 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-11-14T21:41:11.947Z,1510695671.947 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-11-14T21:41:12.130Z,1510695672.130 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:12.131Z,1510695672.131 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:12.450Z,1510695672.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:12.451Z,1510695672.451 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:12.790Z,1510695672.790 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-11-14T21:41:12.791Z,1510695672.791 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-11-14T21:41:12.791Z,1510695672.791 [BuoyancyServo] Communications Fault, FailCount= 1
2017-11-14T21:41:12.791Z,1510695672.791 [BuoyancyServo](ERROR): Communications Fault
2017-11-14T21:41:12.998Z,1510695672.998 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-11-14T21:41:13.035Z,1510695673.035 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:13.036Z,1510695673.036 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:13.056Z,1510695673.056 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-11-14T21:41:13.056Z,1510695673.056 [BuoyancyServo](INFO): Powering down
2017-11-14T21:41:13.449Z,1510695673.449 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:13.458Z,1510695673.458 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:13.834Z,1510695673.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:13.835Z,1510695673.835 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:14.234Z,1510695674.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:14.235Z,1510695674.235 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:14.651Z,1510695674.651 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:14.652Z,1510695674.652 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:14.901Z,1510695674.901 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-11-14T21:41:14.974Z,1510695674.974 [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-14T21:41:15.047Z,1510695675.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:15.048Z,1510695675.048 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:15.117Z,1510695675.117 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-11-14T21:41:15.117Z,1510695675.117 [BuoyancyServo] No Fault, FailCount= 1
2017-11-14T21:41:15.152Z,1510695675.152 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-11-14T21:41:15.153Z,1510695675.153 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-11-14T21:41:15.154Z,1510695675.154 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-11-14T21:41:15.434Z,1510695675.434 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:15.435Z,1510695675.435 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:15.441Z,1510695675.441 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-11-14T21:41:15.441Z,1510695675.441 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-11-14T21:41:15.846Z,1510695675.846 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:15.847Z,1510695675.847 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:16.248Z,1510695676.248 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:16.249Z,1510695676.249 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:16.634Z,1510695676.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:16.635Z,1510695676.635 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:17.034Z,1510695677.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:17.035Z,1510695677.035 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:17.459Z,1510695677.459 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:17.460Z,1510695677.460 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:17.834Z,1510695677.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:17.842Z,1510695677.842 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:18.234Z,1510695678.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:18.235Z,1510695678.235 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:18.606Z,1510695678.606 [AcousticModem_Benthos_ATM900](DEBUG): 
2017-11-14T21:41:18.629Z,1510695678.629 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2017-11-14T21:41:18.629Z,1510695678.629 [PNI_TCM] Data Fault, FailCount= 1
2017-11-14T21:41:18.629Z,1510695678.629 [PNI_TCM](ERROR): Data Fault
2017-11-14T21:41:18.634Z,1510695678.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:18.635Z,1510695678.635 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:18.735Z,1510695678.735 [CBIT](ERROR): Data Fault in component: PNI_TCM
2017-11-14T21:41:19.029Z,1510695679.029 [PNI_TCM](INFO): Powering down
2017-11-14T21:41:19.076Z,1510695679.076 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:19.077Z,1510695679.077 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:19.434Z,1510695679.434 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:19.435Z,1510695679.435 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:19.869Z,1510695679.869 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:19.870Z,1510695679.870 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:20.207Z,1510695680.207 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2017-11-14T21:41:20.208Z,1510695680.208 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2017-11-14T21:41:20.208Z,1510695680.208 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2017-11-14T21:41:20.209Z,1510695680.209 [AcousticModem_Benthos_ATM900](DEBUG): Jan  9 2000 04:01:39 
2017-11-14T21:41:20.234Z,1510695680.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:20.235Z,1510695680.235 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:20.284Z,1510695680.284 [CBIT](INFO): Clearing failed state for component PNI_TCM
2017-11-14T21:41:20.284Z,1510695680.284 [PNI_TCM] No Fault, FailCount= 1
2017-11-14T21:41:20.606Z,1510695680.606 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2017-11-14T21:41:20.635Z,1510695680.635 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:20.636Z,1510695680.636 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:21.047Z,1510695681.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:21.048Z,1510695681.048 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:21.406Z,1510695681.406 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2017-11-14T21:41:21.454Z,1510695681.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:21.462Z,1510695681.462 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:21.806Z,1510695681.806 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2017-11-14T21:41:21.807Z,1510695681.807 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2017-11-14T21:41:21.807Z,1510695681.807 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2017-11-14T21:41:21.836Z,1510695681.836 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:21.837Z,1510695681.837 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:22.206Z,1510695682.206 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2017-11-14T21:41:22.272Z,1510695682.272 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:22.274Z,1510695682.274 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:22.606Z,1510695682.606 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2017-11-14T21:41:22.607Z,1510695682.607 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2017-11-14T21:41:22.662Z,1510695682.662 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:22.663Z,1510695682.663 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:23.007Z,1510695683.007 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2017-11-14T21:41:23.036Z,1510695683.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:23.037Z,1510695683.037 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:23.435Z,1510695683.435 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:23.436Z,1510695683.436 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:23.850Z,1510695683.850 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:23.852Z,1510695683.852 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:24.235Z,1510695684.235 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:24.236Z,1510695684.236 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:24.634Z,1510695684.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:24.635Z,1510695684.635 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:25.042Z,1510695685.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:25.043Z,1510695685.043 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:25.435Z,1510695685.435 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:25.436Z,1510695685.436 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:25.834Z,1510695685.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:25.835Z,1510695685.835 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:26.234Z,1510695686.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:26.235Z,1510695686.235 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:26.639Z,1510695686.639 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:26.639Z,1510695686.639 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:27.035Z,1510695687.035 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:27.036Z,1510695687.036 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:27.434Z,1510695687.434 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:27.435Z,1510695687.435 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:27.829Z,1510695687.829 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2017-11-14T21:41:27.829Z,1510695687.829 [PNI_TCM] Data Fault, FailCount= 2
2017-11-14T21:41:27.829Z,1510695687.829 [PNI_TCM](ERROR): Data Fault
2017-11-14T21:41:27.830Z,1510695687.830 [BPC1](ERROR): Failed to parse bank A battery data
2017-11-14T21:41:27.830Z,1510695687.830 [BPC1] Data Fault, FailCount= 1
2017-11-14T21:41:27.830Z,1510695687.830 [BPC1](ERROR): Data Fault
2017-11-14T21:41:27.841Z,1510695687.841 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:27.842Z,1510695687.842 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:27.903Z,1510695687.903 [CBIT](ERROR): Data Fault in component: PNI_TCM
2017-11-14T21:41:27.903Z,1510695687.903 [CBIT](ERROR): Data Fault in component: BPC1
2017-11-14T21:41:28.228Z,1510695688.228 [PNI_TCM](INFO): Powering down
2017-11-14T21:41:28.234Z,1510695688.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:28.235Z,1510695688.235 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:28.634Z,1510695688.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:28.635Z,1510695688.635 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:29.038Z,1510695689.038 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:29.039Z,1510695689.039 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:29.096Z,1510695689.096 [CBIT](INFO): Clearing failed state for component BPC1
2017-11-14T21:41:29.096Z,1510695689.096 [BPC1] No Fault, FailCount= 1
2017-11-14T21:41:29.490Z,1510695689.490 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:29.499Z,1510695689.499 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:29.583Z,1510695689.583 [CBIT](INFO): Clearing failed state for component PNI_TCM
2017-11-14T21:41:29.583Z,1510695689.583 [PNI_TCM] No Fault, FailCount= 2
2017-11-14T21:41:29.834Z,1510695689.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:29.835Z,1510695689.835 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:30.246Z,1510695690.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:30.247Z,1510695690.247 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:30.651Z,1510695690.651 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:30.652Z,1510695690.652 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:31.036Z,1510695691.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:31.037Z,1510695691.037 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:31.436Z,1510695691.436 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:31.437Z,1510695691.437 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:31.850Z,1510695691.850 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:31.859Z,1510695691.859 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:32.236Z,1510695692.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:32.238Z,1510695692.238 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:32.634Z,1510695692.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:32.635Z,1510695692.635 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:32.703Z,1510695692.703 [CommandLine](IMPORTANT): got command quit
2017-11-14T21:41:33.042Z,1510695693.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:33.054Z,1510695693.054 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:33.450Z,1510695693.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:33.451Z,1510695693.451 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:33.706Z,1510695693.706 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2017-11-14T21:41:33.709Z,1510695693.709 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-14T21:41:33.710Z,1510695693.710 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:33.842Z,1510695693.842 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:33.843Z,1510695693.843 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:33.891Z,1510695693.891 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-11-14T21:41:33.892Z,1510695693.892 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:33.892Z,1510695693.892 [CommandLine](INFO): Join timeout helper Thread ID is 910
2017-11-14T21:41:33.893Z,1510695693.893 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-11-14T21:41:33.893Z,1510695693.893 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:33.894Z,1510695693.894 [NavChartDb](INFO): Join timeout helper Thread ID is 911
2017-11-14T21:41:33.999Z,1510695693.999 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-14T21:41:33.999Z,1510695693.999 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:34.010Z,1510695694.010 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2017-11-14T21:41:34.010Z,1510695694.010 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:34.011Z,1510695694.011 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 912
2017-11-14T21:41:34.243Z,1510695694.243 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:34.244Z,1510695694.244 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:34.646Z,1510695694.646 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:34.647Z,1510695694.647 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:34.686Z,1510695694.686 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-14T21:41:34.687Z,1510695694.687 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-11-14T21:41:34.809Z,1510695694.809 [Rowe_600LCM](INFO): Powering down
2017-11-14T21:41:34.810Z,1510695694.810 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:34.826Z,1510695694.826 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-11-14T21:41:34.826Z,1510695694.826 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:34.827Z,1510695694.827 [Radio_Surface](INFO): Join timeout helper Thread ID is 914
2017-11-14T21:41:35.047Z,1510695695.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:35.048Z,1510695695.048 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-14T21:41:35.155Z,1510695695.155 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-14T21:41:35.155Z,1510695695.155 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:35.174Z,1510695695.174 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-11-14T21:41:35.174Z,1510695695.174 [logger ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:35.175Z,1510695695.175 [logger](INFO): Join timeout helper Thread ID is 915
2017-11-14T21:41:35.182Z,1510695695.182 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-14T21:41:35.183Z,1510695695.183 [logger ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:35.195Z,1510695695.195 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-11-14T21:41:35.195Z,1510695695.195 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:35.196Z,1510695695.196 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-11-14T21:41:35.196Z,1510695695.196 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:35.196Z,1510695695.196 [controlThread](INFO): Join timeout helper Thread ID is 916
2017-11-14T21:41:35.402Z,1510695695.402 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-14T21:41:35.403Z,1510695695.403 [controlThread](DEBUG): Uninitializing ControlThread
2017-11-14T21:41:35.403Z,1510695695.403 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-11-14T21:41:35.523Z,1510695695.523 [NAL9602](INFO): Powering down
2017-11-14T21:41:35.543Z,1510695695.543 [PNI_TCM](INFO): Powering down
2017-11-14T21:41:35.631Z,1510695695.631 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-11-14T21:41:35.633Z,1510695695.633 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-11-14T21:41:35.633Z,1510695695.633 [MissionManager](INFO): Uninitializing Mission Default
2017-11-14T21:41:35.636Z,1510695695.636 [MissionManager](INFO): Uninitializing Mission Startup
2017-11-14T21:41:35.637Z,1510695695.637 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-11-14T21:41:35.638Z,1510695695.638 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-11-14T21:41:35.638Z,1510695695.638 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-11-14T21:41:35.638Z,1510695695.638 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-11-14T21:41:35.638Z,1510695695.638 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-11-14T21:41:35.639Z,1510695695.639 [BuoyancyServo](INFO): Powering down
2017-11-14T21:41:35.650Z,1510695695.650 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-11-14T21:41:35.650Z,1510695695.650 [ElevatorServo](INFO): Powering down
2017-11-14T21:41:35.669Z,1510695695.669 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-11-14T21:41:35.669Z,1510695695.669 [MassServo](INFO): Powering down
2017-11-14T21:41:35.670Z,1510695695.670 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-11-14T21:41:35.671Z,1510695695.671 [RudderServo](INFO): Powering down
2017-11-14T21:41:35.671Z,1510695695.671 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-11-14T21:41:35.671Z,1510695695.671 [ThrusterServo](INFO): Powering down
2017-11-14T21:41:35.672Z,1510695695.672 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-11-14T21:41:35.673Z,1510695695.673 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-11-14T21:41:35.673Z,1510695695.673 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-11-14T21:41:35.674Z,1510695695.674 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:35.754Z,1510695695.754 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:35.760Z,1510695695.760 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:36.066Z,1510695696.066 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-11-14T21:41:36.115Z,1510695696.115 [logger ThreadHandler](INFO): Thread cancelled.