2018-07-12T23:10:35.978Z,1531437035.978 [Supervisor](DEBUG): Initializing supervisor.
2018-07-12T23:10:35.981Z,1531437035.981 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-07-12T23:10:35.981Z,1531437035.981 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-07-12T23:10:35.982Z,1531437035.982 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-07-12T23:10:35.983Z,1531437035.983 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-07-12T23:10:35.983Z,1531437035.983 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-07-12T23:10:35.986Z,1531437035.986 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-07-12T23:10:35.998Z,1531437035.998 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-07-12T23:10:35.000Z,1531437036.000 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-07-12T23:10:35.000Z,1531437036.000 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-07-12T23:10:36.001Z,1531437036.001 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-07-12T23:10:36.002Z,1531437036.002 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-07-12T23:10:36.002Z,1531437036.002 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-07-12T23:10:36.004Z,1531437036.004 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-07-12T23:10:36.005Z,1531437036.005 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-07-12T23:10:36.009Z,1531437036.009 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-07-12T23:10:36.211Z,1531437036.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-07-12T23:10:36.213Z,1531437036.213 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-07-12T23:10:36.358Z,1531437036.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-07-12T23:10:36.360Z,1531437036.360 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-07-12T23:10:36.720Z,1531437036.720 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-07-12T23:10:36.721Z,1531437036.721 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-07-12T23:10:36.862Z,1531437036.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-07-12T23:10:36.864Z,1531437036.864 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-07-12T23:10:36.946Z,1531437036.946 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-07-12T23:10:37.164Z,1531437037.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-07-12T23:10:37.165Z,1531437037.165 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-07-12T23:10:37.265Z,1531437037.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-07-12T23:10:37.266Z,1531437037.266 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-07-12T23:10:37.615Z,1531437037.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-07-12T23:10:37.615Z,1531437037.615 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-07-12T23:10:38.066Z,1531437038.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-07-12T23:10:38.067Z,1531437038.067 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-07-12T23:10:38.382Z,1531437038.382 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-07-12T23:10:38.383Z,1531437038.383 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-07-12T23:10:38.901Z,1531437038.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-07-12T23:10:38.902Z,1531437038.902 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-07-12T23:10:39.119Z,1531437039.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-07-12T23:10:39.119Z,1531437039.119 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-07-12T23:10:39.220Z,1531437039.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-07-12T23:10:39.220Z,1531437039.220 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-07-12T23:10:39.731Z,1531437039.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-07-12T23:10:39.731Z,1531437039.731 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-07-12T23:10:40.052Z,1531437040.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-07-12T23:10:40.054Z,1531437040.054 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-07-12T23:10:40.080Z,1531437040.080 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-07-12T23:10:40.314Z,1531437040.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-07-12T23:10:40.316Z,1531437040.316 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-07-12T23:10:40.419Z,1531437040.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-07-12T23:10:40.514Z,1531437040.514 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-07-12T23:10:40.620Z,1531437040.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-07-12T23:10:40.709Z,1531437040.709 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-07-12T23:10:40.845Z,1531437040.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-07-12T23:10:41.019Z,1531437041.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-07-12T23:10:41.132Z,1531437041.132 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-07-12T23:10:41.218Z,1531437041.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-07-12T23:10:41.303Z,1531437041.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-07-12T23:10:41.501Z,1531437041.501 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-07-12T23:10:41.507Z,1531437041.507 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-07-12T23:10:41.666Z,1531437041.666 [SBIT](DEBUG): Construct Startup Built In Test.
2018-07-12T23:10:41.697Z,1531437041.697 [SBIT] Loaded
2018-07-12T23:10:41.697Z,1531437041.697 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-07-12T23:10:41.698Z,1531437041.698 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-07-12T23:10:41.727Z,1531437041.727 [IBIT] Loaded
2018-07-12T23:10:41.727Z,1531437041.727 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-07-12T23:10:41.731Z,1531437041.731 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-07-12T23:10:41.891Z,1531437041.891 [CBIT] Loaded
2018-07-12T23:10:41.891Z,1531437041.891 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-07-12T23:10:41.891Z,1531437041.891 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-07-12T23:10:41.892Z,1531437041.892 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-07-12T23:10:41.981Z,1531437041.981 [VerticalControl](DEBUG): Construct VerticalControl.
2018-07-12T23:10:42.084Z,1531437042.084 [VerticalControl] Loaded
2018-07-12T23:10:42.084Z,1531437042.084 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-07-12T23:10:42.085Z,1531437042.085 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-07-12T23:10:42.148Z,1531437042.148 [HorizontalControl] Loaded
2018-07-12T23:10:42.149Z,1531437042.149 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-07-12T23:10:42.149Z,1531437042.149 [SpeedControl](DEBUG): Construct SpeedControl.
2018-07-12T23:10:42.155Z,1531437042.155 [SpeedControl] Loaded
2018-07-12T23:10:42.155Z,1531437042.155 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-07-12T23:10:42.156Z,1531437042.156 [LoopControl](DEBUG): Construct LoopControl.
2018-07-12T23:10:42.157Z,1531437042.157 [LoopControl] Loaded
2018-07-12T23:10:42.157Z,1531437042.157 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-07-12T23:10:42.158Z,1531437042.158 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-07-12T23:10:42.158Z,1531437042.158 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-07-12T23:10:42.214Z,1531437042.214 [DepthRateCalculator] Loaded
2018-07-12T23:10:42.214Z,1531437042.214 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-07-12T23:10:42.220Z,1531437042.220 [PitchRateCalculator] Loaded
2018-07-12T23:10:42.220Z,1531437042.220 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-07-12T23:10:42.237Z,1531437042.237 [SpeedCalculator] Loaded
2018-07-12T23:10:42.238Z,1531437042.238 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-07-12T23:10:42.259Z,1531437042.259 [TempGradientCalculator] Loaded
2018-07-12T23:10:42.260Z,1531437042.260 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-07-12T23:10:42.276Z,1531437042.276 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-07-12T23:10:42.276Z,1531437042.276 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-07-12T23:10:42.281Z,1531437042.281 [YawRateCalculator] Loaded
2018-07-12T23:10:42.282Z,1531437042.282 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-07-12T23:10:42.312Z,1531437042.312 [ElevatorOffsetCalculator] Loaded
2018-07-12T23:10:42.312Z,1531437042.312 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-07-12T23:10:42.313Z,1531437042.313 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-07-12T23:10:42.313Z,1531437042.313 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-07-12T23:10:42.438Z,1531437042.438 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-07-12T23:10:42.438Z,1531437042.438 [StratificationFrontDetector](DEBUG): (re)initializing
2018-07-12T23:10:42.438Z,1531437042.438 [StratificationFrontDetector] Loaded
2018-07-12T23:10:42.439Z,1531437042.439 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-07-12T23:10:42.439Z,1531437042.439 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-07-12T23:10:42.441Z,1531437042.441 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-07-12T23:10:42.726Z,1531437042.726 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-07-12T23:10:42.727Z,1531437042.727 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-07-12T23:10:42.843Z,1531437042.843 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-07-12T23:10:42.843Z,1531437042.843 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-07-12T23:10:42.899Z,1531437042.899 [DeadReckonUsingSpeedCalculator] Loaded
2018-07-12T23:10:42.899Z,1531437042.899 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-07-12T23:10:42.917Z,1531437042.917 [NavChart] Loaded
2018-07-12T23:10:42.917Z,1531437042.917 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-07-12T23:10:42.921Z,1531437042.921 [UniversalFixResidualReporter] Loaded
2018-07-12T23:10:42.922Z,1531437042.922 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-07-12T23:10:42.922Z,1531437042.922 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-07-12T23:10:42.923Z,1531437042.923 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-07-12T23:10:42.948Z,1531437042.948 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-07-12T23:10:42.948Z,1531437042.948 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-07-12T23:10:43.152Z,1531437043.152 [Aanderaa_O2] Loaded
2018-07-12T23:10:43.153Z,1531437043.153 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-07-12T23:10:43.232Z,1531437043.232 [CTD_NeilBrown] Loaded
2018-07-12T23:10:43.232Z,1531437043.232 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-07-12T23:10:43.233Z,1531437043.233 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406D24E0
2018-07-12T23:10:43.234Z,1531437043.234 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 881
2018-07-12T23:10:43.249Z,1531437043.249 [PAR_Licor] Loaded
2018-07-12T23:10:43.249Z,1531437043.249 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-07-12T23:10:43.300Z,1531437043.300 [WetLabsBB2FL] Loaded
2018-07-12T23:10:43.300Z,1531437043.300 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-07-12T23:10:43.301Z,1531437043.301 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407024E0
2018-07-12T23:10:43.301Z,1531437043.301 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 882
2018-07-12T23:10:43.347Z,1531437043.347 [WetLabsSeaOWL_UV_A] Loaded
2018-07-12T23:10:43.347Z,1531437043.347 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-07-12T23:10:43.348Z,1531437043.348 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407324E0
2018-07-12T23:10:43.348Z,1531437043.348 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 883
2018-07-12T23:10:43.349Z,1531437043.349 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-07-12T23:10:43.349Z,1531437043.349 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-07-12T23:10:43.959Z,1531437043.959 [AHRS_M2] Loaded
2018-07-12T23:10:43.960Z,1531437043.960 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-07-12T23:10:44.100Z,1531437044.100 [DataOverHttps] Loaded
2018-07-12T23:10:44.100Z,1531437044.100 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-07-12T23:10:44.114Z,1531437044.114 [Depth_Keller] Loaded
2018-07-12T23:10:44.114Z,1531437044.114 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-07-12T23:10:44.119Z,1531437044.119 [DropWeight] Loaded
2018-07-12T23:10:44.120Z,1531437044.120 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-07-12T23:10:44.212Z,1531437044.212 [NAL9602] Loaded
2018-07-12T23:10:44.212Z,1531437044.212 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-07-12T23:10:44.218Z,1531437044.218 [Onboard] Loaded
2018-07-12T23:10:44.218Z,1531437044.218 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-07-12T23:10:44.225Z,1531437044.225 [Radio_Surface] Loaded
2018-07-12T23:10:44.225Z,1531437044.225 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-07-12T23:10:44.226Z,1531437044.226 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409884E0
2018-07-12T23:10:44.227Z,1531437044.227 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 884
2018-07-12T23:10:44.256Z,1531437044.256 [RDI_Pathfinder] Loaded
2018-07-12T23:10:44.256Z,1531437044.256 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-07-12T23:10:44.263Z,1531437044.263 [SCPI] Loaded
2018-07-12T23:10:44.263Z,1531437044.263 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-07-12T23:10:46.413Z,1531437046.413 [BPC1] Loaded
2018-07-12T23:10:46.413Z,1531437046.413 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-07-12T23:10:46.413Z,1531437046.413 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-07-12T23:10:46.414Z,1531437046.414 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-07-12T23:10:46.535Z,1531437046.535 [BuoyancyServo] Loaded
2018-07-12T23:10:46.536Z,1531437046.536 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-07-12T23:10:46.548Z,1531437046.548 [ElevatorServo] Loaded
2018-07-12T23:10:46.548Z,1531437046.548 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-07-12T23:10:46.560Z,1531437046.560 [MassServo] Loaded
2018-07-12T23:10:46.560Z,1531437046.560 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-07-12T23:10:46.572Z,1531437046.572 [RudderServo] Loaded
2018-07-12T23:10:46.572Z,1531437046.572 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-07-12T23:10:46.584Z,1531437046.584 [ThrusterServo] Loaded
2018-07-12T23:10:46.584Z,1531437046.584 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-07-12T23:10:46.585Z,1531437046.585 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-07-12T23:10:46.585Z,1531437046.585 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-07-12T23:10:46.714Z,1531437046.714 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-07-12T23:10:46.715Z,1531437046.715 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-07-12T23:10:46.752Z,1531437046.752 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-07-12T23:10:46.756Z,1531437046.756 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-07-12T23:10:46.757Z,1531437046.757 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-07-12T23:10:46.764Z,1531437046.764 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-07-12T23:10:46.765Z,1531437046.765 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A314E0
2018-07-12T23:10:46.766Z,1531437046.766 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 885
2018-07-12T23:10:46.771Z,1531437046.771 [Supervisor](INFO): Main Thread ID is 795
2018-07-12T23:10:46.771Z,1531437046.771 [Supervisor](DEBUG): Running supervisor.
2018-07-12T23:10:46.771Z,1531437046.771 [CommandLine ThreadHandler](INFO): Handler Thread ID is 886
2018-07-12T23:10:46.774Z,1531437046.774 [controlThread ThreadHandler](INFO): Handler Thread ID is 887
2018-07-12T23:10:46.774Z,1531437046.774 [controlThread](DEBUG): Initializing ControlThread
2018-07-12T23:10:46.775Z,1531437046.775 [SBIT](INFO): Initialize SBIT Component.
2018-07-12T23:10:46.776Z,1531437046.776 [SBIT](IMPORTANT): git: 2018-06-03-17-gbdf8474
2018-07-12T23:10:46.776Z,1531437046.776 [SBIT](INFO): git hash: bdf847407d9faffe74fd7e88a9e899f3511e6e11
2018-07-12T23:10:46.776Z,1531437046.776 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-07-12T23:10:46.778Z,1531437046.778 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-07-12T23:10:46.779Z,1531437046.779 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2018-07-12T23:10:46.779Z,1531437046.779 [IBIT](INFO): Initialize IBIT Component.
2018-07-12T23:10:46.780Z,1531437046.780 [CBIT](DEBUG): Initialize CBIT Component.
2018-07-12T23:10:46.781Z,1531437046.781 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-07-12T23:10:46.781Z,1531437046.781 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-07-12T23:10:46.782Z,1531437046.782 [logger ThreadHandler](INFO): Handler Thread ID is 888
2018-07-12T23:10:46.809Z,1531437046.809 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 889
2018-07-12T23:10:46.810Z,1531437046.810 [CTD_NeilBrown](INFO): Powering down
2018-07-12T23:10:46.843Z,1531437046.843 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 890
2018-07-12T23:10:46.853Z,1531437046.853 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 891
2018-07-12T23:10:46.854Z,1531437046.854 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-07-12T23:10:46.883Z,1531437046.883 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 892
2018-07-12T23:10:46.888Z,1531437046.888 [Radio_Surface](INFO): Powering up
2018-07-12T23:10:46.889Z,1531437046.889 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-07-12T23:10:46.891Z,1531437046.891 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-07-12T23:10:46.892Z,1531437046.892 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-07-12T23:10:46.892Z,1531437046.892 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-07-12T23:10:46.893Z,1531437046.893 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-12T23:10:46.893Z,1531437046.893 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-07-12T23:10:46.893Z,1531437046.893 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-07-12T23:10:46.894Z,1531437046.894 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-07-12T23:10:46.894Z,1531437046.894 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-07-12T23:10:46.895Z,1531437046.895 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-07-12T23:10:46.895Z,1531437046.895 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-07-12T23:10:46.896Z,1531437046.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-12T23:10:46.897Z,1531437046.897 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-12T23:10:46.898Z,1531437046.898 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-12T23:10:46.898Z,1531437046.898 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-12T23:10:46.899Z,1531437046.899 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-12T23:10:46.899Z,1531437046.899 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-12T23:10:46.899Z,1531437046.899 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-07-12T23:10:46.900Z,1531437046.900 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-07-12T23:10:46.913Z,1531437046.913 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 893
2018-07-12T23:10:46.922Z,1531437046.922 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-07-12T23:10:46.923Z,1531437046.923 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-07-12T23:10:46.923Z,1531437046.923 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-07-12T23:10:46.923Z,1531437046.923 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-07-12T23:10:46.923Z,1531437046.923 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-07-12T23:10:46.923Z,1531437046.923 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-07-12T23:10:46.924Z,1531437046.924 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-07-12T23:10:46.924Z,1531437046.924 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-07-12T23:10:46.924Z,1531437046.924 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-07-12T23:10:46.924Z,1531437046.924 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-07-12T23:10:46.925Z,1531437046.925 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-07-12T23:10:46.925Z,1531437046.925 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-07-12T23:10:46.925Z,1531437046.925 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-07-12T23:10:46.925Z,1531437046.925 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-07-12T23:10:46.926Z,1531437046.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-07-12T23:10:46.926Z,1531437046.926 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-07-12T23:10:46.954Z,1531437046.954 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-07-12T23:10:46.999Z,1531437046.999 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-12T23:10:47.035Z,1531437047.035 [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>


2018-07-12T23:10:47.036Z,1531437047.036 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-07-12T23:10:47.127Z,1531437047.127 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-07-12T23:10:47.128Z,1531437047.128 [Default:A.Wait](DEBUG): Construct Wait.
2018-07-12T23:10:47.130Z,1531437047.130 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-12T23:10:47.181Z,1531437047.181 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-07-12T23:10:47.184Z,1531437047.184 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-07-12T23:10:47.200Z,1531437047.200 [Default:E.Execute](DEBUG): Construct Execute.
2018-07-12T23:10:47.224Z,1531437047.224 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <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">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <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>


2018-07-12T23:10:47.229Z,1531437047.229 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-07-12T23:10:47.326Z,1531437047.326 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-12T23:10:47.431Z,1531437047.431 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-07-12T23:10:47.922Z,1531437047.922 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:47.947Z,1531437047.947 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:48.073Z,1531437048.073 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:10:48.081Z,1531437048.081 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:10:48.101Z,1531437048.101 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:10:48.109Z,1531437048.109 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-07-12T23:10:48.133Z,1531437048.133 [MassServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:10:48.141Z,1531437048.141 [MassServo](DEBUG): Initializing MassServo.
2018-07-12T23:10:48.159Z,1531437048.159 [RudderServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:10:48.165Z,1531437048.165 [RudderServo](DEBUG): Initializing RudderServo.
2018-07-12T23:10:48.187Z,1531437048.187 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:10:48.193Z,1531437048.193 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-12T23:10:48.230Z,1531437048.230 [CommandLine](FAULT): Scheduling is paused
2018-07-12T23:10:48.230Z,1531437048.230 [CBIT](INFO): Critical error at 20180712T231046
2018-07-12T23:10:48.230Z,1531437048.230 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-07-12T23:10:48.375Z,1531437048.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:48.392Z,1531437048.392 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:48.682Z,1531437048.682 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	651	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2018-07-12T23:10:48.682Z,1531437048.682 [Aanderaa_O2] Data Fault, FailCount= 1
2018-07-12T23:10:48.682Z,1531437048.682 [Aanderaa_O2](ERROR): Data Fault
2018-07-12T23:10:49.013Z,1531437049.013 [AHRS_M2](ERROR): Stop data-stream UART error: serial timeout
2018-07-12T23:10:49.061Z,1531437049.061 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:49.062Z,1531437049.062 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:49.357Z,1531437049.357 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2018-07-12T23:10:49.485Z,1531437049.485 [Aanderaa_O2](INFO): Powering down
2018-07-12T23:10:49.613Z,1531437049.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:49.615Z,1531437049.615 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:49.817Z,1531437049.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:49.818Z,1531437049.818 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:50.213Z,1531437050.213 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:50.214Z,1531437050.214 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:50.240Z,1531437050.240 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-07-12T23:10:50.240Z,1531437050.240 [Aanderaa_O2] No Fault, FailCount= 1
2018-07-12T23:10:50.613Z,1531437050.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:50.614Z,1531437050.614 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:51.052Z,1531437051.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:51.053Z,1531437051.053 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:51.457Z,1531437051.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:51.458Z,1531437051.458 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:51.498Z,1531437051.498 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000

2018-07-12T23:10:51.498Z,1531437051.498 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:10:51.498Z,1531437051.498 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-07-12T23:10:51.498Z,1531437051.498 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:10:51.531Z,1531437051.531 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:10:51.837Z,1531437051.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:51.838Z,1531437051.838 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:51.851Z,1531437051.851 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:10:51.851Z,1531437051.851 [BuoyancyServo](INFO): Powering down
2018-07-12T23:10:52.254Z,1531437052.254 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:52.255Z,1531437052.255 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:52.655Z,1531437052.655 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:52.656Z,1531437052.656 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:53.053Z,1531437053.053 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:53.054Z,1531437053.054 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:53.403Z,1531437053.403 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:53.404Z,1531437053.404 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:53.806Z,1531437053.806 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:53.807Z,1531437053.807 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:54.250Z,1531437054.250 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:54.251Z,1531437054.251 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:54.318Z,1531437054.318 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:10:54.318Z,1531437054.318 [BuoyancyServo] No Fault, FailCount= 1
2018-07-12T23:10:54.654Z,1531437054.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:54.655Z,1531437054.655 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:54.678Z,1531437054.678 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:10:54.798Z,1531437054.798 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:10:55.034Z,1531437055.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:55.035Z,1531437055.035 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:55.461Z,1531437055.461 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:55.462Z,1531437055.462 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:55.841Z,1531437055.841 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:55.842Z,1531437055.842 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:56.241Z,1531437056.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:56.242Z,1531437056.242 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:56.630Z,1531437056.630 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:56.631Z,1531437056.631 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:57.053Z,1531437057.053 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:57.054Z,1531437057.054 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:57.418Z,1531437057.418 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:57.419Z,1531437057.419 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:57.823Z,1531437057.823 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:57.824Z,1531437057.824 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:57.869Z,1531437057.869 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000

2018-07-12T23:10:57.870Z,1531437057.870 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:10:57.870Z,1531437057.870 [BuoyancyServo] Hardware Fault, FailCount= 2
2018-07-12T23:10:57.870Z,1531437057.870 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:10:57.914Z,1531437057.914 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:10:58.226Z,1531437058.226 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:58.227Z,1531437058.227 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:58.234Z,1531437058.234 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:10:58.235Z,1531437058.235 [BuoyancyServo](INFO): Powering down
2018-07-12T23:10:58.622Z,1531437058.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:58.623Z,1531437058.623 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:59.006Z,1531437059.006 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:59.007Z,1531437059.007 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:59.418Z,1531437059.418 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:59.419Z,1531437059.419 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:59.814Z,1531437059.814 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:10:59.815Z,1531437059.815 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:11:00.217Z,1531437060.217 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:11:00.218Z,1531437060.218 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:11:00.273Z,1531437060.273 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:11:00.273Z,1531437060.273 [BuoyancyServo] No Fault, FailCount= 2
2018-07-12T23:11:00.578Z,1531437060.578 [Aanderaa_O2](FAULT): Timed out starting
2018-07-12T23:11:00.578Z,1531437060.578 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-07-12T23:11:00.578Z,1531437060.578 [Aanderaa_O2](ERROR): Communications Fault
2018-07-12T23:11:00.658Z,1531437060.658 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:11:00.659Z,1531437060.659 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:11:00.683Z,1531437060.683 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:11:00.802Z,1531437060.802 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:11:00.846Z,1531437060.846 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-07-12T23:11:01.085Z,1531437061.085 [Aanderaa_O2](INFO): Powering down
2018-07-12T23:11:01.942Z,1531437061.942 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-07-12T23:11:01.942Z,1531437061.942 [Aanderaa_O2] No Fault, FailCount= 2
2018-07-12T23:11:03.767Z,1531437063.767 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	651	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2018-07-12T23:11:03.767Z,1531437063.767 [Aanderaa_O2] Data Fault, FailCount= 3
2018-07-12T23:11:03.767Z,1531437063.767 [Aanderaa_O2](ERROR): Data Fault
2018-07-12T23:11:03.898Z,1531437063.898 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000

2018-07-12T23:11:03.898Z,1531437063.898 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:11:03.898Z,1531437063.898 [BuoyancyServo] Hardware Fault, FailCount= 3
2018-07-12T23:11:03.898Z,1531437063.898 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:11:03.942Z,1531437063.942 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2018-07-12T23:11:03.943Z,1531437063.943 [CBIT](FAULT): Data Fault in component: Aanderaa_O2
2018-07-12T23:11:03.943Z,1531437063.943 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:11:04.285Z,1531437064.285 [Aanderaa_O2](INFO): Powering down
2018-07-12T23:11:04.429Z,1531437064.429 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:11:04.429Z,1531437064.429 [BuoyancyServo](INFO): Powering down
2018-07-12T23:11:06.722Z,1531437066.722 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:11:06.723Z,1531437066.723 [BuoyancyServo] No Fault, FailCount= 3
2018-07-12T23:11:07.055Z,1531437067.055 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:11:07.174Z,1531437067.174 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:11:10.353Z,1531437070.353 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000

2018-07-12T23:11:10.353Z,1531437070.353 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:11:10.353Z,1531437070.353 [BuoyancyServo] Hardware Fault, FailCount= 4
2018-07-12T23:11:10.353Z,1531437070.353 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:11:10.360Z,1531437070.360 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:11:10.665Z,1531437070.665 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:11:10.666Z,1531437070.666 [BuoyancyServo](INFO): Powering down
2018-07-12T23:11:12.675Z,1531437072.675 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:11:12.675Z,1531437072.675 [BuoyancyServo] No Fault, FailCount= 4
2018-07-12T23:11:13.062Z,1531437073.062 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:11:13.182Z,1531437073.182 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:11:14.599Z,1531437074.599 [NAL9602](INFO): Powering up NAL9602
2018-07-12T23:11:16.361Z,1531437076.361 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000

2018-07-12T23:11:16.361Z,1531437076.361 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:11:16.362Z,1531437076.362 [BuoyancyServo] Hardware Fault, FailCount= 5
2018-07-12T23:11:16.362Z,1531437076.362 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:11:16.368Z,1531437076.368 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:11:16.368Z,1531437076.368 [CBIT](CRITICAL): Hardware Fault in component: BuoyancyServo
2018-07-12T23:11:16.635Z,1531437076.635 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:11:16.635Z,1531437076.635 [BuoyancyServo](INFO): Powering down
2018-07-12T23:11:16.661Z,1531437076.661 [CBIT](INFO): Critical error at 20180712T231116
2018-07-12T23:11:25.794Z,1531437085.794 [NAL9602](INFO): NAL9602 initialized
2018-07-12T23:11:34.611Z,1531437094.611 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-07-12T23:11:34.611Z,1531437094.611 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18071223122574,35,   0.0,1448.9,  0
2018-07-12T23:11:34.643Z,1531437094.643 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-12T23:11:35.026Z,1531437095.026 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-12T23:11:50.293Z,1531437110.293 [SBIT](IMPORTANT): Beginning Startup BIT
2018-07-12T23:11:50.310Z,1531437110.310 [CBIT](IMPORTANT): Beginning ground fault scan
2018-07-12T23:11:52.793Z,1531437112.793 [NAL9602](INFO): SBD MO Status=0, MOMSN=31, MT Status=0, MTMSN=0
2018-07-12T23:11:52.793Z,1531437112.793 [NAL9602](INFO): No messages in MT queue
2018-07-12T23:12:01.664Z,1531437121.664 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011764
CHAN A1 (24V): -0.016874
CHAN A2 (12V): -0.007320
CHAN A3 (5V): -0.003267
CHAN B0 (3.3V): -0.000970
CHAN B1 (3.15aV): -0.000601
CHAN B2 (3.15bV): -0.000370
CHAN B3 (GND): 0.000586
OPEN: 0.009703
Full Scale Calc: 4.765 mA, -1.589 mA
2018-07-12T23:12:32.598Z,1531437152.598 [BPC1](ERROR): Bank B: No match for stick 7 serial number:1717 in the onboard configuration file.
2018-07-12T23:12:32.598Z,1531437152.598 [BPC1](ERROR): Failed to parse Bank B battery data
2018-07-12T23:12:44.398Z,1531437164.398 [SBIT](IMPORTANT): SBIT PASSED
2018-07-12T23:12:44.474Z,1531437164.474 [CommandLine](IMPORTANT): got command configSet list
2018-07-12T23:12:44.474Z,1531437164.474 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-07-12T23:12:44.478Z,1531437164.478 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2018-07-12T23:12:44.478Z,1531437164.478 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2018-07-12T23:12:44.479Z,1531437164.479 [CommandLine](IMPORTANT): BuoyancyServo.limitHi=9575 count;

2018-07-12T23:12:44.479Z,1531437164.479 [CommandLine](IMPORTANT): BuoyancyServo.limitLo=1200 count;

2018-07-12T23:12:44.479Z,1531437164.479 [CommandLine](IMPORTANT): BuoyancyServo.loadAtStartup=0 bool;

2018-07-12T23:12:44.479Z,1531437164.479 [CommandLine](IMPORTANT): BuoyancyServo.offsetVolume=200 cubic_centimeter;

2018-07-12T23:12:44.479Z,1531437164.479 [CommandLine](IMPORTANT): ElevatorServo.loadAtStartup=0 bool;

2018-07-12T23:12:44.479Z,1531437164.479 [CommandLine](IMPORTANT): MassServo.loadAtStartup=0 bool;

2018-07-12T23:12:44.479Z,1531437164.479 [CommandLine](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2018-07-12T23:12:44.479Z,1531437164.479 [CommandLine](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2018-07-12T23:12:44.480Z,1531437164.480 [CommandLine](IMPORTANT): SCPI.loadAtStartup=0 bool;

2018-07-12T23:12:44.480Z,1531437164.480 [CommandLine](IMPORTANT): ThrusterServo.loadAtStartup=0 bool;

2018-07-12T23:12:44.480Z,1531437164.480 [CommandLine](IMPORTANT): VerticalControl.buoyancyDefault=1070 cubic_centimeter;

2018-07-12T23:12:44.480Z,1531437164.480 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitHiCC=1075 cubic_centimeter;

2018-07-12T23:12:44.480Z,1531437164.480 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitLoCC=100 cubic_centimeter;

2018-07-12T23:12:44.480Z,1531437164.480 [CommandLine](IMPORTANT): VerticalControl.massDefault=1 millimeter;

2018-07-12T23:12:44.779Z,1531437164.779 [MissionManager](IMPORTANT): Started mission Startup
2018-07-12T23:12:44.779Z,1531437164.779 [Startup] Running Loop=1
2018-07-12T23:12:44.779Z,1531437164.779 [Startup](DEBUG): Aggregate::initialize Startup
2018-07-12T23:12:44.780Z,1531437164.780 [Startup:A.GoToSurface] Running Loop=1
2018-07-12T23:12:44.780Z,1531437164.780 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-12T23:12:44.780Z,1531437164.780 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-12T23:12:44.781Z,1531437164.781 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-12T23:12:44.781Z,1531437164.781 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-12T23:12:44.782Z,1531437164.782 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-12T23:12:44.782Z,1531437164.782 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-12T23:12:44.788Z,1531437164.788 [Startup:StartupSatComms] Running Loop=1
2018-07-12T23:12:44.788Z,1531437164.788 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-07-12T23:12:44.789Z,1531437164.789 [Startup:StartupSatComms:A] Running Loop=1
2018-07-12T23:12:45.165Z,1531437165.165 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-07-12T23:12:46.758Z,1531437166.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-12T23:12:47.158Z,1531437167.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-12T23:12:47.567Z,1531437167.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-12T23:12:47.932Z,1531437167.932 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-12T23:12:48.368Z,1531437168.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-12T23:12:48.765Z,1531437168.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-12T23:12:49.179Z,1531437169.179 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-12T23:12:49.560Z,1531437169.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-12T23:12:49.936Z,1531437169.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-12T23:12:50.343Z,1531437170.343 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-12T23:12:50.734Z,1531437170.734 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-12T23:12:51.165Z,1531437171.165 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-12T23:12:51.579Z,1531437171.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-12T23:12:51.966Z,1531437171.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-12T23:12:52.350Z,1531437172.350 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-12T23:12:52.745Z,1531437172.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-12T23:12:53.142Z,1531437173.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-12T23:12:53.569Z,1531437173.569 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-12T23:13:16.383Z,1531437196.383 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:13:16.383Z,1531437196.383 [BuoyancyServo] No Fault, FailCount= 5
2018-07-12T23:13:16.756Z,1531437196.756 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:13:16.870Z,1531437196.870 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:13:19.985Z,1531437199.985 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000

2018-07-12T23:13:19.986Z,1531437199.986 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:13:19.986Z,1531437199.986 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-07-12T23:13:19.986Z,1531437199.986 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:13:19.992Z,1531437199.992 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:13:20.370Z,1531437200.370 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:13:20.371Z,1531437200.371 [BuoyancyServo](INFO): Powering down
2018-07-12T23:13:22.389Z,1531437202.389 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:13:22.389Z,1531437202.389 [BuoyancyServo] No Fault, FailCount= 1
2018-07-12T23:13:22.768Z,1531437202.768 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:13:22.886Z,1531437202.886 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:13:25.985Z,1531437205.985 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000

2018-07-12T23:13:25.985Z,1531437205.985 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:13:25.985Z,1531437205.985 [BuoyancyServo] Hardware Fault, FailCount= 2
2018-07-12T23:13:25.985Z,1531437205.985 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:13:25.991Z,1531437205.991 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:13:26.380Z,1531437206.380 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:13:26.380Z,1531437206.380 [BuoyancyServo](INFO): Powering down
2018-07-12T23:13:28.369Z,1531437208.369 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:13:28.369Z,1531437208.369 [BuoyancyServo] No Fault, FailCount= 2
2018-07-12T23:13:28.762Z,1531437208.762 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:13:28.878Z,1531437208.878 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:13:31.994Z,1531437211.994 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000

2018-07-12T23:13:31.994Z,1531437211.994 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:13:31.994Z,1531437211.994 [BuoyancyServo] Hardware Fault, FailCount= 3
2018-07-12T23:13:31.994Z,1531437211.994 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:13:32.001Z,1531437212.001 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:13:32.370Z,1531437212.370 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:13:32.371Z,1531437212.371 [BuoyancyServo](INFO): Powering down
2018-07-12T23:13:33.960Z,1531437213.960 [BPC1](ERROR): Bank A: No match for stick 4 serial number:1717 in the onboard configuration file.
2018-07-12T23:13:33.961Z,1531437213.961 [BPC1](ERROR): Failed to parse Bank A battery data
2018-07-12T23:13:34.014Z,1531437214.014 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-07-12T23:13:34.014Z,1531437214.014 [Aanderaa_O2] No Fault, FailCount= 3
2018-07-12T23:13:34.423Z,1531437214.423 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:13:34.423Z,1531437214.423 [BuoyancyServo] No Fault, FailCount= 3
2018-07-12T23:13:34.774Z,1531437214.774 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:13:34.890Z,1531437214.890 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:13:35.895Z,1531437215.895 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	651	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2018-07-12T23:13:35.895Z,1531437215.895 [Aanderaa_O2] Data Fault, FailCount= 1
2018-07-12T23:13:35.895Z,1531437215.895 [Aanderaa_O2](ERROR): Data Fault
2018-07-12T23:13:36.005Z,1531437216.005 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2018-07-12T23:13:36.413Z,1531437216.413 [Aanderaa_O2](INFO): Powering down
2018-07-12T23:13:37.204Z,1531437217.204 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-07-12T23:13:37.204Z,1531437217.204 [Aanderaa_O2] No Fault, FailCount= 1
2018-07-12T23:13:37.989Z,1531437217.989 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000

2018-07-12T23:13:37.989Z,1531437217.989 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:13:37.989Z,1531437217.989 [BuoyancyServo] Hardware Fault, FailCount= 4
2018-07-12T23:13:37.990Z,1531437217.990 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:13:37.995Z,1531437217.995 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:13:38.373Z,1531437218.373 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:13:38.374Z,1531437218.374 [BuoyancyServo](INFO): Powering down
2018-07-12T23:13:39.095Z,1531437219.095 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	651	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2018-07-12T23:13:39.095Z,1531437219.095 [Aanderaa_O2] Data Fault, FailCount= 2
2018-07-12T23:13:39.095Z,1531437219.095 [Aanderaa_O2](ERROR): Data Fault
2018-07-12T23:13:39.238Z,1531437219.238 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2018-07-12T23:13:39.613Z,1531437219.613 [Aanderaa_O2](INFO): Powering down
2018-07-12T23:13:40.378Z,1531437220.378 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-07-12T23:13:40.378Z,1531437220.378 [Aanderaa_O2] No Fault, FailCount= 2
2018-07-12T23:13:40.378Z,1531437220.378 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:13:40.378Z,1531437220.378 [BuoyancyServo] No Fault, FailCount= 4
2018-07-12T23:13:40.772Z,1531437220.772 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:13:40.890Z,1531437220.890 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:13:42.294Z,1531437222.294 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	651	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2018-07-12T23:13:42.295Z,1531437222.295 [Aanderaa_O2] Data Fault, FailCount= 3
2018-07-12T23:13:42.295Z,1531437222.295 [Aanderaa_O2](ERROR): Data Fault
2018-07-12T23:13:42.396Z,1531437222.396 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2018-07-12T23:13:42.397Z,1531437222.397 [CBIT](FAULT): Data Fault in component: Aanderaa_O2
2018-07-12T23:13:42.813Z,1531437222.813 [Aanderaa_O2](INFO): Powering down
2018-07-12T23:13:43.989Z,1531437223.989 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000

2018-07-12T23:13:43.989Z,1531437223.989 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:13:43.989Z,1531437223.989 [BuoyancyServo] Hardware Fault, FailCount= 5
2018-07-12T23:13:43.989Z,1531437223.989 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:13:43.995Z,1531437223.995 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:13:43.996Z,1531437223.996 [CBIT](CRITICAL): Hardware Fault in component: BuoyancyServo
2018-07-12T23:13:44.372Z,1531437224.372 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:13:44.373Z,1531437224.373 [BuoyancyServo](INFO): Powering down
2018-07-12T23:13:44.396Z,1531437224.396 [CBIT](INFO): Critical error at 20180712T231343
2018-07-12T23:13:45.177Z,1531437225.177 [Startup:StartupSatComms:A](INFO): Timed out from 2018-07-12T23:12:44.8Z
2018-07-12T23:13:45.177Z,1531437225.177 [Startup:StartupSatComms:A] Stopped
2018-07-12T23:13:45.177Z,1531437225.177 [Startup:StartupSatComms:B] Running Loop=1
2018-07-12T23:13:45.584Z,1531437225.584 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-12T23:13:47.148Z,1531437227.148 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-12T23:13:47.153Z,1531437227.153 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-07-12T23:13:47.153Z,1531437227.153 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-12T23:13:47.155Z,1531437227.155 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-12T23:13:47.155Z,1531437227.155 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-07-12T23:13:47.155Z,1531437227.155 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-12T23:13:47.199Z,1531437227.199 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-12T23:13:47.199Z,1531437227.199 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-12T23:13:47.573Z,1531437227.573 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-12T23:13:47.573Z,1531437227.573 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-07-12T23:13:47.573Z,1531437227.573 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-12T23:13:47.574Z,1531437227.574 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-07-12T23:13:47.989Z,1531437227.989 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-12T23:13:47.990Z,1531437227.990 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-12T23:13:47.990Z,1531437227.990 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-12T23:13:47.991Z,1531437227.991 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-12T23:13:47.991Z,1531437227.991 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-12T23:13:47.991Z,1531437227.991 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-12T23:13:58.564Z,1531437238.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.2 s old, using for 20.0 s.
2018-07-12T23:13:58.920Z,1531437238.920 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.5 s old, using for 20.0 s.
2018-07-12T23:13:59.317Z,1531437239.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2018-07-12T23:13:59.680Z,1531437239.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s.
2018-07-12T23:14:00.071Z,1531437240.071 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2018-07-12T23:14:00.491Z,1531437240.491 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s.
2018-07-12T23:14:00.879Z,1531437240.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2018-07-12T23:14:01.288Z,1531437241.288 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2018-07-12T23:14:01.668Z,1531437241.668 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s.
2018-07-12T23:14:02.078Z,1531437242.078 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.7 s old, using for 20.0 s.
2018-07-12T23:14:02.480Z,1531437242.480 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2018-07-12T23:14:02.868Z,1531437242.868 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-07-12T23:14:03.303Z,1531437243.303 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2018-07-12T23:14:09.636Z,1531437249.636 [NAL9602](INFO): SBD MO Status=2, MOMSN=32, MT Status=2, MTMSN=0
2018-07-12T23:14:09.636Z,1531437249.636 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-12T23:14:13.588Z,1531437253.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.2 s old, using for 20.0 s.
2018-07-12T23:14:13.916Z,1531437253.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.5 s old, using for 20.0 s.
2018-07-12T23:14:14.238Z,1531437254.238 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004456
2018-07-12T23:14:14.309Z,1531437254.309 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2018-07-12T23:14:14.701Z,1531437254.701 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s.
2018-07-12T23:14:15.091Z,1531437255.091 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2018-07-12T23:14:18.327Z,1531437258.327 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2018-07-12T23:14:18.473Z,1531437258.473 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2018-07-12T23:14:18.769Z,1531437258.769 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180712T225355/Courier0010.lzma
2018-07-12T23:14:18.845Z,1531437258.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2018-07-12T23:14:19.274Z,1531437259.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2018-07-12T23:14:19.566Z,1531437259.566 [DataOverHttps](INFO): Moved sent file to Logs/20180712T225355/Courier0010.lzma.bak
2018-07-12T23:14:19.566Z,1531437259.566 [DataOverHttps](INFO): SBD MOMSN=8366770
2018-07-12T23:14:19.633Z,1531437259.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s.
2018-07-12T23:14:20.042Z,1531437260.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s.
2018-07-12T23:14:20.419Z,1531437260.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.0 s old, using for 20.0 s.
2018-07-12T23:14:23.666Z,1531437263.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.3 s old, using for 20.0 s.
2018-07-12T23:14:23.812Z,1531437263.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-12T23:14:24.246Z,1531437264.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2018-07-12T23:14:24.599Z,1531437264.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-12T23:14:29.040Z,1531437269.040 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20180712T231035/Courier0000.lzma
2018-07-12T23:14:31.038Z,1531437271.038 [DataOverHttps](INFO): Moved sent file to Logs/20180712T231035/Courier0000.lzma.bak
2018-07-12T23:14:31.038Z,1531437271.038 [DataOverHttps](INFO): SBD MOMSN=8366772
2018-07-12T23:14:31.919Z,1531437271.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.3 s old, using for 20.0 s.
2018-07-12T23:14:35.082Z,1531437275.082 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.5 s old, using for 20.0 s.
2018-07-12T23:14:35.243Z,1531437275.243 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-12T23:14:35.625Z,1531437275.625 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-12T23:14:36.003Z,1531437276.003 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-12T23:14:36.402Z,1531437276.402 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-12T23:14:36.781Z,1531437276.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-12T23:14:37.198Z,1531437277.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-12T23:14:37.595Z,1531437277.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-12T23:14:40.859Z,1531437280.859 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-12T23:14:40.955Z,1531437280.955 [DataOverHttps](INFO): Sending 291 bytes from file Logs/20180712T225355/Express0008.lzma
2018-07-12T23:14:41.713Z,1531437281.713 [DataOverHttps](INFO): Moved sent file to Logs/20180712T225355/Express0008.lzma.bak
2018-07-12T23:14:41.714Z,1531437281.714 [DataOverHttps](INFO): SBD MOMSN=8366778
2018-07-12T23:14:45.810Z,1531437285.810 [Startup:StartupSatComms:B](INFO): Timed out from 2018-07-12T23:13:45.2Z
2018-07-12T23:14:45.810Z,1531437285.810 [Startup:StartupSatComms:B] Stopped
2018-07-12T23:14:45.810Z,1531437285.810 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-07-12T23:14:45.810Z,1531437285.810 [Startup:StartupSatComms] Stopped
2018-07-12T23:14:45.810Z,1531437285.810 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-07-12T23:14:45.811Z,1531437285.811 [Startup](INFO): Completed Startup
2018-07-12T23:14:45.811Z,1531437285.811 [MissionManager](INFO): Startup is completed.
2018-07-12T23:14:45.812Z,1531437285.812 [MissionManager](INFO): Uninitializing Mission Startup
2018-07-12T23:14:45.812Z,1531437285.812 [Startup] Stopped
2018-07-12T23:14:45.812Z,1531437285.812 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-07-12T23:14:45.812Z,1531437285.812 [Startup:A.GoToSurface] Stopped
2018-07-12T23:14:45.812Z,1531437285.812 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-12T23:14:45.976Z,1531437285.976 [MissionManager](IMPORTANT): Started mission Default
2018-07-12T23:14:45.976Z,1531437285.976 [Default] Running Loop=1
2018-07-12T23:14:45.976Z,1531437285.976 [Default](DEBUG): Aggregate::initialize Default
2018-07-12T23:14:45.976Z,1531437285.976 [Default:B.GoToSurface] Running Loop=1
2018-07-12T23:14:45.976Z,1531437285.976 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-12T23:14:45.977Z,1531437285.977 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-12T23:14:45.977Z,1531437285.977 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-12T23:14:45.977Z,1531437285.977 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-12T23:14:45.978Z,1531437285.978 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-12T23:14:45.978Z,1531437285.978 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-12T23:14:45.978Z,1531437285.978 [Default:A.Wait] Running Loop=1
2018-07-12T23:14:45.979Z,1531437285.979 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-07-12T23:14:46.715Z,1531437286.715 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-12T23:14:47.114Z,1531437287.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-12T23:14:47.508Z,1531437287.508 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-12T23:14:50.754Z,1531437290.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-12T23:14:50.833Z,1531437290.833 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180712T225355/Express0011.lzma
2018-07-12T23:14:50.926Z,1531437290.926 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.6 s old, using for 20.0 s.
2018-07-12T23:14:51.298Z,1531437291.298 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.0 s old, using for 20.0 s.
2018-07-12T23:14:51.588Z,1531437291.588 [DataOverHttps](INFO): Moved sent file to Logs/20180712T225355/Express0011.lzma.bak
2018-07-12T23:14:51.589Z,1531437291.589 [DataOverHttps](INFO): SBD MOMSN=8366796
2018-07-12T23:14:51.664Z,1531437291.664 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2018-07-12T23:14:52.025Z,1531437292.025 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2018-07-12T23:14:52.465Z,1531437292.465 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2018-07-12T23:14:59.251Z,1531437299.251 [Default:A.Wait](INFO): Done Waiting.
2018-07-12T23:14:59.251Z,1531437299.251 [Default:A.Wait] Stopped
2018-07-12T23:14:59.251Z,1531437299.251 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-07-12T23:14:59.674Z,1531437299.674 [Default:CheckIn] Running Loop=1
2018-07-12T23:14:59.675Z,1531437299.675 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-12T23:14:59.675Z,1531437299.675 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-12T23:15:00.058Z,1531437300.058 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-12T23:15:00.069Z,1531437300.069 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-07-12T23:15:00.447Z,1531437300.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-12T23:15:00.835Z,1531437300.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-12T23:15:01.272Z,1531437301.272 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-12T23:15:01.636Z,1531437301.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-12T23:15:02.056Z,1531437302.056 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-12T23:15:02.468Z,1531437302.468 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-12T23:15:02.834Z,1531437302.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-12T23:15:03.275Z,1531437303.275 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-12T23:15:03.636Z,1531437303.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-12T23:15:04.040Z,1531437304.040 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-12T23:15:04.454Z,1531437304.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-12T23:15:04.850Z,1531437304.850 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-12T23:15:05.316Z,1531437305.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2018-07-12T23:15:05.676Z,1531437305.676 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-12T23:15:06.051Z,1531437306.051 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-12T23:15:06.451Z,1531437306.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-12T23:15:06.850Z,1531437306.850 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-12T23:15:07.282Z,1531437307.282 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-12T23:15:07.637Z,1531437307.637 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-12T23:15:08.052Z,1531437308.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-12T23:15:08.457Z,1531437308.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-12T23:15:08.851Z,1531437308.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-12T23:15:09.264Z,1531437309.264 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-12T23:15:09.620Z,1531437309.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-12T23:15:10.024Z,1531437310.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-12T23:15:10.456Z,1531437310.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-12T23:15:10.824Z,1531437310.824 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-12T23:15:11.282Z,1531437311.282 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-12T23:15:11.655Z,1531437311.655 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-12T23:15:12.030Z,1531437312.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-12T23:15:12.462Z,1531437312.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-12T23:15:12.836Z,1531437312.836 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-12T23:15:13.277Z,1531437313.277 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-12T23:15:13.650Z,1531437313.650 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-12T23:15:14.047Z,1531437314.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-12T23:15:14.446Z,1531437314.446 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-07-12T23:15:14.847Z,1531437314.847 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-07-12T23:15:15.265Z,1531437315.265 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-07-12T23:15:15.633Z,1531437315.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-07-12T23:15:16.036Z,1531437316.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-07-12T23:15:16.452Z,1531437316.452 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-07-12T23:15:16.835Z,1531437316.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-07-12T23:15:17.276Z,1531437317.276 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-07-12T23:15:17.707Z,1531437317.707 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2018-07-12T23:15:18.057Z,1531437318.057 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-07-12T23:15:18.448Z,1531437318.448 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-07-12T23:15:18.840Z,1531437318.840 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-07-12T23:15:19.266Z,1531437319.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-07-12T23:15:19.620Z,1531437319.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2018-07-12T23:15:44.066Z,1531437344.066 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:15:44.066Z,1531437344.066 [BuoyancyServo] No Fault, FailCount= 5
2018-07-12T23:15:44.497Z,1531437344.497 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:15:44.618Z,1531437344.618 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:15:47.705Z,1531437347.705 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000

2018-07-12T23:15:47.705Z,1531437347.705 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:15:47.705Z,1531437347.705 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-07-12T23:15:47.705Z,1531437347.705 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:15:47.711Z,1531437347.711 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:15:50.916Z,1531437350.916 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:15:50.916Z,1531437350.916 [BuoyancyServo](INFO): Powering down
2018-07-12T23:15:53.077Z,1531437353.077 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:15:53.077Z,1531437353.077 [BuoyancyServo] No Fault, FailCount= 1
2018-07-12T23:15:53.470Z,1531437353.470 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:15:53.590Z,1531437353.590 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:15:55.833Z,1531437355.833 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-12T23:15:56.260Z,1531437356.260 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-12T23:15:56.637Z,1531437356.637 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-12T23:15:56.685Z,1531437356.685 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000

2018-07-12T23:15:56.685Z,1531437356.685 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:15:56.685Z,1531437356.685 [BuoyancyServo] Hardware Fault, FailCount= 2
2018-07-12T23:15:56.686Z,1531437356.686 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:15:56.691Z,1531437356.691 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:15:57.033Z,1531437357.033 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-12T23:15:57.042Z,1531437357.042 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:15:57.043Z,1531437357.043 [BuoyancyServo](INFO): Powering down
2018-07-12T23:15:57.434Z,1531437357.434 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-12T23:15:57.816Z,1531437357.816 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-12T23:15:58.267Z,1531437358.267 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-12T23:15:58.620Z,1531437358.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-12T23:15:59.041Z,1531437359.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-12T23:15:59.068Z,1531437359.068 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:15:59.068Z,1531437359.068 [BuoyancyServo] No Fault, FailCount= 2
2018-07-12T23:15:59.452Z,1531437359.452 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-12T23:15:59.461Z,1531437359.461 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:15:59.580Z,1531437359.580 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:15:59.827Z,1531437359.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-12T23:16:00.221Z,1531437360.221 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-12T23:16:00.631Z,1531437360.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-12T23:16:01.032Z,1531437361.032 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-12T23:16:01.431Z,1531437361.431 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-12T23:16:01.833Z,1531437361.833 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-12T23:16:02.268Z,1531437362.268 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-12T23:16:02.643Z,1531437362.643 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-12T23:16:02.689Z,1531437362.689 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000

2018-07-12T23:16:02.689Z,1531437362.689 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:16:02.690Z,1531437362.690 [BuoyancyServo] Hardware Fault, FailCount= 3
2018-07-12T23:16:02.690Z,1531437362.690 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:16:02.696Z,1531437362.696 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:16:03.036Z,1531437363.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-12T23:16:03.059Z,1531437363.059 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:16:03.060Z,1531437363.060 [BuoyancyServo](INFO): Powering down
2018-07-12T23:16:03.436Z,1531437363.436 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-12T23:16:03.806Z,1531437363.806 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-12T23:16:04.277Z,1531437364.277 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-12T23:16:04.621Z,1531437364.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-12T23:16:05.026Z,1531437365.026 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-12T23:16:05.060Z,1531437365.060 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:16:05.060Z,1531437365.060 [BuoyancyServo] No Fault, FailCount= 3
2018-07-12T23:16:05.454Z,1531437365.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-12T23:16:05.463Z,1531437365.463 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:16:05.578Z,1531437365.578 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:16:05.819Z,1531437365.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-12T23:16:06.260Z,1531437366.260 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-12T23:16:06.624Z,1531437366.624 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-12T23:16:07.023Z,1531437367.023 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-12T23:16:07.487Z,1531437367.487 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-12T23:16:07.868Z,1531437367.868 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-12T23:16:08.283Z,1531437368.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-12T23:16:08.628Z,1531437368.628 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-12T23:16:08.669Z,1531437368.669 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000

2018-07-12T23:16:08.669Z,1531437368.669 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:16:08.669Z,1531437368.669 [BuoyancyServo] Hardware Fault, FailCount= 4
2018-07-12T23:16:08.669Z,1531437368.669 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:16:08.676Z,1531437368.676 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:16:09.037Z,1531437369.037 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-12T23:16:09.047Z,1531437369.047 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:16:09.047Z,1531437369.047 [BuoyancyServo](INFO): Powering down
2018-07-12T23:16:09.437Z,1531437369.437 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-12T23:16:11.042Z,1531437371.042 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:16:11.043Z,1531437371.043 [BuoyancyServo] No Fault, FailCount= 4
2018-07-12T23:16:11.453Z,1531437371.453 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:16:11.574Z,1531437371.574 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:16:12.354Z,1531437372.354 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-07-12T23:16:12.354Z,1531437372.354 [Aanderaa_O2] No Fault, FailCount= 3
2018-07-12T23:16:14.166Z,1531437374.166 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	651	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2018-07-12T23:16:14.167Z,1531437374.167 [Aanderaa_O2] Data Fault, FailCount= 1
2018-07-12T23:16:14.167Z,1531437374.167 [Aanderaa_O2](ERROR): Data Fault
2018-07-12T23:16:14.300Z,1531437374.300 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2018-07-12T23:16:14.685Z,1531437374.685 [Aanderaa_O2](INFO): Powering down
2018-07-12T23:16:14.798Z,1531437374.798 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000

2018-07-12T23:16:14.798Z,1531437374.798 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:16:14.798Z,1531437374.798 [BuoyancyServo] Hardware Fault, FailCount= 5
2018-07-12T23:16:14.798Z,1531437374.798 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:16:14.804Z,1531437374.804 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:16:14.804Z,1531437374.804 [CBIT](CRITICAL): Hardware Fault in component: BuoyancyServo
2018-07-12T23:16:15.055Z,1531437375.055 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:16:15.056Z,1531437375.056 [BuoyancyServo](INFO): Powering down
2018-07-12T23:16:15.077Z,1531437375.077 [CBIT](INFO): Critical error at 20180712T231614
2018-07-12T23:16:15.458Z,1531437375.458 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-07-12T23:16:15.458Z,1531437375.458 [Aanderaa_O2] No Fault, FailCount= 1
2018-07-12T23:16:17.367Z,1531437377.367 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	651	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2018-07-12T23:16:17.368Z,1531437377.368 [Aanderaa_O2] Data Fault, FailCount= 2
2018-07-12T23:16:17.368Z,1531437377.368 [Aanderaa_O2](ERROR): Data Fault
2018-07-12T23:16:17.454Z,1531437377.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-12T23:16:17.472Z,1531437377.472 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2018-07-12T23:16:17.885Z,1531437377.885 [Aanderaa_O2](INFO): Powering down
2018-07-12T23:16:17.967Z,1531437377.967 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2018-07-12T23:16:18.254Z,1531437378.254 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-12T23:16:18.632Z,1531437378.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-12T23:16:18.646Z,1531437378.646 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-07-12T23:16:18.646Z,1531437378.646 [Aanderaa_O2] No Fault, FailCount= 2
2018-07-12T23:16:19.021Z,1531437379.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-12T23:16:19.437Z,1531437379.437 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-12T23:16:19.822Z,1531437379.822 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-12T23:16:20.314Z,1531437380.314 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s.
2018-07-12T23:16:20.568Z,1531437380.568 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	651	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2018-07-12T23:16:20.568Z,1531437380.568 [Aanderaa_O2] Data Fault, FailCount= 3
2018-07-12T23:16:20.568Z,1531437380.568 [Aanderaa_O2](ERROR): Data Fault
2018-07-12T23:16:20.688Z,1531437380.688 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.7 s old, using for 20.0 s.
2018-07-12T23:16:20.714Z,1531437380.714 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2018-07-12T23:16:20.714Z,1531437380.714 [CBIT](FAULT): Data Fault in component: Aanderaa_O2
2018-07-12T23:16:21.085Z,1531437381.085 [Aanderaa_O2](INFO): Powering down
2018-07-12T23:16:21.256Z,1531437381.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.2 s old, using for 20.0 s.
2018-07-12T23:16:21.413Z,1531437381.413 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-12T23:16:21.813Z,1531437381.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-12T23:16:22.259Z,1531437382.259 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-12T23:16:22.612Z,1531437382.612 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-12T23:16:23.050Z,1531437383.050 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-12T23:16:23.448Z,1531437383.448 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-12T23:16:23.843Z,1531437383.843 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-12T23:16:24.258Z,1531437384.258 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-12T23:16:45.867Z,1531437405.867 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-12T23:16:46.236Z,1531437406.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-12T23:16:46.640Z,1531437406.640 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-12T23:16:47.040Z,1531437407.040 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-12T23:16:47.444Z,1531437407.444 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-12T23:16:47.840Z,1531437407.840 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-12T23:16:52.586Z,1531437412.586 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.1 s old, using for 20.0 s.
2018-07-12T23:16:52.587Z,1531437412.587 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-12T23:16:52.587Z,1531437412.587 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-07-12T23:16:52.588Z,1531437412.588 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-12T23:16:52.589Z,1531437412.589 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-12T23:16:52.590Z,1531437412.590 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-07-12T23:16:52.590Z,1531437412.590 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-12T23:16:52.635Z,1531437412.635 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-12T23:16:52.635Z,1531437412.635 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-12T23:16:52.878Z,1531437412.878 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-12T23:16:52.878Z,1531437412.878 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-07-12T23:16:52.878Z,1531437412.878 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-12T23:16:52.878Z,1531437412.878 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-07-12T23:16:53.182Z,1531437413.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-12T23:16:53.183Z,1531437413.183 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-12T23:16:53.183Z,1531437413.183 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-12T23:16:53.183Z,1531437413.183 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-12T23:16:53.184Z,1531437413.184 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-12T23:16:53.184Z,1531437413.184 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-12T23:16:53.492Z,1531437413.492 [NAL9602](INFO): GPS fix at 20180712T231635: (36.802780, -121.788044)
2018-07-12T23:16:53.624Z,1531437413.624 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2018-07-12T23:16:53.655Z,1531437413.655 [Default:CheckIn:Read_GPS] Stopped
2018-07-12T23:16:53.655Z,1531437413.655 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-12T23:16:54.012Z,1531437414.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2018-07-12T23:16:54.027Z,1531437414.027 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-12T23:16:54.640Z,1531437414.640 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-12T23:16:55.007Z,1531437415.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.5 s old, using for 20.0 s.
2018-07-12T23:17:02.586Z,1531437422.586 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s.
2018-07-12T23:17:02.704Z,1531437422.704 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20180712T231035/Courier0004.lzma
2018-07-12T23:17:02.917Z,1531437422.917 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.4 s old, using for 20.0 s.
2018-07-12T23:17:03.232Z,1531437423.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.8 s old, using for 20.0 s.
2018-07-12T23:17:03.462Z,1531437423.462 [DataOverHttps](INFO): Moved sent file to Logs/20180712T231035/Courier0004.lzma.bak
2018-07-12T23:17:03.462Z,1531437423.462 [DataOverHttps](INFO): SBD MOMSN=8366805
2018-07-12T23:17:03.624Z,1531437423.624 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2018-07-12T23:17:04.024Z,1531437424.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2018-07-12T23:17:04.424Z,1531437424.424 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2018-07-12T23:17:14.257Z,1531437434.257 [DataOverHttps](INFO): Sending 1075 bytes from file Logs/20180712T231035/Express0001.lzma
2018-07-12T23:17:15.024Z,1531437435.024 [DataOverHttps](INFO): Moved sent file to Logs/20180712T231035/Express0001.lzma.bak
2018-07-12T23:17:15.024Z,1531437435.024 [DataOverHttps](INFO): SBD MOMSN=8366809
2018-07-12T23:17:21.336Z,1531437441.336 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-12T23:17:21.706Z,1531437441.706 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-12T23:17:22.104Z,1531437442.104 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-12T23:17:25.450Z,1531437445.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-07-12T23:17:25.703Z,1531437445.703 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-12T23:17:25.940Z,1531437445.940 [DataOverHttps](INFO): Sending 355 bytes from file Logs/20180712T231035/Express0005.lzma
2018-07-12T23:17:26.042Z,1531437446.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2018-07-12T23:17:26.412Z,1531437446.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2018-07-12T23:17:26.724Z,1531437446.724 [DataOverHttps](INFO): Moved sent file to Logs/20180712T231035/Express0005.lzma.bak
2018-07-12T23:17:26.725Z,1531437446.725 [DataOverHttps](INFO): SBD MOMSN=8366869
2018-07-12T23:17:26.932Z,1531437446.932 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-12T23:17:27.279Z,1531437447.279 [Default:CheckIn:Read_Iridium] Stopped
2018-07-12T23:17:27.279Z,1531437447.279 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-12T23:17:27.279Z,1531437447.279 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-12T23:17:32.913Z,1531437452.913