2019-09-06T21:52:33.995Z,1567806753.995 [Supervisor](DEBUG): Initializing supervisor.
2019-09-06T21:52:33.997Z,1567806753.997 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-09-06T21:52:33.998Z,1567806753.998 [SyncHandler](INFO): Protected caller Thread ID is 9301
2019-09-06T21:52:33.998Z,1567806753.998 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-09-06T21:52:33.999Z,1567806753.999 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-09-06T21:52:33.000Z,1567806754.000 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9302
2019-09-06T21:52:34.002Z,1567806754.002 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-09-06T21:52:34.014Z,1567806754.014 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-09-06T21:52:34.015Z,1567806754.015 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-09-06T21:52:34.015Z,1567806754.015 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9303
2019-09-06T21:52:34.016Z,1567806754.016 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-09-06T21:52:34.017Z,1567806754.017 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-09-06T21:52:34.017Z,1567806754.017 [logger ThreadHandler](INFO): Protected caller Thread ID is 9304
2019-09-06T21:52:34.019Z,1567806754.019 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-09-06T21:52:34.020Z,1567806754.020 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-09-06T21:52:34.021Z,1567806754.021 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-09-06T21:52:34.116Z,1567806754.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-09-06T21:52:34.116Z,1567806754.116 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-09-06T21:52:34.682Z,1567806754.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-09-06T21:52:34.683Z,1567806754.683 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-09-06T21:52:34.780Z,1567806754.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-09-06T21:52:34.780Z,1567806754.780 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-09-06T21:52:34.881Z,1567806754.881 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-09-06T21:52:34.882Z,1567806754.882 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-09-06T21:52:34.961Z,1567806754.961 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-09-06T21:52:35.105Z,1567806755.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-09-06T21:52:35.106Z,1567806755.106 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-09-06T21:52:35.407Z,1567806755.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-09-06T21:52:35.408Z,1567806755.408 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-09-06T21:52:35.948Z,1567806755.948 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-09-06T21:52:35.949Z,1567806755.949 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-09-06T21:52:36.095Z,1567806756.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-09-06T21:52:36.096Z,1567806756.096 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-09-06T21:52:36.297Z,1567806756.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-09-06T21:52:36.298Z,1567806756.298 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-09-06T21:52:36.761Z,1567806756.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-09-06T21:52:36.762Z,1567806756.762 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-09-06T21:52:36.978Z,1567806756.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-09-06T21:52:36.979Z,1567806756.979 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-09-06T21:52:37.182Z,1567806757.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-09-06T21:52:37.183Z,1567806757.183 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-09-06T21:52:37.582Z,1567806757.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-09-06T21:52:37.582Z,1567806757.582 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-09-06T21:52:37.921Z,1567806757.921 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-09-06T21:52:37.923Z,1567806757.923 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-09-06T21:52:37.924Z,1567806757.924 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-09-06T21:52:38.006Z,1567806758.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-09-06T21:52:38.163Z,1567806758.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-09-06T21:52:38.271Z,1567806758.271 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-09-06T21:52:38.355Z,1567806758.355 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-09-06T21:52:38.448Z,1567806758.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-09-06T21:52:38.644Z,1567806758.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-09-06T21:52:38.903Z,1567806758.903 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-09-06T21:52:38.903Z,1567806758.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-09-06T21:52:39.024Z,1567806759.024 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-09-06T21:52:39.314Z,1567806759.314 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-09-06T21:52:39.533Z,1567806759.533 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-09-06T21:52:39.724Z,1567806759.724 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2019-09-06T21:52:39.724Z,1567806759.724 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-09-06T21:52:39.974Z,1567806759.974 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-09-06T21:52:40.045Z,1567806760.045 [VerticalControl](DEBUG): Construct VerticalControl.
2019-09-06T21:52:40.165Z,1567806760.165 [VerticalControl] Loaded
2019-09-06T21:52:40.165Z,1567806760.165 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-09-06T21:52:40.166Z,1567806760.166 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-09-06T21:52:40.238Z,1567806760.238 [HorizontalControl] Loaded
2019-09-06T21:52:40.238Z,1567806760.238 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-09-06T21:52:40.239Z,1567806760.239 [SpeedControl](DEBUG): Construct SpeedControl.
2019-09-06T21:52:40.244Z,1567806760.244 [SpeedControl] Loaded
2019-09-06T21:52:40.245Z,1567806760.245 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-09-06T21:52:40.246Z,1567806760.246 [LoopControl](DEBUG): Construct LoopControl.
2019-09-06T21:52:40.246Z,1567806760.246 [LoopControl] Loaded
2019-09-06T21:52:40.246Z,1567806760.246 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-09-06T21:52:40.247Z,1567806760.247 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-09-06T21:52:40.247Z,1567806760.247 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-09-06T21:52:40.290Z,1567806760.290 [DepthRateCalculator] Loaded
2019-09-06T21:52:40.291Z,1567806760.291 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-09-06T21:52:40.296Z,1567806760.296 [PitchRateCalculator] Loaded
2019-09-06T21:52:40.296Z,1567806760.296 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-09-06T21:52:40.312Z,1567806760.312 [SpeedCalculator] Loaded
2019-09-06T21:52:40.313Z,1567806760.313 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-09-06T21:52:40.334Z,1567806760.334 [TempGradientCalculator] Loaded
2019-09-06T21:52:40.335Z,1567806760.335 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-09-06T21:52:40.340Z,1567806760.340 [YawRateCalculator] Loaded
2019-09-06T21:52:40.340Z,1567806760.340 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-09-06T21:52:40.370Z,1567806760.370 [ElevatorOffsetCalculator] Loaded
2019-09-06T21:52:40.371Z,1567806760.371 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-09-06T21:52:40.371Z,1567806760.371 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-09-06T21:52:40.372Z,1567806760.372 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-09-06T21:52:40.405Z,1567806760.405 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-09-06T21:52:40.405Z,1567806760.405 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-09-06T21:52:40.478Z,1567806760.478 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-09-06T21:52:40.478Z,1567806760.478 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-09-06T21:52:40.782Z,1567806760.782 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-09-06T21:52:40.783Z,1567806760.783 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-09-06T21:52:40.886Z,1567806760.886 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-09-06T21:52:40.886Z,1567806760.886 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-09-06T21:52:41.247Z,1567806761.247 [AHRS_M2] Loaded
2019-09-06T21:52:41.247Z,1567806761.247 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-09-06T21:52:41.320Z,1567806761.320 [DataOverHttps] Loaded
2019-09-06T21:52:41.321Z,1567806761.321 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-09-06T21:52:41.322Z,1567806761.322 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BB4E0
2019-09-06T21:52:41.322Z,1567806761.322 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9384
2019-09-06T21:52:41.336Z,1567806761.336 [Depth_Keller] Loaded
2019-09-06T21:52:41.336Z,1567806761.336 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-09-06T21:52:41.341Z,1567806761.341 [DropWeight] Loaded
2019-09-06T21:52:41.341Z,1567806761.341 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-09-06T21:52:41.443Z,1567806761.443 [NAL9602] Loaded
2019-09-06T21:52:41.444Z,1567806761.444 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-09-06T21:52:41.460Z,1567806761.460 [Onboard] Loaded
2019-09-06T21:52:41.460Z,1567806761.460 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-09-06T21:52:41.464Z,1567806761.464 [Radio_Surface] Loaded
2019-09-06T21:52:41.464Z,1567806761.464 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-09-06T21:52:41.465Z,1567806761.465 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408EB4E0
2019-09-06T21:52:41.465Z,1567806761.465 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9385
2019-09-06T21:52:41.513Z,1567806761.513 [Rowe_600] Loaded
2019-09-06T21:52:41.513Z,1567806761.513 [ComponentRegistry](DEBUG): SyncComponent "Rowe_600" handled in the control thread.
2019-09-06T21:52:43.084Z,1567806763.084 [BPC1] Loaded
2019-09-06T21:52:43.085Z,1567806763.085 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-09-06T21:52:43.085Z,1567806763.085 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-09-06T21:52:43.086Z,1567806763.086 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-09-06T21:52:43.193Z,1567806763.193 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-09-06T21:52:43.193Z,1567806763.193 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-09-06T21:52:43.214Z,1567806763.214 [NavChart] Loaded
2019-09-06T21:52:43.214Z,1567806763.214 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-09-06T21:52:43.219Z,1567806763.219 [UniversalFixResidualReporter] Loaded
2019-09-06T21:52:43.219Z,1567806763.219 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-09-06T21:52:43.219Z,1567806763.219 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-09-06T21:52:43.220Z,1567806763.220 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-09-06T21:52:43.359Z,1567806763.359 [SBIT](DEBUG): Construct Startup Built In Test.
2019-09-06T21:52:43.370Z,1567806763.370 [SBIT] Loaded
2019-09-06T21:52:43.370Z,1567806763.370 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-09-06T21:52:43.371Z,1567806763.371 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-09-06T21:52:43.383Z,1567806763.383 [IBIT] Loaded
2019-09-06T21:52:43.383Z,1567806763.383 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-09-06T21:52:43.386Z,1567806763.386 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-09-06T21:52:43.529Z,1567806763.529 [CBIT] Loaded
2019-09-06T21:52:43.530Z,1567806763.530 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-09-06T21:52:43.530Z,1567806763.530 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-09-06T21:52:43.531Z,1567806763.531 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-09-06T21:52:43.637Z,1567806763.637 [BuoyancyServo] Loaded
2019-09-06T21:52:43.637Z,1567806763.637 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-09-06T21:52:43.648Z,1567806763.648 [ElevatorServo] Loaded
2019-09-06T21:52:43.649Z,1567806763.649 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-09-06T21:52:43.660Z,1567806763.660 [MassServo] Loaded
2019-09-06T21:52:43.660Z,1567806763.660 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-09-06T21:52:43.671Z,1567806763.671 [RudderServo] Loaded
2019-09-06T21:52:43.672Z,1567806763.672 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-09-06T21:52:43.683Z,1567806763.683 [ThrusterServo] Loaded
2019-09-06T21:52:43.683Z,1567806763.683 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-09-06T21:52:43.684Z,1567806763.684 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-09-06T21:52:43.684Z,1567806763.684 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-09-06T21:52:43.697Z,1567806763.697 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-09-06T21:52:43.698Z,1567806763.698 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-09-06T21:52:43.881Z,1567806763.881 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-09-06T21:52:43.886Z,1567806763.886 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-09-06T21:52:43.888Z,1567806763.888 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-09-06T21:52:43.893Z,1567806763.893 [CTD_Seabird](INFO): created writer for : depth
2019-09-06T21:52:43.894Z,1567806763.894 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-09-06T21:52:43.899Z,1567806763.899 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-09-06T21:52:43.900Z,1567806763.900 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-09-06T21:52:43.905Z,1567806763.905 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-09-06T21:52:43.906Z,1567806763.906 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-09-06T21:52:43.911Z,1567806763.911 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-09-06T21:52:43.912Z,1567806763.912 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-09-06T21:52:43.918Z,1567806763.918 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-09-06T21:52:43.918Z,1567806763.918 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-09-06T21:52:43.923Z,1567806763.923 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-09-06T21:52:43.987Z,1567806763.987 [CTD_Seabird] Loaded
2019-09-06T21:52:43.987Z,1567806763.987 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-09-06T21:52:43.989Z,1567806763.989 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A6F4E0
2019-09-06T21:52:43.989Z,1567806763.989 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9386
2019-09-06T21:52:44.138Z,1567806764.138 [WetLabsBB2FL] Loaded
2019-09-06T21:52:44.138Z,1567806764.138 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-09-06T21:52:44.139Z,1567806764.139 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A9F4E0
2019-09-06T21:52:44.139Z,1567806764.139 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9387
2019-09-06T21:52:44.140Z,1567806764.140 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-09-06T21:52:44.144Z,1567806764.144 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-09-06T21:52:44.145Z,1567806764.145 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-09-06T21:52:44.151Z,1567806764.151 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-09-06T21:52:44.153Z,1567806764.153 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACF4E0
2019-09-06T21:52:44.153Z,1567806764.153 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9388
2019-09-06T21:52:44.157Z,1567806764.157 [Supervisor](INFO): Main Thread ID is 9300
2019-09-06T21:52:44.158Z,1567806764.158 [Supervisor](DEBUG): Running supervisor.
2019-09-06T21:52:44.158Z,1567806764.158 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9389
2019-09-06T21:52:44.161Z,1567806764.161 [controlThread ThreadHandler](INFO): Handler Thread ID is 9390
2019-09-06T21:52:44.161Z,1567806764.161 [controlThread](DEBUG): Initializing ControlThread
2019-09-06T21:52:44.162Z,1567806764.162 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-09-06T21:52:44.164Z,1567806764.164 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-09-06T21:52:44.164Z,1567806764.164 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-09-06T21:52:44.165Z,1567806764.165 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-09-06T21:52:44.165Z,1567806764.165 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-09-06T21:52:44.165Z,1567806764.165 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-09-06T21:52:44.166Z,1567806764.166 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-09-06T21:52:44.166Z,1567806764.166 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-09-06T21:52:44.167Z,1567806764.167 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-09-06T21:52:44.167Z,1567806764.167 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-09-06T21:52:44.171Z,1567806764.171 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-09-06T21:52:44.172Z,1567806764.172 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-09-06T21:52:44.172Z,1567806764.172 [SBIT](INFO): Initialize SBIT Component.
2019-09-06T21:52:44.173Z,1567806764.173 [SBIT](IMPORTANT): git: 2019-08-21a
2019-09-06T21:52:44.173Z,1567806764.173 [SBIT](INFO): git hash: 91726968a91bb65c09438a13bfa0101a916cbc64
2019-09-06T21:52:44.173Z,1567806764.173 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-09-06T21:52:44.173Z,1567806764.173 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-09-06T21:52:44.175Z,1567806764.175 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2019-09-06T21:52:44.175Z,1567806764.175 [IBIT](INFO): Initialize IBIT Component.
2019-09-06T21:52:44.176Z,1567806764.176 [CBIT](DEBUG): Initialize CBIT Component.
2019-09-06T21:52:44.177Z,1567806764.177 [logger ThreadHandler](INFO): Handler Thread ID is 9391
2019-09-06T21:52:44.189Z,1567806764.189 [CBIT](DEBUG): Initialized mux pins.
2019-09-06T21:52:44.189Z,1567806764.189 [CBIT](DEBUG): Initializing the watchdog timer.
2019-09-06T21:52:44.197Z,1567806764.197 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9392
2019-09-06T21:52:44.202Z,1567806764.202 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-09-06T21:52:44.209Z,1567806764.209 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9393
2019-09-06T21:52:44.213Z,1567806764.213 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-09-06T21:52:44.213Z,1567806764.213 [CBIT](DEBUG): Initializing heartbeat.
2019-09-06T21:52:44.221Z,1567806764.221 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9394
2019-09-06T21:52:44.222Z,1567806764.222 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-09-06T21:52:44.225Z,1567806764.225 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-09-06T21:52:44.226Z,1567806764.226 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9396
2019-09-06T21:52:44.228Z,1567806764.228 [WetLabsBB2FL](INFO): Powering down
2019-09-06T21:52:44.257Z,1567806764.257 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9397
2019-09-06T21:52:44.260Z,1567806764.260 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-09-06T21:52:44.261Z,1567806764.261 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-09-06T21:52:44.261Z,1567806764.261 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-09-06T21:52:44.261Z,1567806764.261 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-09-06T21:52:44.261Z,1567806764.261 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-09-06T21:52:44.261Z,1567806764.261 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-09-06T21:52:44.261Z,1567806764.261 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-09-06T21:52:44.262Z,1567806764.262 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-09-06T21:52:44.262Z,1567806764.262 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-09-06T21:52:44.262Z,1567806764.262 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-09-06T21:52:44.262Z,1567806764.262 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-09-06T21:52:44.262Z,1567806764.262 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-09-06T21:52:44.263Z,1567806764.263 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-09-06T21:52:44.263Z,1567806764.263 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-09-06T21:52:44.263Z,1567806764.263 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-09-06T21:52:44.263Z,1567806764.263 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-09-06T21:52:44.285Z,1567806764.285 [CBIT](DEBUG): Deactivating GF circuits.
2019-09-06T21:52:44.285Z,1567806764.285 [CBIT](DEBUG): Deactivating emergency mode.
2019-09-06T21:52:44.321Z,1567806764.321 [CBIT](DEBUG): Backplane powered.
2019-09-06T21:52:44.323Z,1567806764.323 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-09-06T21:52:44.333Z,1567806764.333 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-06T21:52:44.351Z,1567806764.351 [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>


2019-09-06T21:52:44.352Z,1567806764.352 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-09-06T21:52:44.423Z,1567806764.423 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-09-06T21:52:44.424Z,1567806764.424 [Default:A.Wait](DEBUG): Construct Wait.
2019-09-06T21:52:44.426Z,1567806764.426 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-06T21:52:44.473Z,1567806764.473 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-09-06T21:52:44.475Z,1567806764.475 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-09-06T21:52:44.498Z,1567806764.498 [Default:E.Execute](DEBUG): Construct Execute.
2019-09-06T21:52:44.502Z,1567806764.502 [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 on</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>


2019-09-06T21:52:44.512Z,1567806764.512 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,Rowe_600,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-09-06T21:52:44.525Z,1567806764.525 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-06T21:52:44.558Z,1567806764.558 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2019-09-06T21:52:44.613Z,1567806764.613 [Radio_Surface](INFO): Powering up
2019-09-06T21:52:44.653Z,1567806764.653 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-09-06T21:52:44.691Z,1567806764.691 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-09-06T21:52:44.697Z,1567806764.697 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-09-06T21:52:44.698Z,1567806764.698 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-09-06T21:52:44.705Z,1567806764.705 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-09-06T21:52:44.706Z,1567806764.706 [MassServo](DEBUG): Initializing EZServoServo.
2019-09-06T21:52:44.713Z,1567806764.713 [MassServo](DEBUG): Initializing MassServo.
2019-09-06T21:52:44.714Z,1567806764.714 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-06T21:52:44.721Z,1567806764.721 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-06T21:52:44.722Z,1567806764.722 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-09-06T21:52:44.729Z,1567806764.729 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-09-06T21:52:44.957Z,1567806764.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-09-06T21:52:45.657Z,1567806765.657 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-09-06T21:52:45.657Z,1567806765.657 [RudderServo](FAULT): Rudder failed to initialize
2019-09-06T21:52:45.657Z,1567806765.657 [RudderServo] Communications Fault, FailCount= 1
2019-09-06T21:52:45.657Z,1567806765.657 [RudderServo](ERROR): Communications Fault
2019-09-06T21:52:45.768Z,1567806765.768 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-09-06T21:52:45.822Z,1567806765.822 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T21:52:45.822Z,1567806765.822 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T21:52:45.994Z,1567806765.994 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-06T21:52:45.994Z,1567806765.994 [RudderServo](INFO): Powering down
2019-09-06T21:52:46.614Z,1567806766.614 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T21:52:46.615Z,1567806766.615 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T21:52:46.679Z,1567806766.679 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-06T21:52:46.798Z,1567806766.798 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-06T21:52:46.802Z,1567806766.802 [CBIT](INFO): Clearing failed state for component RudderServo
2019-09-06T21:52:46.802Z,1567806766.802 [RudderServo] No Fault, FailCount= 1
2019-09-06T21:52:47.413Z,1567806767.413 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T21:52:47.414Z,1567806767.414 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:52:48.662Z,1567806768.662 [Rowe_600](INFO): Inst Velocity X:-4.000000
2019-09-06T21:52:48.662Z,1567806768.662 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:52:49.470Z,1567806769.470 [Rowe_600](INFO): Inst Velocity X:-3.000000
2019-09-06T21:52:49.470Z,1567806769.470 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T21:52:50.292Z,1567806770.292 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:52:50.292Z,1567806770.292 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T21:52:51.610Z,1567806771.610 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T21:52:51.611Z,1567806771.611 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T21:52:52.414Z,1567806772.414 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T21:52:52.414Z,1567806772.414 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T21:52:53.591Z,1567806773.591 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:52:53.594Z,1567806773.594 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:52:53.594Z,1567806773.594 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:52:54.395Z,1567806774.395 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:52:54.398Z,1567806774.398 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:52:54.398Z,1567806774.398 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T21:52:55.607Z,1567806775.607 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:52:55.609Z,1567806775.609 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:52:55.610Z,1567806775.610 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:52:56.350Z,1567806776.350 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004525
2019-09-06T21:52:56.420Z,1567806776.420 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:52:56.422Z,1567806776.422 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:52:56.423Z,1567806776.423 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:52:57.631Z,1567806777.631 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:52:57.634Z,1567806777.634 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:52:57.634Z,1567806777.634 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:52:58.435Z,1567806778.435 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:52:58.438Z,1567806778.438 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:52:58.438Z,1567806778.438 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:52:59.647Z,1567806779.647 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:52:59.650Z,1567806779.650 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T21:52:59.650Z,1567806779.650 [Rowe_600](INFO): Inst Velocity Y:-2.000000
2019-09-06T21:53:00.456Z,1567806780.456 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:00.458Z,1567806780.458 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:00.459Z,1567806780.459 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:01.667Z,1567806781.667 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:01.669Z,1567806781.669 [Rowe_600](ERROR): only read 3 of 4 data items
2019-09-06T21:53:01.669Z,1567806781.669 [Rowe_600](ERROR): Failed to parse:
:RA,  0.00,   0.00,  11.14,   0.00
2019-09-06T21:53:02.492Z,1567806782.492 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:02.494Z,1567806782.494 [Rowe_600](INFO): Inst Velocity X:-3.000000
2019-09-06T21:53:02.495Z,1567806782.495 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:53:03.291Z,1567806783.291 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:03.294Z,1567806783.294 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:03.294Z,1567806783.294 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:04.500Z,1567806784.500 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:04.503Z,1567806784.503 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:04.503Z,1567806784.503 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:05.304Z,1567806785.304 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:05.307Z,1567806785.307 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:05.307Z,1567806785.307 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:06.515Z,1567806786.515 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:06.518Z,1567806786.518 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:06.519Z,1567806786.519 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:07.727Z,1567806787.727 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:07.730Z,1567806787.730 [Rowe_600](INFO): Inst Velocity X:1.000000
2019-09-06T21:53:07.730Z,1567806787.730 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T21:53:08.540Z,1567806788.540 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:08.547Z,1567806788.547 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:08.547Z,1567806788.547 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:09.347Z,1567806789.347 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:09.350Z,1567806789.350 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:53:09.350Z,1567806789.350 [Rowe_600](INFO): Inst Velocity Y:-5.000000
2019-09-06T21:53:10.571Z,1567806790.571 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:10.574Z,1567806790.574 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:10.574Z,1567806790.574 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:11.363Z,1567806791.363 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:11.366Z,1567806791.366 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T21:53:11.366Z,1567806791.366 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T21:53:11.750Z,1567806791.750 [NAL9602](INFO): Powering up NAL9602
2019-09-06T21:53:12.578Z,1567806792.578 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T21:53:12.578Z,1567806792.578 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T21:53:13.386Z,1567806793.386 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T21:53:13.386Z,1567806793.386 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T21:53:14.606Z,1567806794.606 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:53:14.606Z,1567806794.606 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T21:53:15.405Z,1567806795.405 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T21:53:15.405Z,1567806795.405 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T21:53:16.618Z,1567806796.618 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T21:53:16.618Z,1567806796.618 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T21:53:17.426Z,1567806797.426 [Rowe_600](INFO): Inst Velocity X:-3.000000
2019-09-06T21:53:17.426Z,1567806797.426 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:53:18.638Z,1567806798.638 [Rowe_600](INFO): Inst Velocity X:-5.000000
2019-09-06T21:53:18.638Z,1567806798.638 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T21:53:19.446Z,1567806799.446 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:53:19.446Z,1567806799.446 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T21:53:20.269Z,1567806800.269 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error
2019-09-06T21:53:20.269Z,1567806800.269 [Rowe_600](ERROR): Failed to parse:
:TS,192300,35,   0.0,1498.0,  0
2019-09-06T21:53:21.474Z,1567806801.474 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:53:21.474Z,1567806801.474 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T21:53:22.309Z,1567806802.309 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:22.312Z,1567806802.312 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:22.312Z,1567806802.312 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:22.670Z,1567806802.670 [NAL9602](INFO): NAL9602 initialized
2019-09-06T21:53:23.481Z,1567806803.481 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:53:23.498Z,1567806803.498 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:23.501Z,1567806803.501 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:23.501Z,1567806803.501 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:24.318Z,1567806804.318 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:24.321Z,1567806804.321 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:24.321Z,1567806804.321 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:25.520Z,1567806805.520 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:25.523Z,1567806805.523 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:25.523Z,1567806805.523 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:26.720Z,1567806806.720 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:26.723Z,1567806806.723 [Rowe_600](INFO): Inst Velocity X:118.000000
2019-09-06T21:53:26.724Z,1567806806.724 [Rowe_600](INFO): Inst Velocity Y:-71.000000
2019-09-06T21:53:27.527Z,1567806807.527 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:27.530Z,1567806807.530 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:27.530Z,1567806807.530 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:28.343Z,1567806808.343 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:28.346Z,1567806808.346 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:28.347Z,1567806808.347 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:29.545Z,1567806809.545 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:29.547Z,1567806809.547 [Rowe_600](INFO): Inst Velocity X:28.000000
2019-09-06T21:53:29.548Z,1567806809.548 [Rowe_600](INFO): Inst Velocity Y:14.000000
2019-09-06T21:53:30.363Z,1567806810.363 [Rowe_600](INFO): Inst Velocity X:-29.000000
2019-09-06T21:53:30.363Z,1567806810.363 [Rowe_600](INFO): Inst Velocity Y:29.000000
2019-09-06T21:53:31.574Z,1567806811.574 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T21:53:31.575Z,1567806811.575 [Rowe_600](INFO): Inst Velocity Y:61.000000
2019-09-06T21:53:32.372Z,1567806812.372 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:32.375Z,1567806812.375 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:32.375Z,1567806812.375 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:33.647Z,1567806813.647 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:33.650Z,1567806813.650 [Rowe_600](INFO): Inst Velocity X:119.000000
2019-09-06T21:53:33.650Z,1567806813.650 [Rowe_600](INFO): Inst Velocity Y:76.000000
2019-09-06T21:53:34.411Z,1567806814.411 [Rowe_600](INFO): Inst Velocity X:136.000000
2019-09-06T21:53:34.411Z,1567806814.411 [Rowe_600](INFO): Inst Velocity Y:70.000000
2019-09-06T21:53:35.624Z,1567806815.624 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:35.627Z,1567806815.627 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:35.627Z,1567806815.627 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:36.442Z,1567806816.442 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:36.445Z,1567806816.445 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:36.445Z,1567806816.445 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:37.644Z,1567806817.644 [Rowe_600](ERROR): Failed to parse:
:T14514000,35.   0.0,1498.0,  4
2019-09-06T21:53:38.028Z,1567806818.028 [NAL9602](INFO): SBD MO Status=0, MOMSN=645, MT Status=0, MTMSN=0
2019-09-06T21:53:38.028Z,1567806818.028 [NAL9602](INFO): No messages in MT queue
2019-09-06T21:53:38.458Z,1567806818.458 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:38.460Z,1567806818.460 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:38.461Z,1567806818.461 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:38.853Z,1567806818.853 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:53:39.300Z,1567806819.300 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:39.302Z,1567806819.302 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:39.303Z,1567806819.303 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:40.475Z,1567806820.475 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:40.478Z,1567806820.478 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:40.478Z,1567806820.478 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:41.277Z,1567806821.277 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:53:41.295Z,1567806821.295 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:41.298Z,1567806821.298 [Rowe_600](INFO): Inst Velocity X:24.000000
2019-09-06T21:53:41.298Z,1567806821.298 [Rowe_600](INFO): Inst Velocity Y:-45.000000
2019-09-06T21:53:42.526Z,1567806822.526 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:42.528Z,1567806822.528 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:42.537Z,1567806822.537 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:43.323Z,1567806823.323 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:43.326Z,1567806823.326 [Rowe_600](INFO): Inst Velocity X:17.000000
2019-09-06T21:53:43.326Z,1567806823.326 [Rowe_600](INFO): Inst Velocity Y:-38.000000
2019-09-06T21:53:43.731Z,1567806823.731 [SBIT](IMPORTANT): Beginning Startup BIT
2019-09-06T21:53:43.766Z,1567806823.766 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-06T21:53:44.113Z,1567806824.113 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:53:44.529Z,1567806824.529 [Rowe_600](INFO): Inst Velocity X:-8.000000
2019-09-06T21:53:44.529Z,1567806824.529 [Rowe_600](INFO): Inst Velocity Y:-27.000000
2019-09-06T21:53:45.319Z,1567806825.319 [Rowe_600](INFO): Inst Velocity X:-4.000000
2019-09-06T21:53:45.319Z,1567806825.319 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:53:46.749Z,1567806826.749 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:53:46.790Z,1567806826.790 [Rowe_600](INFO): Inst Velocity X:-20.000000
2019-09-06T21:53:46.790Z,1567806826.790 [Rowe_600](INFO): Inst Velocity Y:17.000000
2019-09-06T21:53:47.554Z,1567806827.554 [Rowe_600](INFO): Inst Velocity X:-52.000000
2019-09-06T21:53:47.554Z,1567806827.554 [Rowe_600](INFO): Inst Velocity Y:33.000000
2019-09-06T21:53:48.359Z,1567806828.359 [Rowe_600](INFO): Inst Velocity X:-158.000000
2019-09-06T21:53:48.359Z,1567806828.359 [Rowe_600](INFO): Inst Velocity Y:19.000000
2019-09-06T21:53:49.129Z,1567806829.129 [CBIT](INFO): A/D timeout, 5 tries over 139 ms

2019-09-06T21:53:49.313Z,1567806829.313 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:53:49.339Z,1567806829.339 [Rowe_600](INFO): Inst Velocity X:-248.000000
2019-09-06T21:53:49.339Z,1567806829.339 [Rowe_600](INFO): Inst Velocity Y:18.000000
2019-09-06T21:53:50.379Z,1567806830.379 [Rowe_600](INFO): Inst Velocity X:-309.000000
2019-09-06T21:53:50.379Z,1567806830.379 [Rowe_600](INFO): Inst Velocity Y:13.000000
2019-09-06T21:53:51.588Z,1567806831.588 [Rowe_600](INFO): Inst Velocity X:-273.000000
2019-09-06T21:53:51.608Z,1567806831.608 [Rowe_600](INFO): Inst Velocity Y:-11.000000
2019-09-06T21:53:52.385Z,1567806832.385 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:53:52.406Z,1567806832.406 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:52.409Z,1567806832.409 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:52.409Z,1567806832.409 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:53.607Z,1567806833.607 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:53.610Z,1567806833.610 [Rowe_600](INFO): Inst Velocity X:-82.000000
2019-09-06T21:53:53.610Z,1567806833.610 [Rowe_600](INFO): Inst Velocity Y:38.000000
2019-09-06T21:53:54.455Z,1567806834.455 [Rowe_600](INFO): Inst Velocity X:-74.000000
2019-09-06T21:53:54.456Z,1567806834.456 [Rowe_600](INFO): Inst Velocity Y:19.000000
2019-09-06T21:53:54.925Z,1567806834.925 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.011904
CHAN A1 (24V): 0.035750
CHAN A2 (12V): -0.007598
CHAN A3 (5V): nan
CHAN B0 (3.3V): 0.011855
CHAN B1 (3.15aV): -0.000317
CHAN B2 (3.15bV): 0.000337
CHAN B3 (GND): 0.001959
OPEN: 0.007172
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-06T21:53:55.213Z,1567806835.213 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:53:55.668Z,1567806835.668 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:55.671Z,1567806835.671 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:53:55.671Z,1567806835.671 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:53:56.436Z,1567806836.436 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:53:56.438Z,1567806836.438 [Rowe_600](INFO): Inst Velocity X:-302.000000
2019-09-06T21:53:56.439Z,1567806836.439 [Rowe_600](INFO): Inst Velocity Y:12.000000
2019-09-06T21:53:57.648Z,1567806837.648 [Rowe_600](INFO): Inst Velocity X:-332.000000
2019-09-06T21:53:57.648Z,1567806837.648 [Rowe_600](INFO): Inst Velocity Y:-10.000000
2019-09-06T21:53:58.473Z,1567806838.473 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:53:58.492Z,1567806838.492 [Rowe_600](INFO): Inst Velocity X:-277.000000
2019-09-06T21:53:58.512Z,1567806838.512 [Rowe_600](INFO): Inst Velocity Y:-2.000000
2019-09-06T21:53:59.281Z,1567806839.281 [Rowe_600](INFO): Inst Velocity X:-172.000000
2019-09-06T21:53:59.281Z,1567806839.281 [Rowe_600](INFO): Inst Velocity Y:-11.000000
2019-09-06T21:53:59.347Z,1567806839.347 [Rowe_600](ERROR): DVL uart error: serial timeout
2019-09-06T21:53:59.347Z,1567806839.347 [Rowe_600] Communications Fault, FailCount= 1
2019-09-06T21:53:59.347Z,1567806839.347 [Rowe_600](ERROR): Communications Fault
2019-09-06T21:53:59.347Z,1567806839.347 [Rowe_600](ERROR): Failed to parse:
2019-09-06T21:53:59.818Z,1567806839.818 [CBIT](ERROR): Communications Fault in component: Rowe_600
2019-09-06T21:53:59.973Z,1567806839.973 [Rowe_600](INFO): Powering down
2019-09-06T21:54:00.798Z,1567806840.798 [CBIT](INFO): Clearing failed state for component Rowe_600
2019-09-06T21:54:00.798Z,1567806840.798 [Rowe_600] No Fault, FailCount= 1
2019-09-06T21:54:01.453Z,1567806841.453 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:04.281Z,1567806844.281 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:06.712Z,1567806846.712 [Rowe_600](ERROR): Failed to parse:Copyright (c) 2009-2019 Rowe Technologies Inc. All rights reserved.
2019-09-06T21:54:07.109Z,1567806847.109 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:07.563Z,1567806847.563 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error
2019-09-06T21:54:07.563Z,1567806847.563 [Rowe_600](ERROR): Failed to parse:
:TS,19090614521000,35.0,+15.0,   0.0,1498.0,  C
2019-09-06T21:54:08.330Z,1567806848.330 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error
2019-09-06T21:54:08.331Z,1567806848.331 [Rowe_600](ERROR): Failed to parse:
:TS,19090614521100,35.0,+15.0,   0.0,1498.0,  C
2019-09-06T21:54:09.553Z,1567806849.553 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error
2019-09-06T21:54:09.553Z,1567806849.553 [Rowe_600](ERROR): Failed to parse:
:TS,19090614521200,35.0,+15.0,   0.0,1498.0,  C
2019-09-06T21:54:10.341Z,1567806850.341 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:10.366Z,1567806850.366 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:54:10.368Z,1567806850.368 [Rowe_600](INFO): Inst Velocity X:-53.000000
2019-09-06T21:54:10.368Z,1567806850.368 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T21:54:11.566Z,1567806851.566 [Rowe_600](INFO): Inst Velocity X:46.000000
2019-09-06T21:54:11.566Z,1567806851.566 [Rowe_600](INFO): Inst Velocity Y:16.000000
2019-09-06T21:54:12.372Z,1567806852.372 [Rowe_600](INFO): Inst Velocity X:196.000000
2019-09-06T21:54:12.373Z,1567806852.373 [Rowe_600](INFO): Inst Velocity Y:122.000000
2019-09-06T21:54:13.173Z,1567806853.173 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:13.605Z,1567806853.605 [Rowe_600](INFO): Inst Velocity X:102.000000
2019-09-06T21:54:13.606Z,1567806853.606 [Rowe_600](INFO): Inst Velocity Y:52.000000
2019-09-06T21:54:14.390Z,1567806854.390 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:54:14.391Z,1567806854.391 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:54:15.604Z,1567806855.604 [Rowe_600](INFO): Inst Velocity X:-57.000000
2019-09-06T21:54:15.605Z,1567806855.605 [Rowe_600](INFO): Inst Velocity Y:18.000000
2019-09-06T21:54:16.405Z,1567806856.405 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:16.425Z,1567806856.425 [Rowe_600](INFO): Inst Velocity X:-64.000000
2019-09-06T21:54:16.425Z,1567806856.425 [Rowe_600](INFO): Inst Velocity Y:8.000000
2019-09-06T21:54:17.626Z,1567806857.626 [Rowe_600](INFO): Inst Velocity X:-17.000000
2019-09-06T21:54:17.626Z,1567806857.626 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T21:54:18.433Z,1567806858.433 [Rowe_600](INFO): Inst Velocity X:57.000000
2019-09-06T21:54:18.433Z,1567806858.433 [Rowe_600](INFO): Inst Velocity Y:-6.000000
2019-09-06T21:54:19.233Z,1567806859.233 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:19.672Z,1567806859.672 [Rowe_600](INFO): Inst Velocity X:74.000000
2019-09-06T21:54:19.672Z,1567806859.672 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T21:54:20.450Z,1567806860.450 [Rowe_600](INFO): Inst Velocity X:111.000000
2019-09-06T21:54:20.450Z,1567806860.450 [Rowe_600](INFO): Inst Velocity Y:64.000000
2019-09-06T21:54:21.271Z,1567806861.271 [Rowe_600](ERROR): Failed to parse:
:BI,    +0,   +22,   -1,A
2019-09-06T21:54:22.465Z,1567806862.465 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:22.484Z,1567806862.484 [Rowe_600](INFO): Inst Velocity X:-67.000000
2019-09-06T21:54:22.484Z,1567806862.484 [Rowe_600](INFO): Inst Velocity Y:-5.000000
2019-09-06T21:54:23.302Z,1567806863.302 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:54:23.305Z,1567806863.305 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:54:23.305Z,1567806863.305 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:54:24.494Z,1567806864.494 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:54:24.497Z,1567806864.497 [Rowe_600](INFO): Inst Velocity X:59.000000
2019-09-06T21:54:24.497Z,1567806864.497 [Rowe_600](INFO): Inst Velocity Y:59.000000
2019-09-06T21:54:25.289Z,1567806865.289 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:25.308Z,1567806865.308 [Rowe_600](INFO): Inst Velocity X:55.000000
2019-09-06T21:54:25.308Z,1567806865.308 [Rowe_600](INFO): Inst Velocity Y:13.000000
2019-09-06T21:54:26.610Z,1567806866.610 [Rowe_600](INFO): Inst Velocity X:55.000000
2019-09-06T21:54:26.611Z,1567806866.611 [Rowe_600](INFO): Inst Velocity Y:10.000000
2019-09-06T21:54:27.414Z,1567806867.414 [Rowe_600](INFO): Inst Velocity X:33.000000
2019-09-06T21:54:27.415Z,1567806867.415 [Rowe_600](INFO): Inst Velocity Y:33.000000
2019-09-06T21:54:28.617Z,1567806868.617 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:28.637Z,1567806868.637 [Rowe_600](INFO): Inst Velocity X:-101.000000
2019-09-06T21:54:28.637Z,1567806868.637 [Rowe_600](INFO): Inst Velocity Y:-60.000000
2019-09-06T21:54:29.444Z,1567806869.444 [Rowe_600](INFO): Inst Velocity X:-88.000000
2019-09-06T21:54:29.444Z,1567806869.444 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T21:54:30.649Z,1567806870.649 [Rowe_600](INFO): Inst Velocity X:-17.000000
2019-09-06T21:54:30.649Z,1567806870.649 [Rowe_600](INFO): Inst Velocity Y:-8.000000
2019-09-06T21:54:31.441Z,1567806871.441 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:31.458Z,1567806871.458 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:54:31.461Z,1567806871.461 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T21:54:31.462Z,1567806871.462 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T21:54:32.275Z,1567806872.275 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:54:32.277Z,1567806872.277 [Rowe_600](INFO): Inst Velocity X:29.000000
2019-09-06T21:54:32.277Z,1567806872.277 [Rowe_600](INFO): Inst Velocity Y:-5.000000
2019-09-06T21:54:32.279Z,1567806872.279 [Rowe_600](ERROR): only read 0 of 1 data item for altitude
2019-09-06T21:54:32.279Z,1567806872.279 [Rowe_600](ERROR): Failed to parse:
:BD,  21,            -0.67,  2.00
2019-09-06T21:54:33.494Z,1567806873.494 [Rowe_600](INFO): Inst Velocity X:32.000000
2019-09-06T21:54:33.494Z,1567806873.494 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T21:54:34.302Z,1567806874.302 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T21:54:34.302Z,1567806874.302 [Rowe_600](INFO): Inst Velocity Y:10.000000
2019-09-06T21:54:34.701Z,1567806874.701 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:35.529Z,1567806875.529 [Rowe_600](INFO): Inst Velocity X:-45.000000
2019-09-06T21:54:35.529Z,1567806875.529 [Rowe_600](INFO): Inst Velocity Y:-13.000000
2019-09-06T21:54:36.326Z,1567806876.326 [Rowe_600](INFO): Inst Velocity X:-31.000000
2019-09-06T21:54:36.327Z,1567806876.327 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T21:54:37.529Z,1567806877.529 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:54:37.548Z,1567806877.548 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T21:54:37.549Z,1567806877.549 [Rowe_600](INFO): Inst Velocity Y:14.000000
2019-09-06T21:54:37.565Z,1567806877.565 [SBIT](IMPORTANT): SBIT PASSED
2019-09-06T21:55:18.500Z,1567806918.500 [Rowe_600](ERROR): No DVL communication! Re-initializing
2019-09-06T21:55:18.500Z,1567806918.500 [Rowe_600] Communications Fault, FailCount= 1
2019-09-06T21:55:18.500Z,1567806918.500 [Rowe_600](ERROR): Communications Fault
2019-09-06T21:55:18.514Z,1567806918.514 [MissionManager](IMPORTANT): Started mission Startup
2019-09-06T21:55:18.514Z,1567806918.514 [Startup] Running Loop=1
2019-09-06T21:55:18.514Z,1567806918.514 [Startup](DEBUG): Aggregate::initialize Startup
2019-09-06T21:55:18.514Z,1567806918.514 [Startup:A.GoToSurface] Running Loop=1
2019-09-06T21:55:18.514Z,1567806918.514 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-06T21:55:18.515Z,1567806918.515 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-06T21:55:18.515Z,1567806918.515 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-06T21:55:18.516Z,1567806918.516 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-06T21:55:18.516Z,1567806918.516 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-06T21:55:18.521Z,1567806918.521 [CommandLine](IMPORTANT): got command configSet list
2019-09-06T21:55:18.521Z,1567806918.521 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-09-06T21:55:18.522Z,1567806918.522 [CommandLine](IMPORTANT): Micromodem.loadAtStartup=0 bool;

2019-09-06T21:55:18.522Z,1567806918.522 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter;

2019-09-06T21:55:18.524Z,1567806918.524 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-06T21:55:18.529Z,1567806918.529 [Startup:StartupSatComms] Running Loop=1
2019-09-06T21:55:18.530Z,1567806918.530 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-09-06T21:55:18.530Z,1567806918.530 [Startup:StartupSatComms:A] Running Loop=1
2019-09-06T21:55:18.546Z,1567806918.546 [CBIT](ERROR): Communications Fault in component: Rowe_600
2019-09-06T21:55:18.949Z,1567806918.949 [Rowe_600](INFO): Powering down
2019-09-06T21:55:18.951Z,1567806918.951 [BPC1](ERROR): BPC1A buffer size (CBA bytes) exceeded the max device response limit.
2019-09-06T21:55:18.951Z,1567806918.951 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-09-06T21:55:18.958Z,1567806918.958 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-09-06T21:55:19.685Z,1567806919.685 [BPC1](ERROR): BPC1B buffer size (CBA bytes) exceeded the max device response limit.
2019-09-06T21:55:19.686Z,1567806919.686 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-06T21:55:19.707Z,1567806919.707 [CBIT](INFO): Clearing failed state for component Rowe_600
2019-09-06T21:55:19.707Z,1567806919.707 [Rowe_600] No Fault, FailCount= 1
2019-09-06T21:55:20.477Z,1567806920.477 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:55:20.872Z,1567806920.872 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215322.00,A,3648.15334,N,12147.27799,W,0.058,0.00,060919,,,A*79
2019-09-06T21:55:20.875Z,1567806920.875 [NAL9602](INFO): GPS fix at 20190906T215322: (36.802556, -121.787966)
2019-09-06T21:55:20.920Z,1567806920.920 [Startup:StartupSatComms:A] Stopped
2019-09-06T21:55:20.920Z,1567806920.920 [Startup:StartupSatComms:B] Running Loop=1
2019-09-06T21:55:21.323Z,1567806921.323 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-09-06T21:55:25.752Z,1567806925.752 [Rowe_600](ERROR): Failed to parse:Copyright (c) 2009-2019 Rowe Technologies Inc. All rights reserved.
2019-09-06T21:55:27.367Z,1567806927.367 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error
2019-09-06T21:55:27.367Z,1567806927.367 [Rowe_600](ERROR): Failed to parse:
:TS,19090614533000,35.0,+15.0,   0.0,1498.0,  C
2019-09-06T21:55:28.590Z,1567806928.590 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error
2019-09-06T21:55:28.590Z,1567806928.590 [Rowe_600](ERROR): Failed to parse:
:TS,19090614533100,35.0,+15.0,   0.0,1498.0,  C
2019-09-06T21:55:29.384Z,1567806929.384 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T21:55:29.387Z,1567806929.387 [Rowe_600](INFO): Inst Velocity X:-38.000000
2019-09-06T21:55:29.387Z,1567806929.387 [Rowe_600](INFO): Inst Velocity Y:-49.000000
2019-09-06T21:55:30.598Z,1567806930.598 [Rowe_600](INFO): Inst Velocity X:-9.000000
2019-09-06T21:55:30.598Z,1567806930.598 [Rowe_600](INFO): Inst Velocity Y:-10.000000
2019-09-06T21:55:30.737Z,1567806930.737 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190906T214436/Courier0004.lzma
2019-09-06T21:55:31.542Z,1567806931.542 [DataOverHttps](INFO): Moved sent file to Logs/20190906T214436/Courier0004.lzma.bak
2019-09-06T21:55:31.543Z,1567806931.543 [DataOverHttps](INFO): SBD MOMSN=11748390
2019-09-06T21:55:36.266Z,1567806936.266 [Rowe_600](ERROR): Failed to parse:
:BS,    -4,    -3,    +0,A
2019-09-06T21:55:37.463Z,1567806937.463 [Rowe_600](ERROR): Failed to parse:
:SA, +79.47, -24.45,241.37
2019-09-06T21:55:38.302Z,1567806938.302 [Rowe_600](INFO): Inst Velocity X:13.000000
2019-09-06T21:55:38.302Z,1567806938.302 [Rowe_600](INFO): Inst Velocity Y:10.000000
2019-09-06T21:55:39.491Z,1567806939.491 [Rowe_600](INFO): Inst Velocity X:12.000000
2019-09-06T21:55:39.491Z,1567806939.491 [Rowe_600](INFO): Inst Velocity Y:14.000000
2019-09-06T21:55:40.308Z,1567806940.308 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:55:40.308Z,1567806940.308 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:55:41.513Z,1567806941.513 [Rowe_600](INFO): Inst Velocity X:-14.000000
2019-09-06T21:55:41.514Z,1567806941.514 [Rowe_600](INFO): Inst Velocity Y:-4.000000
2019-09-06T21:55:42.313Z,1567806942.313 [Rowe_600](INFO): Inst Velocity X:-17.000000
2019-09-06T21:55:42.314Z,1567806942.314 [Rowe_600](INFO): Inst Velocity Y:-12.000000
2019-09-06T21:55:43.536Z,1567806943.536 [Rowe_600](INFO): Inst Velocity X:-6.000000
2019-09-06T21:55:43.536Z,1567806943.536 [Rowe_600](INFO): Inst Velocity Y:-7.000000
2019-09-06T21:55:44.333Z,1567806944.333 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T21:55:44.334Z,1567806944.334 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T21:55:45.545Z,1567806945.545 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T21:55:45.545Z,1567806945.545 [Rowe_600](INFO): Inst Velocity Y:8.000000
2019-09-06T21:55:46.095Z,1567806946.095 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20190906T215233/Courier0000.lzma
2019-09-06T21:55:46.357Z,1567806946.357 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T21:55:46.358Z,1567806946.358 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T21:55:46.898Z,1567806946.898 [DataOverHttps](INFO): Moved sent file to Logs/20190906T215233/Courier0000.lzma.bak
2019-09-06T21:55:46.898Z,1567806946.898 [DataOverHttps](INFO): SBD MOMSN=11748398
2019-09-06T21:55:47.567Z,1567806947.567 [Rowe_600](INFO): Inst Velocity X:-14.000000
2019-09-06T21:55:47.567Z,1567806947.567 [Rowe_600](INFO): Inst Velocity Y:-8.000000
2019-09-06T21:55:48.374Z,1567806948.374 [Rowe_600](INFO): Inst Velocity X:-11.000000
2019-09-06T21:55:48.374Z,1567806948.374 [Rowe_600](INFO): Inst Velocity Y:-11.000000
2019-09-06T21:55:49.601Z,1567806949.601 [Rowe_600](INFO): Inst Velocity X:-12.000000
2019-09-06T21:55:49.602Z,1567806949.602 [Rowe_600](INFO): Inst Velocity Y:-10.000000
2019-09-06T21:55:50.400Z,1567806950.400 [Rowe_600](INFO): Inst Velocity X:-11.000000
2019-09-06T21:55:50.401Z,1567806950.401 [Rowe_600](INFO): Inst Velocity Y:-13.000000
2019-09-06T21:55:51.609Z,1567806951.609 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T21:55:51.609Z,1567806951.609 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:55:52.416Z,1567806952.416 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T21:55:52.416Z,1567806952.416 [Rowe_600](INFO): Inst Velocity Y:12.000000
2019-09-06T21:55:53.213Z,1567806953.213 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-06T21:55:53.642Z,1567806953.642 [Rowe_600](INFO): Inst Velocity X:-11.000000
2019-09-06T21:55:53.642Z,1567806953.642 [Rowe_600](INFO): Inst Velocity Y:-7.000000
2019-09-06T21:55:54.438Z,1567806954.438 [Rowe_600](INFO): Inst Velocity X:-14.000000
2019-09-06T21:55:54.439Z,1567806954.439 [Rowe_600](INFO): Inst Velocity Y:-4.000000
2019-09-06T21:55:55.646Z,1567806955.646 [Rowe_600](INFO): Inst Velocity X:-9.000000
2019-09-06T21:55:55.646Z,1567806955.646 [Rowe_600](INFO): Inst Velocity Y:-10.000000
2019-09-06T21:55:56.457Z,1567806956.457 [Rowe_600](INFO): Inst Velocity X:-6.000000
2019-09-06T21:55:56.458Z,1567806956.458 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:55:57.284Z,1567806957.284 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error
2019-09-06T21:55:57.284Z,1567806957.284 [Rowe_600](ERROR): Failed to parse:
:TS,19090614540000,35.0,+15.0,   0.0, 0
2019-09-06T21:55:58.474Z,1567806958.474 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T21:55:58.474Z,1567806958.474 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T21:55:59.330Z,1567806959.330 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:55:59.330Z,1567806959.330 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:56:00.507Z,1567806960.507 [Rowe_600](INFO): Inst Velocity X:-15.000000
2019-09-06T21:56:00.508Z,1567806960.508 [Rowe_600](INFO): Inst Velocity Y:-17.000000
2019-09-06T21:56:01.306Z,1567806961.306 [Rowe_600](INFO): Inst Velocity X:-12.000000
2019-09-06T21:56:01.306Z,1567806961.306 [Rowe_600](INFO): Inst Velocity Y:-6.000000
2019-09-06T21:56:02.500Z,1567806962.500 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20190906T214436/Express0005.lzma
2019-09-06T21:56:02.519Z,1567806962.519 [Rowe_600](INFO): Inst Velocity X:-7.000000
2019-09-06T21:56:02.519Z,1567806962.519 [Rowe_600](INFO): Inst Velocity Y:-6.000000
2019-09-06T21:56:03.307Z,1567806963.307 [DataOverHttps](INFO): Moved sent file to Logs/20190906T214436/Express0005.lzma.bak
2019-09-06T21:56:03.307Z,1567806963.307 [DataOverHttps](INFO): SBD MOMSN=11748402
2019-09-06T21:56:03.342Z,1567806963.342 [Rowe_600](INFO): Inst Velocity X:-10.000000
2019-09-06T21:56:03.342Z,1567806963.342 [Rowe_600](INFO): Inst Velocity Y:-15.000000
2019-09-06T21:56:04.567Z,1567806964.567 [Rowe_600](INFO): Inst Velocity X:-6.000000
2019-09-06T21:56:04.567Z,1567806964.567 [Rowe_600](INFO): Inst Velocity Y:-7.000000
2019-09-06T21:56:05.342Z,1567806965.342 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T21:56:05.343Z,1567806965.343 [Rowe_600](INFO): Inst Velocity Y:-5.000000
2019-09-06T21:56:06.567Z,1567806966.567 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:56:06.567Z,1567806966.567 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T21:56:07.361Z,1567806967.361 [Rowe_600](INFO): Inst Velocity X:-12.000000
2019-09-06T21:56:07.361Z,1567806967.361 [Rowe_600](INFO): Inst Velocity Y:-2.000000
2019-09-06T21:56:08.575Z,1567806968.575 [Rowe_600](INFO): Inst Velocity X:-7.000000
2019-09-06T21:56:08.575Z,1567806968.575 [Rowe_600](INFO): Inst Velocity Y:-8.000000
2019-09-06T21:56:09.399Z,1567806969.399 [Rowe_600](INFO): Inst Velocity X:-6.000000
2019-09-06T21:56:09.399Z,1567806969.399 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:56:10.603Z,1567806970.603 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T21:56:10.603Z,1567806970.603 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T21:56:11.401Z,1567806971.401 [Rowe_600](INFO): Inst Velocity X:-6.000000
2019-09-06T21:56:11.401Z,1567806971.401 [Rowe_600](INFO): Inst Velocity Y:-7.000000
2019-09-06T21:56:12.622Z,1567806972.622 [Rowe_600](INFO): Inst Velocity X:-7.000000
2019-09-06T21:56:12.623Z,1567806972.623 [Rowe_600](INFO): Inst Velocity Y:-5.000000
2019-09-06T21:56:13.422Z,1567806973.422 [Rowe_600](INFO): Inst Velocity X:-13.000000
2019-09-06T21:56:13.423Z,1567806973.423 [Rowe_600](INFO): Inst Velocity Y:-17.000000
2019-09-06T21:56:14.637Z,1567806974.637 [Rowe_600](INFO): Inst Velocity X:-11.000000
2019-09-06T21:56:14.637Z,1567806974.637 [Rowe_600](INFO): Inst Velocity Y:-8.000000
2019-09-06T21:56:15.448Z,1567806975.448 [Rowe_600](INFO): Inst Velocity X:-8.000000
2019-09-06T21:56:15.448Z,1567806975.448 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T21:56:16.301Z,1567806976.301 [Rowe_600](INFO): Inst Velocity X:-8.000000
2019-09-06T21:56:16.301Z,1567806976.301 [Rowe_600](INFO): Inst Velocity Y:-14.000000
2019-09-06T21:56:17.471Z,1567806977.471 [Rowe_600](INFO): Inst Velocity X:-4.000000
2019-09-06T21:56:17.471Z,1567806977.471 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T21:56:18.282Z,1567806978.282 [Rowe_600](INFO): Inst Velocity X:-6.000000
2019-09-06T21:56:18.282Z,1567806978.282 [Rowe_600](INFO): Inst Velocity Y:-4.000000
2019-09-06T21:56:18.285Z,1567806978.285 [Rowe_600](ERROR): only read 0 of 1 data item for altitude
2019-09-06T21:56:18.285Z,1567806978.285 [Rowe_600](ERROR): Failed to parse:
:BD,       -0.17,            10.69,  1.00
2019-09-06T21:56:19.489Z,1567806979.489 [Rowe_600](INFO): Inst Velocity X:-3.000000
2019-09-06T21:56:19.489Z,1567806979.489 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T21:56:20.299Z,1567806980.299 [Rowe_600](INFO): Inst Velocity X:-12.000000
2019-09-06T21:56:20.299Z,1567806980.299 [Rowe_600](INFO): Inst Velocity Y:-7.000000
2019-09-06T21:56:20.326Z,1567806980.326 [DataOverHttps](INFO): Sending 786 bytes from file Logs/20190906T215233/Express0001.lzma
2019-09-06T21:56:21.104Z,1567806981.104 [Startup:StartupSatComms:B](INFO): Timed out from 2019-09-06T21:55:20.9Z
2019-09-06T21:56:21.104Z,1567806981.104 [Startup:StartupSatComms:B] Stopped
2019-09-06T21:56:21.104Z,1567806981.104 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-09-06T21:56:21.104Z,1567806981.104 [Startup:StartupSatComms] Stopped
2019-09-06T21:56:21.105Z,1567806981.105 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-09-06T21:56:21.105Z,1567806981.105 [Startup](INFO): Completed Startup
2019-09-06T21:56:21.105Z,1567806981.105 [MissionManager](INFO): Startup is completed.
2019-09-06T21:56:21.106Z,1567806981.106 [MissionManager](INFO): Uninitializing Mission Startup
2019-09-06T21:56:21.106Z,1567806981.106 [Startup] Stopped
2019-09-06T21:56:21.106Z,1567806981.106 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-09-06T21:56:21.106Z,1567806981.106 [Startup:A.GoToSurface] Stopped
2019-09-06T21:56:21.106Z,1567806981.106 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-06T21:56:21.130Z,1567806981.130 [DataOverHttps](INFO): Moved sent file to Logs/20190906T215233/Express0001.lzma.bak
2019-09-06T21:56:21.130Z,1567806981.130 [DataOverHttps](INFO): SBD MOMSN=11748406
2019-09-06T21:56:21.503Z,1567806981.503 [Rowe_600](INFO): Inst Velocity X:-8.000000
2019-09-06T21:56:21.503Z,1567806981.503 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T21:56:21.516Z,1567806981.516 [MissionManager](IMPORTANT): Started mission Default
2019-09-06T21:56:21.516Z,1567806981.516 [Default] Running Loop=1
2019-09-06T21:56:21.517Z,1567806981.517 [Default](DEBUG): Aggregate::initialize Default
2019-09-06T21:56:21.517Z,1567806981.517 [Default:B.GoToSurface] Running Loop=1
2019-09-06T21:56:21.517Z,1567806981.517 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-06T21:56:21.517Z,1567806981.517 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-06T21:56:21.517Z,1567806981.517 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-06T21:56:21.518Z,1567806981.518 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-06T21:56:21.518Z,1567806981.518 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-06T21:56:21.518Z,1567806981.518 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-06T21:56:21.519Z,1567806981.519 [Default:A.Wait] Running Loop=1
2019-09-06T21:56:21.519Z,1567806981.519 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-09-06T21:56:22.317Z,1567806982.317 [Rowe_600](INFO): Inst Velocity X:-10.000000
2019-09-06T21:56:22.318Z,1567806982.318 [Rowe_600](INFO): Inst Velocity Y:-13.000000
2019-09-06T21:56:23.522Z,1567806983.522 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T21:56:23.522Z,1567806983.522 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T21:56:24.329Z,1567806984.329 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:56:24.330Z,1567806984.330 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:56:25.542Z,1567806985.542 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T21:56:25.542Z,1567806985.542 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T21:56:26.362Z,1567806986.362 [Rowe_600](INFO): Inst Velocity X:-13.000000
2019-09-06T21:56:26.362Z,1567806986.362 [Rowe_600](INFO): Inst Velocity Y:-15.000000
2019-09-06T21:56:27.566Z,1567806987.566 [Rowe_600](INFO): Inst Velocity X:-12.000000
2019-09-06T21:56:27.566Z,1567806987.566 [Rowe_600](INFO): Inst Velocity Y:-3.000000
2019-09-06T21:56:28.373Z,1567806988.373 [Rowe_600](INFO): Inst Velocity X:-12.000000
2019-09-06T21:56:28.373Z,1567806988.373 [Rowe_600](INFO): Inst Velocity Y:-10.000000
2019-09-06T21:56:29.590Z,1567806989.590 [Rowe_600](INFO): Inst Velocity X:-5.000000
2019-09-06T21:56:29.590Z,1567806989.590 [Rowe_600](INFO): Inst Velocity Y:-2.000000
2019-09-06T21:56:30.393Z,1567806990.393 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T21:56:30.393Z,1567806990.393 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T21:56:31.606Z,1567806991.606 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:56:31.607Z,1567806991.607 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T21:56:32.414Z,1567806992.414 [Rowe_600](INFO): Inst Velocity X:-7.000000
2019-09-06T21:56:32.414Z,1567806992.414 [Rowe_600](INFO): Inst Velocity Y:-4.000000
2019-09-06T21:56:33.622Z,1567806993.622 [Rowe_600](INFO): Inst Velocity X:-12.000000
2019-09-06T21:56:33.622Z,1567806993.622 [Rowe_600](INFO): Inst Velocity Y:-14.000000
2019-09-06T21:56:34.433Z,1567806994.433 [Rowe_600](INFO): Inst Velocity X:-12.000000
2019-09-06T21:56:34.434Z,1567806994.434 [Rowe_600](INFO): Inst Velocity Y:-11.000000
2019-09-06T21:56:34.841Z,1567806994.841 [Default:A.Wait](INFO): Done Waiting.
2019-09-06T21:56:34.841Z,1567806994.841 [Default:A.Wait] Stopped
2019-09-06T21:56:34.841Z,1567806994.841 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-09-06T21:56:35.241Z,1567806995.241 [Default:CheckIn] Running Loop=1
2019-09-06T21:56:35.241Z,1567806995.241 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-06T21:56:35.241Z,1567806995.241 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-06T21:56:35.652Z,1567806995.652 [Rowe_600](INFO): Inst Velocity X:-11.000000
2019-09-06T21:56:35.653Z,1567806995.653 [Rowe_600](INFO): Inst Velocity Y:-16.000000
2019-09-06T21:56:35.666Z,1567806995.666 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-09-06T21:56:36.450Z,1567806996.450 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T21:56:36.450Z,1567806996.450 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T21:56:36.849Z,1567806996.849 [NAL9602](DEBUG): Fix Requested
2019-09-06T21:56:37.238Z,1567806997.238 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215438.00,A,3648.17213,N,12147.27654,W,1.575,0.00,060919,,,A*78
2019-09-06T21:56:37.240Z,1567806997.240 [NAL9602](INFO): GPS fix at 20190906T215438: (36.802869, -121.787942)
2019-09-06T21:56:37.277Z,1567806997.277 [Default:CheckIn:Read_GPS] Stopped
2019-09-06T21:56:37.277Z,1567806997.277 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-06T21:56:37.755Z,1567806997.755 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-09-06T21:56:38.606Z,1567806998.606 [Rowe_600](ERROR): only read 3 of 4 data items
2019-09-06T21:56:38.607Z,1567806998.607 [Rowe_600](ERROR): Failed to parse:
:RA,  0.00,   0.00,   9.91,  10.54
2019-09-06T21:56:39.429Z,1567806999.429 [Rowe_600](INFO): Inst Velocity X:-13.000000
2019-09-06T21:56:39.429Z,1567806999.429 [Rowe_600](INFO): Inst Velocity Y:-15.000000
2019-09-06T21:56:40.656Z,1567807000.656 [Rowe_600](INFO): Inst Velocity X:-14.000000
2019-09-06T21:56:40.656Z,1567807000.656 [Rowe_600](INFO): Inst Velocity Y:-15.000000
2019-09-06T21:56:41.426Z,1567807001.426 [Rowe_600](INFO): Inst Velocity X:-13.000000
2019-09-06T21:56:41.426Z,1567807001.426 [Rowe_600](INFO): Inst Velocity Y:-14.000000
2019-09-06T21:56:42.638Z,1567807002.638 [Rowe_600](INFO): Inst Velocity X:-6.000000
2019-09-06T21:56:42.638Z,1567807002.638 [Rowe_600](INFO): Inst Velocity Y:-6.000000
2019-09-06T21:56:43.469Z,1567807003.469 [Rowe_600](INFO): Inst Velocity X:1.000000
2019-09-06T21:56:43.470Z,1567807003.470 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T21:56:44.277Z,1567807004.277 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T21:56:44.277Z,1567807004.277 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T21:56:45.300Z,1567807005.300 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190906T215233/Courier0004.lzma
2019-09-06T21:56:45.466Z,1567807005.466 [Rowe_600](INFO): Inst Velocity X:-10.000000
2019-09-06T21:56:45.466Z,1567807005.466 [Rowe_600](INFO): Inst Velocity Y:-10.000000
2019-09-06T21:56:46.106Z,1567807006.106 [DataOverHttps](INFO): Moved sent file to Logs/20190906T215233/Courier0004.lzma.bak
2019-09-06T21:56:46.106Z,1567807006.106 [DataOverHttps](INFO): SBD MOMSN=11748422
2019-09-06T21:56:46.300Z,1567807006.300 [Rowe_600](INFO): Inst Velocity X:-14.000000
2019-09-06T21:56:46.300Z,1567807006.300 [Rowe_600](INFO): Inst Velocity Y:-16.000000
2019-09-06T21:56:47.486Z,1567807007.486 [Rowe_600](INFO): Inst Velocity X:-14.000000
2019-09-06T21:56:47.486Z,1567807007.486 [Rowe_600](INFO): Inst Velocity Y:-18.000000
2019-09-06T21:56:48.314Z,1567807008.314 [Rowe_600](INFO): Inst Velocity X:-10.000000
2019-09-06T21:56:48.314Z,1567807008.314 [Rowe_600](INFO): Inst Velocity Y:-16.000000
2019-09-06T21:56:49.523Z,1567807009.523 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T21:56:49.523Z,1567807009.523 [Rowe_600](INFO): Inst Velocity Y:-2.000000
2019-09-06T21:56:50.314Z,1567807010.314 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T21:56:50.314Z,1567807010.314 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T21:56:51.526Z,1567807011.526 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T21:56:51.527Z,1567807011.527 [Rowe_600](INFO): Inst Velocity Y:-2.000000
2019-09-06T21:56:52.334Z,1567807012.334 [Rowe_600](INFO): Inst Velocity X:-13.000000
2019-09-06T21:56:52.334Z,1567807012.334 [Rowe_600](INFO): Inst Velocity Y:-16.000000
2019-09-06T21:56:53.566Z,1567807013.566 [Rowe_600](INFO): Inst Velocity X:-13.000000
2019-09-06T21:56:53.566Z,1567807013.566 [Rowe_600](INFO): Inst Velocity Y:-16.000000
2019-09-06T21:56:54.365Z,1567807014.365 [Rowe_600](INFO): Inst Velocity X:-11.000000
2019-09-06T21:56:54.366Z,1567807014.366 [Rowe_600](INFO): Inst Velocity Y:-17.000000
2019-09-06T21:56:55.566Z,1567807015.566 [Rowe_600](INFO): Inst Velocity X:-7.000000
2019-09-06T21:56:55.566Z,1567807015.566 [Rowe_600](INFO): Inst Velocity Y:-3.000000
2019-09-06T21:56:56.395Z,1567807016.395 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T21:56:56.395Z,1567807016.395 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T21:56:56.677Z,1567807016.677 [CommandLine](IMPORTANT): got command quit
2019-09-06T21:56:57.591Z,1567807017.591 [Rowe_600](INFO): Inst Velocity X:-3.000000
2019-09-06T21:56:57.591Z,1567807017.591 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T21:56:57.681Z,1567807017.681 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-09-06T21:56:57.681Z,1567807017.681 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T21:56:57.681Z,1567807017.681 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:56:57.817Z,1567807017.817 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-09-06T21:56:57.817Z,1567807017.817 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:56:57.818Z,1567807017.818 [CommandLine](INFO): Join timeout helper Thread ID is 9430
2019-09-06T21:56:57.825Z,1567807017.825 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-09-06T21:56:57.825Z,1567807017.825 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:56:57.825Z,1567807017.825 [NavChartDb](INFO): Join timeout helper Thread ID is 9431
2019-09-06T21:56:58.153Z,1567807018.153 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T21:56:58.153Z,1567807018.153 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:56:58.165Z,1567807018.165 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-09-06T21:56:58.165Z,1567807018.165 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:56:58.165Z,1567807018.165 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9432
2019-09-06T21:56:58.277Z,1567807018.277 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T21:56:58.278Z,1567807018.278 [WetLabsBB2FL](INFO): Powering down
2019-09-06T21:56:58.278Z,1567807018.278 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:56:58.293Z,1567807018.293 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-09-06T21:56:58.293Z,1567807018.293 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:56:58.293Z,1567807018.293 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9433
2019-09-06T21:56:58.309Z,1567807018.309 [CTD_Seabird](INFO): Powering down
2019-09-06T21:56:58.321Z,1567807018.321 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T21:56:58.321Z,1567807018.321 [CTD_Seabird](INFO): Powering down
2019-09-06T21:56:58.333Z,1567807018.333 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:56:58.341Z,1567807018.341 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-09-06T21:56:58.341Z,1567807018.341 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:56:58.341Z,1567807018.341 [Radio_Surface](INFO): Join timeout helper Thread ID is 9434
2019-09-06T21:56:58.397Z,1567807018.397 [Radio_Surface](INFO): Powering down
2019-09-06T21:56:58.398Z,1567807018.398 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T21:56:58.398Z,1567807018.398 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:56:58.415Z,1567807018.415 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-09-06T21:56:58.415Z,1567807018.415 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:56:58.416Z,1567807018.416 [DataOverHttps](INFO): Join timeout helper Thread ID is 9435
2019-09-06T21:56:58.445Z,1567807018.445 [Rowe_600](INFO): Inst Velocity X:-9.000000
2019-09-06T21:56:58.445Z,1567807018.445 [Rowe_600](INFO): Inst Velocity Y:-11.000000
2019-09-06T21:56:59.606Z,1567807019.606 [Rowe_600](INFO): Inst Velocity X:-13.000000
2019-09-06T21:56:59.606Z,1567807019.606 [Rowe_600](INFO): Inst Velocity Y:-14.000000
2019-09-06T21:57:00.417Z,1567807020.417 [Rowe_600](INFO): Inst Velocity X:-12.000000
2019-09-06T21:57:00.418Z,1567807020.418 [Rowe_600](INFO): Inst Velocity Y:-15.000000
2019-09-06T21:57:01.135Z,1567807021.135 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T21:57:01.138Z,1567807021.138 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:57:01.153Z,1567807021.153 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-09-06T21:57:01.154Z,1567807021.154 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:57:01.154Z,1567807021.154 [logger](INFO): Join timeout helper Thread ID is 9436
2019-09-06T21:57:01.169Z,1567807021.169 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T21:57:01.169Z,1567807021.169 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:57:01.173Z,1567807021.173 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-09-06T21:57:01.174Z,1567807021.174 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:57:01.174Z,1567807021.174 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-09-06T21:57:01.174Z,1567807021.174 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:57:01.174Z,1567807021.174 [controlThread](INFO): Join timeout helper Thread ID is 9437
2019-09-06T21:57:01.185Z,1567807021.185 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T21:57:01.185Z,1567807021.185 [controlThread](DEBUG): Uninitializing ControlThread
2019-09-06T21:57:01.185Z,1567807021.185 [AHRS_M2](INFO): Powering down
2019-09-06T21:57:01.258Z,1567807021.258 [NAL9602](INFO): Powering down
2019-09-06T21:57:01.329Z,1567807021.329 [Rowe_600](INFO): Powering down
2019-09-06T21:57:01.330Z,1567807021.330 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-09-06T21:57:01.331Z,1567807021.331 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-09-06T21:57:01.332Z,1567807021.332 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-09-06T21:57:01.332Z,1567807021.332 [MissionManager](INFO): Uninitializing Mission Default
2019-09-06T21:57:01.333Z,1567807021.333 [Default] Stopped
2019-09-06T21:57:01.333Z,1567807021.333 [Default](DEBUG): Aggregate::uninitialize Default
2019-09-06T21:57:01.333Z,1567807021.333 [Default:B.GoToSurface] Stopped
2019-09-06T21:57:01.333Z,1567807021.333 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-06T21:57:01.333Z,1567807021.333 [Default:CheckIn] Stopped
2019-09-06T21:57:01.333Z,1567807021.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-06T21:57:01.333Z,1567807021.333 [Default:CheckIn:Read_Iridium] Stopped
2019-09-06T21:57:01.335Z,1567807021.335 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-09-06T21:57:01.336Z,1567807021.336 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-09-06T21:57:01.336Z,1567807021.336 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-09-06T21:57:01.336Z,1567807021.336 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-09-06T21:57:01.337Z,1567807021.337 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-09-06T21:57:01.337Z,1567807021.337 [BuoyancyServo](INFO): Powering down
2019-09-06T21:57:01.349Z,1567807021.349 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-09-06T21:57:01.349Z,1567807021.349 [ElevatorServo](INFO): Powering down
2019-09-06T21:57:01.350Z,1567807021.350 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-09-06T21:57:01.350Z,1567807021.350 [MassServo](INFO): Powering down
2019-09-06T21:57:01.351Z,1567807021.351 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-06T21:57:01.351Z,1567807021.351 [RudderServo](INFO): Powering down
2019-09-06T21:57:01.351Z,1567807021.351 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-09-06T21:57:01.351Z,1567807021.351 [ThrusterServo](INFO): Powering down
2019-09-06T21:57:01.352Z,1567807021.352 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-09-06T21:57:01.353Z,1567807021.353 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-09-06T21:57:01.353Z,1567807021.353 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-09-06T21:57:01.353Z,1567807021.353 [CBIT](DEBUG): Powering off loads.
2019-09-06T21:57:01.365Z,1567807021.365 [CBIT](DEBUG): Disabling WDT.
2019-09-06T21:57:01.377Z,1567807021.377 [CBIT](DEBUG): Opening all GF detection circuits.
2019-09-06T21:57:01.377Z,1567807021.377 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:57:01.464Z,1567807021.464 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:57:01.470Z,1567807021.470 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:57:01.521Z,1567807021.521 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:57:01.524Z,1567807021.524 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:57:01.533Z,1567807021.533 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-06T21:57:01.601Z,1567807021.601 [logger ThreadHandler](INFO): Thread cancelled.