2019-09-23T19:36:57.069Z,1569267417.069 [Supervisor](DEBUG): Initializing supervisor.
2019-09-23T19:36:57.072Z,1569267417.072 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-09-23T19:36:57.073Z,1569267417.073 [SyncHandler](INFO): Protected caller Thread ID is 1093
2019-09-23T19:36:57.073Z,1569267417.073 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-09-23T19:36:57.074Z,1569267417.074 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-09-23T19:36:57.075Z,1569267417.075 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1094
2019-09-23T19:36:57.078Z,1569267417.078 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-09-23T19:36:57.089Z,1569267417.089 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-09-23T19:36:57.090Z,1569267417.090 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-09-23T19:36:57.091Z,1569267417.091 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1095
2019-09-23T19:36:57.092Z,1569267417.092 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-09-23T19:36:57.093Z,1569267417.093 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-09-23T19:36:57.093Z,1569267417.093 [logger ThreadHandler](INFO): Protected caller Thread ID is 1096
2019-09-23T19:36:57.095Z,1569267417.095 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-09-23T19:36:57.095Z,1569267417.095 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-09-23T19:36:57.097Z,1569267417.097 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-09-23T19:36:57.506Z,1569267417.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-09-23T19:36:57.506Z,1569267417.506 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-09-23T19:36:57.647Z,1569267417.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-09-23T19:36:57.648Z,1569267417.648 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-09-23T19:36:57.728Z,1569267417.728 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-09-23T19:36:57.826Z,1569267417.826 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-09-23T19:36:57.827Z,1569267417.827 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-09-23T19:36:57.921Z,1569267417.921 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-09-23T19:36:57.922Z,1569267417.922 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-09-23T19:36:58.295Z,1569267418.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-09-23T19:36:58.296Z,1569267418.296 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-09-23T19:36:58.409Z,1569267418.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-09-23T19:36:58.410Z,1569267418.410 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-09-23T19:36:58.994Z,1569267418.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-09-23T19:36:58.994Z,1569267418.994 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-09-23T19:36:59.189Z,1569267419.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-09-23T19:36:59.190Z,1569267419.190 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-09-23T19:36:59.486Z,1569267419.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-09-23T19:36:59.487Z,1569267419.487 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-09-23T19:36:59.695Z,1569267419.695 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-09-23T19:36:59.695Z,1569267419.695 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-09-23T19:36:59.835Z,1569267419.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-09-23T19:36:59.836Z,1569267419.836 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-09-23T19:37:00.437Z,1569267420.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-09-23T19:37:00.438Z,1569267420.438 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-09-23T19:37:00.780Z,1569267420.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-09-23T19:37:00.781Z,1569267420.781 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-09-23T19:37:00.983Z,1569267420.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-09-23T19:37:00.985Z,1569267420.985 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-09-23T19:37:00.986Z,1569267420.986 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-09-23T19:37:01.184Z,1569267421.184 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-09-23T19:37:01.267Z,1569267421.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-09-23T19:37:01.397Z,1569267421.397 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-09-23T19:37:01.482Z,1569267421.482 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-09-23T19:37:01.710Z,1569267421.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-09-23T19:37:01.710Z,1569267421.710 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-09-23T19:37:01.804Z,1569267421.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-09-23T19:37:01.910Z,1569267421.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-09-23T19:37:02.002Z,1569267422.002 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-09-23T19:37:02.153Z,1569267422.153 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-09-23T19:37:02.251Z,1569267422.251 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-09-23T19:37:02.349Z,1569267422.349 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-09-23T19:37:02.350Z,1569267422.350 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-09-23T19:37:02.354Z,1569267422.354 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-09-23T19:37:02.368Z,1569267422.368 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-09-23T19:37:02.369Z,1569267422.369 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-09-23T19:37:02.436Z,1569267422.436 [VerticalControl](DEBUG): Construct VerticalControl.
2019-09-23T19:37:02.549Z,1569267422.549 [VerticalControl] Loaded
2019-09-23T19:37:02.549Z,1569267422.549 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-09-23T19:37:02.550Z,1569267422.550 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-09-23T19:37:02.620Z,1569267422.620 [HorizontalControl] Loaded
2019-09-23T19:37:02.620Z,1569267422.620 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-09-23T19:37:02.621Z,1569267422.621 [SpeedControl](DEBUG): Construct SpeedControl.
2019-09-23T19:37:02.626Z,1569267422.626 [SpeedControl] Loaded
2019-09-23T19:37:02.626Z,1569267422.626 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-09-23T19:37:02.627Z,1569267422.627 [LoopControl](DEBUG): Construct LoopControl.
2019-09-23T19:37:02.628Z,1569267422.628 [LoopControl] Loaded
2019-09-23T19:37:02.628Z,1569267422.628 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-09-23T19:37:02.629Z,1569267422.629 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-09-23T19:37:02.629Z,1569267422.629 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-09-23T19:37:02.754Z,1569267422.754 [BuoyancyServo] Loaded
2019-09-23T19:37:02.755Z,1569267422.755 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-09-23T19:37:02.766Z,1569267422.766 [ElevatorServo] Loaded
2019-09-23T19:37:02.766Z,1569267422.766 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-09-23T19:37:02.777Z,1569267422.777 [MassServo] Loaded
2019-09-23T19:37:02.777Z,1569267422.777 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-09-23T19:37:02.788Z,1569267422.788 [RudderServo] Loaded
2019-09-23T19:37:02.789Z,1569267422.789 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-09-23T19:37:02.800Z,1569267422.800 [ThrusterServo] Loaded
2019-09-23T19:37:02.800Z,1569267422.800 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-09-23T19:37:02.800Z,1569267422.800 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-09-23T19:37:02.801Z,1569267422.801 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-09-23T19:37:02.841Z,1569267422.841 [DepthRateCalculator] Loaded
2019-09-23T19:37:02.841Z,1569267422.841 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-09-23T19:37:02.846Z,1569267422.846 [PitchRateCalculator] Loaded
2019-09-23T19:37:02.847Z,1569267422.847 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-09-23T19:37:02.862Z,1569267422.862 [SpeedCalculator] Loaded
2019-09-23T19:37:02.863Z,1569267422.863 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-09-23T19:37:02.883Z,1569267422.883 [TempGradientCalculator] Loaded
2019-09-23T19:37:02.884Z,1569267422.884 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-09-23T19:37:02.889Z,1569267422.889 [YawRateCalculator] Loaded
2019-09-23T19:37:02.889Z,1569267422.889 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-09-23T19:37:02.919Z,1569267422.919 [ElevatorOffsetCalculator] Loaded
2019-09-23T19:37:02.919Z,1569267422.919 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-09-23T19:37:02.920Z,1569267422.920 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-09-23T19:37:02.920Z,1569267422.920 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-09-23T19:37:03.023Z,1569267423.023 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-09-23T19:37:03.024Z,1569267423.024 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-09-23T19:37:03.044Z,1569267423.044 [NavChart] Loaded
2019-09-23T19:37:03.045Z,1569267423.045 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-09-23T19:37:03.049Z,1569267423.049 [UniversalFixResidualReporter] Loaded
2019-09-23T19:37:03.049Z,1569267423.049 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-09-23T19:37:03.050Z,1569267423.050 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-09-23T19:37:03.050Z,1569267423.050 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-09-23T19:37:03.329Z,1569267423.329 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-09-23T19:37:03.334Z,1569267423.334 [AHRS_M2](INFO): created writer for : platform_orientation
2019-09-23T19:37:03.336Z,1569267423.336 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-09-23T19:37:03.341Z,1569267423.341 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-09-23T19:37:03.342Z,1569267423.342 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-09-23T19:37:03.346Z,1569267423.346 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-09-23T19:37:03.347Z,1569267423.347 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-09-23T19:37:03.352Z,1569267423.352 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-09-23T19:37:03.423Z,1569267423.423 [AHRS_M2] Loaded
2019-09-23T19:37:03.423Z,1569267423.423 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-09-23T19:37:03.497Z,1569267423.497 [DataOverHttps] Loaded
2019-09-23T19:37:03.497Z,1569267423.497 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-09-23T19:37:03.498Z,1569267423.498 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408134E0
2019-09-23T19:37:03.499Z,1569267423.499 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1175
2019-09-23T19:37:03.512Z,1569267423.512 [Depth_Keller] Loaded
2019-09-23T19:37:03.513Z,1569267423.513 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-09-23T19:37:03.518Z,1569267423.518 [DropWeight] Loaded
2019-09-23T19:37:03.518Z,1569267423.518 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-09-23T19:37:03.793Z,1569267423.793 [DVL_micro] Loaded
2019-09-23T19:37:03.794Z,1569267423.794 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-09-23T19:37:04.102Z,1569267424.102 [NAL9602] Loaded
2019-09-23T19:37:04.103Z,1569267424.103 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-09-23T19:37:04.145Z,1569267424.145 [Onboard] Loaded
2019-09-23T19:37:04.145Z,1569267424.145 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-09-23T19:37:04.149Z,1569267424.149 [Radio_Surface] Loaded
2019-09-23T19:37:04.149Z,1569267424.149 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-09-23T19:37:04.150Z,1569267424.150 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408434E0
2019-09-23T19:37:04.150Z,1569267424.150 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1176
2019-09-23T19:37:04.393Z,1569267424.393 [DAT] Loaded
2019-09-23T19:37:04.393Z,1569267424.393 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-09-23T19:37:05.960Z,1569267425.960 [BPC1] Loaded
2019-09-23T19:37:05.960Z,1569267425.960 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-09-23T19:37:05.981Z,1569267425.981 [BR_Ping1D] Loaded
2019-09-23T19:37:05.982Z,1569267425.982 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-09-23T19:37:05.982Z,1569267425.982 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-09-23T19:37:05.983Z,1569267425.983 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-09-23T19:37:06.052Z,1569267426.052 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-09-23T19:37:06.052Z,1569267426.052 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-09-23T19:37:06.150Z,1569267426.150 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-09-23T19:37:06.151Z,1569267426.151 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-09-23T19:37:06.182Z,1569267426.182 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-09-23T19:37:06.183Z,1569267426.183 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-09-23T19:37:06.358Z,1569267426.358 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-09-23T19:37:06.363Z,1569267426.363 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-09-23T19:37:06.364Z,1569267426.364 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-09-23T19:37:06.369Z,1569267426.369 [CTD_Seabird](INFO): created writer for : depth
2019-09-23T19:37:06.369Z,1569267426.369 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-09-23T19:37:06.374Z,1569267426.374 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-09-23T19:37:06.375Z,1569267426.375 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-09-23T19:37:06.380Z,1569267426.380 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-09-23T19:37:06.380Z,1569267426.380 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-09-23T19:37:06.385Z,1569267426.385 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-09-23T19:37:06.386Z,1569267426.386 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-09-23T19:37:06.391Z,1569267426.391 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-09-23T19:37:06.392Z,1569267426.392 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-09-23T19:37:06.397Z,1569267426.397 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-09-23T19:37:06.397Z,1569267426.397 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2019-09-23T19:37:06.402Z,1569267426.402 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2019-09-23T19:37:06.403Z,1569267426.403 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2019-09-23T19:37:06.403Z,1569267426.403 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2019-09-23T19:37:06.436Z,1569267426.436 [CTD_Seabird] Loaded
2019-09-23T19:37:06.436Z,1569267426.436 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-09-23T19:37:06.437Z,1569267426.437 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DA4E0
2019-09-23T19:37:06.438Z,1569267426.438 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1177
2019-09-23T19:37:06.452Z,1569267426.452 [PAR_Licor] Loaded
2019-09-23T19:37:06.453Z,1569267426.453 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-09-23T19:37:06.501Z,1569267426.501 [WetLabsBB2FL] Loaded
2019-09-23T19:37:06.501Z,1569267426.501 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-09-23T19:37:06.502Z,1569267426.502 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0A4E0
2019-09-23T19:37:06.503Z,1569267426.503 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1178
2019-09-23T19:37:06.503Z,1569267426.503 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-09-23T19:37:06.504Z,1569267426.504 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-09-23T19:37:06.795Z,1569267426.795 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-09-23T19:37:06.795Z,1569267426.795 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-09-23T19:37:06.930Z,1569267426.930 [SBIT](DEBUG): Construct Startup Built In Test.
2019-09-23T19:37:06.942Z,1569267426.942 [SBIT] Loaded
2019-09-23T19:37:06.942Z,1569267426.942 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-09-23T19:37:06.943Z,1569267426.943 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-09-23T19:37:06.954Z,1569267426.954 [IBIT] Loaded
2019-09-23T19:37:06.954Z,1569267426.954 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-09-23T19:37:06.958Z,1569267426.958 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-09-23T19:37:07.097Z,1569267427.097 [CBIT] Loaded
2019-09-23T19:37:07.098Z,1569267427.098 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-09-23T19:37:07.098Z,1569267427.098 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-09-23T19:37:07.102Z,1569267427.102 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-09-23T19:37:07.103Z,1569267427.103 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-09-23T19:37:07.110Z,1569267427.110 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-09-23T19:37:07.111Z,1569267427.111 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AE94E0
2019-09-23T19:37:07.111Z,1569267427.111 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1179
2019-09-23T19:37:07.116Z,1569267427.116 [Supervisor](INFO): Main Thread ID is 801
2019-09-23T19:37:07.116Z,1569267427.116 [Supervisor](DEBUG): Running supervisor.
2019-09-23T19:37:07.116Z,1569267427.116 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1180
2019-09-23T19:37:07.119Z,1569267427.119 [controlThread ThreadHandler](INFO): Handler Thread ID is 1181
2019-09-23T19:37:07.119Z,1569267427.119 [controlThread](DEBUG): Initializing ControlThread
2019-09-23T19:37:07.120Z,1569267427.120 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-09-23T19:37:07.122Z,1569267427.122 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-09-23T19:37:07.122Z,1569267427.122 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-09-23T19:37:07.123Z,1569267427.123 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-09-23T19:37:07.124Z,1569267427.124 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-09-23T19:37:07.124Z,1569267427.124 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-09-23T19:37:07.125Z,1569267427.125 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-09-23T19:37:07.125Z,1569267427.125 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-09-23T19:37:07.126Z,1569267427.126 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-09-23T19:37:07.126Z,1569267427.126 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-09-23T19:37:07.127Z,1569267427.127 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-09-23T19:37:07.127Z,1569267427.127 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-09-23T19:37:07.133Z,1569267427.133 [SBIT](INFO): Initialize SBIT Component.
2019-09-23T19:37:07.133Z,1569267427.133 [SBIT](IMPORTANT): git: 2019-09-10-9-g851767d
2019-09-23T19:37:07.134Z,1569267427.134 [SBIT](INFO): git hash: 851767d67e1cfa0383bee058d4dc44c68fc1ad67
2019-09-23T19:37:07.134Z,1569267427.134 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-09-23T19:37:07.135Z,1569267427.135 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-09-23T19:37:07.136Z,1569267427.136 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2019-09-23T19:37:07.137Z,1569267427.137 [IBIT](INFO): Initialize IBIT Component.
2019-09-23T19:37:07.138Z,1569267427.138 [CBIT](DEBUG): Initialize CBIT Component.
2019-09-23T19:37:07.139Z,1569267427.139 [logger ThreadHandler](INFO): Handler Thread ID is 1182
2019-09-23T19:37:07.149Z,1569267427.149 [CBIT](DEBUG): Initialized mux pins.
2019-09-23T19:37:07.149Z,1569267427.149 [CBIT](DEBUG): Initializing the watchdog timer.
2019-09-23T19:37:07.157Z,1569267427.157 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1183
2019-09-23T19:37:07.158Z,1569267427.158 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-09-23T19:37:07.173Z,1569267427.173 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1184
2019-09-23T19:37:07.178Z,1569267427.178 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-09-23T19:37:07.178Z,1569267427.178 [CBIT](DEBUG): Initializing heartbeat.
2019-09-23T19:37:07.185Z,1569267427.185 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1185
2019-09-23T19:37:07.186Z,1569267427.186 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-09-23T19:37:07.189Z,1569267427.189 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-09-23T19:37:07.190Z,1569267427.190 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1187
2019-09-23T19:37:07.191Z,1569267427.191 [WetLabsBB2FL](INFO): Powering down
2019-09-23T19:37:07.225Z,1569267427.225 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1188
2019-09-23T19:37:07.228Z,1569267427.228 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-09-23T19:37:07.229Z,1569267427.229 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-09-23T19:37:07.229Z,1569267427.229 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-09-23T19:37:07.229Z,1569267427.229 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-09-23T19:37:07.229Z,1569267427.229 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-09-23T19:37:07.229Z,1569267427.229 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-09-23T19:37:07.230Z,1569267427.230 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-09-23T19:37:07.230Z,1569267427.230 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-09-23T19:37:07.230Z,1569267427.230 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-09-23T19:37:07.230Z,1569267427.230 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-09-23T19:37:07.230Z,1569267427.230 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-09-23T19:37:07.230Z,1569267427.230 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-09-23T19:37:07.231Z,1569267427.231 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-09-23T19:37:07.231Z,1569267427.231 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-09-23T19:37:07.231Z,1569267427.231 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-09-23T19:37:07.231Z,1569267427.231 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-09-23T19:37:07.249Z,1569267427.249 [CBIT](DEBUG): Deactivating GF circuits.
2019-09-23T19:37:07.249Z,1569267427.249 [CBIT](DEBUG): Deactivating emergency mode.
2019-09-23T19:37:07.284Z,1569267427.284 [CBIT](DEBUG): Backplane powered.
2019-09-23T19:37:07.285Z,1569267427.285 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-09-23T19:37:07.295Z,1569267427.295 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-23T19:37:07.305Z,1569267427.305 [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-23T19:37:07.306Z,1569267427.306 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-09-23T19:37:07.368Z,1569267427.368 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-09-23T19:37:07.369Z,1569267427.369 [Default:A.Wait](DEBUG): Construct Wait.
2019-09-23T19:37:07.371Z,1569267427.371 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-23T19:37:07.418Z,1569267427.418 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-09-23T19:37:07.421Z,1569267427.421 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-09-23T19:37:07.442Z,1569267427.442 [Default:E.Execute](DEBUG): Construct Execute.
2019-09-23T19:37:07.445Z,1569267427.445 [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-23T19:37:07.461Z,1569267427.461 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,BR_Ping1D,PAR_Licor,Depth_Keller,PAR_Licor,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-23T19:37:07.473Z,1569267427.473 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-23T19:37:07.529Z,1569267427.529 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-09-23T19:37:07.577Z,1569267427.577 [Radio_Surface](INFO): Powering up
2019-09-23T19:37:07.605Z,1569267427.605 [DVL_micro](INFO): Initializing
2019-09-23T19:37:07.677Z,1569267427.677 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-09-23T19:37:07.677Z,1569267427.677 [DAT](INFO): Powering up
2019-09-23T19:37:07.678Z,1569267427.678 [DAT](DEBUG): Initializing DAT.
2019-09-23T19:37:07.724Z,1569267427.724 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-09-23T19:37:07.834Z,1569267427.834 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-09-23T19:37:08.045Z,1569267428.045 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-09-23T19:37:08.053Z,1569267428.053 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-09-23T19:37:08.082Z,1569267428.082 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-09-23T19:37:08.089Z,1569267428.089 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-09-23T19:37:08.095Z,1569267428.095 [MassServo](DEBUG): Initializing EZServoServo.
2019-09-23T19:37:08.101Z,1569267428.101 [MassServo](DEBUG): Initializing MassServo.
2019-09-23T19:37:08.123Z,1569267428.123 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-23T19:37:08.129Z,1569267428.129 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-23T19:37:08.135Z,1569267428.135 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-09-23T19:37:08.141Z,1569267428.141 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-09-23T19:37:08.186Z,1569267428.186 [CommandLine](FAULT): Scheduling is paused
2019-09-23T19:37:08.186Z,1569267428.186 [CBIT](INFO): Critical error at 20190923T193707
2019-09-23T19:37:08.186Z,1569267428.186 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-09-23T19:37:09.017Z,1569267429.017 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-09-23T19:37:09.017Z,1569267429.017 [RudderServo](FAULT): Rudder failed to initialize
2019-09-23T19:37:09.017Z,1569267429.017 [RudderServo] Communications Fault, FailCount= 1
2019-09-23T19:37:09.017Z,1569267429.017 [RudderServo](ERROR): Communications Fault
2019-09-23T19:37:09.128Z,1569267429.128 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-09-23T19:37:09.354Z,1569267429.354 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-23T19:37:09.354Z,1569267429.354 [RudderServo](INFO): Powering down
2019-09-23T19:37:10.015Z,1569267430.015 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-23T19:37:10.133Z,1569267430.133 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-23T19:37:10.138Z,1569267430.138 [CBIT](INFO): Clearing failed state for component RudderServo
2019-09-23T19:37:10.138Z,1569267430.138 [RudderServo] No Fault, FailCount= 1
2019-09-23T19:37:13.656Z,1569267433.656 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-09-23T19:37:13.657Z,1569267433.657 [BR_Ping1D] Data Fault, FailCount= 1
2019-09-23T19:37:13.657Z,1569267433.657 [BR_Ping1D](ERROR): Data Fault
2019-09-23T19:37:14.077Z,1569267434.077 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-09-23T19:37:14.146Z,1569267434.146 [BR_Ping1D](INFO): Powering down
2019-09-23T19:37:15.412Z,1569267435.412 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-09-23T19:37:15.412Z,1569267435.412 [BR_Ping1D] No Fault, FailCount= 1
2019-09-23T19:37:20.974Z,1569267440.974 [DAT](INFO): setting local address to 5
2019-09-23T19:37:21.379Z,1569267441.379 [DAT](INFO): set local address to 5
2019-09-23T19:37:21.766Z,1569267441.766 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T19:37:21.766Z,1569267441.766 [DVL_micro] Communications Fault, FailCount= 1
2019-09-23T19:37:21.766Z,1569267441.766 [DVL_micro](ERROR): Communications Fault
2019-09-23T19:37:21.845Z,1569267441.845 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T19:37:22.241Z,1569267442.241 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:37:23.057Z,1569267443.057 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-23T19:37:23.057Z,1569267443.057 [DVL_micro] No Fault, FailCount= 1
2019-09-23T19:37:23.389Z,1569267443.389 [DVL_micro](INFO): Initializing
2019-09-23T19:37:27.919Z,1569267447.919 [SBIT](IMPORTANT): Beginning Startup BIT
2019-09-23T19:37:27.935Z,1569267447.935 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-23T19:37:34.151Z,1569267454.151 [NAL9602](INFO): Powering up NAL9602
2019-09-23T19:37:37.384Z,1569267457.384 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T19:37:37.385Z,1569267457.385 [DVL_micro] Communications Fault, FailCount= 2
2019-09-23T19:37:37.385Z,1569267457.385 [DVL_micro](ERROR): Communications Fault
2019-09-23T19:37:37.498Z,1569267457.498 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T19:37:37.861Z,1569267457.861 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:37:38.714Z,1569267458.714 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-23T19:37:38.714Z,1569267458.714 [DVL_micro] No Fault, FailCount= 2
2019-09-23T19:37:38.718Z,1569267458.718 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.028691
CHAN A1 (24V): 0.017888
CHAN A2 (12V): -0.018830
CHAN A3 (5V): -0.028121
CHAN B0 (3.3V): -0.013349
CHAN B1 (3.15aV): -0.007658
CHAN B2 (3.15bV): -0.004295
CHAN B3 (GND): -0.015215
OPEN: 0.005833
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-23T19:37:39.021Z,1569267459.021 [DVL_micro](INFO): Initializing
2019-09-23T19:37:45.255Z,1569267465.255 [NAL9602](INFO): NAL9602 initialized
2019-09-23T19:37:46.069Z,1569267466.069 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:37:52.135Z,1569267472.135 [CommandLine](IMPORTANT): got command failComponent
2019-09-23T19:37:52.135Z,1569267472.135 [CommandLine](IMPORTANT): Failed components:
2019-09-23T19:37:52.136Z,1569267472.136 [CommandLine](IMPORTANT): No failed Components.
2019-09-23T19:37:52.922Z,1569267472.922 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T19:37:52.922Z,1569267472.922 [DVL_micro] Communications Fault, FailCount= 3
2019-09-23T19:37:52.922Z,1569267472.922 [DVL_micro](ERROR): Communications Fault
2019-09-23T19:37:53.074Z,1569267473.074 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T19:37:53.393Z,1569267473.393 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:37:54.258Z,1569267474.258 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-23T19:37:54.259Z,1569267474.259 [DVL_micro] No Fault, FailCount= 3
2019-09-23T19:37:54.548Z,1569267474.548 [DVL_micro](INFO): Initializing
2019-09-23T19:38:08.282Z,1569267488.282 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T19:38:08.282Z,1569267488.282 [DVL_micro] Communications Fault, FailCount= 4
2019-09-23T19:38:08.282Z,1569267488.282 [DVL_micro](ERROR): Communications Fault
2019-09-23T19:38:08.425Z,1569267488.425 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T19:38:08.749Z,1569267488.749 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:38:09.689Z,1569267489.689 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-23T19:38:09.690Z,1569267489.690 [DVL_micro] No Fault, FailCount= 4
2019-09-23T19:38:09.899Z,1569267489.899 [DVL_micro](INFO): Initializing
2019-09-23T19:38:21.432Z,1569267501.432 [SBIT](IMPORTANT): SBIT PASSED
2019-09-23T19:38:21.537Z,1569267501.537 [CommandLine](IMPORTANT): got command configSet list
2019-09-23T19:38:21.538Z,1569267501.538 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-09-23T19:38:21.538Z,1569267501.538 [CommandLine](IMPORTANT): BR_Ping1D.verbosity=0 count;

2019-09-23T19:38:21.539Z,1569267501.539 [CommandLine](IMPORTANT): CBIT.gf24Offset=150 microampere;

2019-09-23T19:38:21.539Z,1569267501.539 [CommandLine](IMPORTANT): VerticalControl.massDefault=0 millimeter;

2019-09-23T19:38:21.819Z,1569267501.819 [MissionManager](IMPORTANT): Started mission Startup
2019-09-23T19:38:21.820Z,1569267501.820 [Startup] Running Loop=1
2019-09-23T19:38:21.820Z,1569267501.820 [Startup](DEBUG): Aggregate::initialize Startup
2019-09-23T19:38:21.820Z,1569267501.820 [Startup:A.GoToSurface] Running Loop=1
2019-09-23T19:38:21.820Z,1569267501.820 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-23T19:38:21.825Z,1569267501.825 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-23T19:38:21.826Z,1569267501.826 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-23T19:38:21.826Z,1569267501.826 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-23T19:38:21.827Z,1569267501.827 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-23T19:38:21.827Z,1569267501.827 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-23T19:38:21.842Z,1569267501.842 [Startup:StartupSatComms] Running Loop=1
2019-09-23T19:38:21.842Z,1569267501.842 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-09-23T19:38:21.842Z,1569267501.842 [Startup:StartupSatComms:A] Running Loop=1
2019-09-23T19:38:22.211Z,1569267502.211 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-09-23T19:38:23.355Z,1569267503.355 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T19:38:23.355Z,1569267503.355 [DVL_micro] Communications Fault, FailCount= 5
2019-09-23T19:38:23.355Z,1569267503.355 [DVL_micro](ERROR): Communications Fault
2019-09-23T19:38:23.431Z,1569267503.431 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T19:38:23.431Z,1569267503.431 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-23T19:38:23.841Z,1569267503.841 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:38:23.908Z,1569267503.908 [CBIT](INFO): Critical error at 20190923T193823
2019-09-23T19:39:18.714Z,1569267558.714 [CommandLine](IMPORTANT): got command gfscan
2019-09-23T19:39:18.820Z,1569267558.820 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-23T19:39:22.005Z,1569267562.005 [Startup:StartupSatComms:A](INFO): Timed out from 2019-09-23T19:38:21.8Z
2019-09-23T19:39:22.006Z,1569267562.006 [Startup:StartupSatComms:A] Stopped
2019-09-23T19:39:22.006Z,1569267562.006 [Startup:StartupSatComms:B] Running Loop=1
2019-09-23T19:39:22.391Z,1569267562.391 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-09-23T19:39:29.681Z,1569267569.681 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.032564
CHAN A1 (24V): 0.017147
CHAN A2 (12V): -0.017802
CHAN A3 (5V): -0.026025
CHAN B0 (3.3V): -0.013194
CHAN B1 (3.15aV): -0.006605
CHAN B2 (3.15bV): -0.004175
CHAN B3 (GND): -0.015199
OPEN: 0.006233
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-23T19:39:36.583Z,1569267576.583 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005072
2019-09-23T19:39:43.862Z,1569267583.862 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB4
2019-09-23T19:39:45.821Z,1569267585.821 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190923T193042/Courier0007.lzma
2019-09-23T19:39:45.910Z,1569267585.910 [CommandLine](IMPORTANT): got command gfscan
2019-09-23T19:39:46.257Z,1569267586.257 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-23T19:39:47.826Z,1569267587.826 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193042/Courier0007.lzma.bak
2019-09-23T19:39:47.827Z,1569267587.827 [DataOverHttps](INFO): SBD MOMSN=11804609
2019-09-23T19:39:57.179Z,1569267597.179 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003559
CHAN A1 (24V): 0.000820
CHAN A2 (12V): -0.004487
CHAN A3 (5V): -0.004041
CHAN B0 (3.3V): -0.001696
CHAN B1 (3.15aV): -0.001903
CHAN B2 (3.15bV): -0.002096
CHAN B3 (GND): -0.003228
OPEN: 0.005910
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-23T19:40:05.728Z,1569267605.728 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20190923T193657/Courier0000.lzma
2019-09-23T19:40:07.239Z,1569267607.239 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-09-23T19:40:07.239Z,1569267607.239 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-09-23T19:40:07.276Z,1569267607.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-09-23T19:40:07.657Z,1569267607.657 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-09-23T19:40:07.657Z,1569267607.657 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-09-23T19:40:07.734Z,1569267607.734 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193657/Courier0000.lzma.bak
2019-09-23T19:40:07.734Z,1569267607.734 [DataOverHttps](INFO): SBD MOMSN=11804611
2019-09-23T19:40:13.259Z,1569267613.259 [NAL9602](INFO): SBD MO Status=0, MOMSN=1563, MT Status=0, MTMSN=0
2019-09-23T19:40:13.259Z,1569267613.259 [NAL9602](INFO): No messages in MT queue
2019-09-23T19:40:14.073Z,1569267614.073 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:17.297Z,1569267617.297 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:20.133Z,1569267620.133 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:22.185Z,1569267622.185 [Startup:StartupSatComms:B](INFO): Timed out from 2019-09-23T19:39:22.0Z
2019-09-23T19:40:22.185Z,1569267622.185 [Startup:StartupSatComms:B] Stopped
2019-09-23T19:40:22.185Z,1569267622.185 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-09-23T19:40:22.185Z,1569267622.185 [Startup:StartupSatComms] Stopped
2019-09-23T19:40:22.185Z,1569267622.185 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-09-23T19:40:22.186Z,1569267622.186 [Startup](INFO): Completed Startup
2019-09-23T19:40:22.186Z,1569267622.186 [MissionManager](INFO): Startup is completed.
2019-09-23T19:40:22.187Z,1569267622.187 [MissionManager](INFO): Uninitializing Mission Startup
2019-09-23T19:40:22.187Z,1569267622.187 [Startup] Stopped
2019-09-23T19:40:22.187Z,1569267622.187 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-09-23T19:40:22.187Z,1569267622.187 [Startup:A.GoToSurface] Stopped
2019-09-23T19:40:22.187Z,1569267622.187 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-23T19:40:22.594Z,1569267622.594 [MissionManager](IMPORTANT): Started mission Default
2019-09-23T19:40:22.595Z,1569267622.595 [Default] Running Loop=1
2019-09-23T19:40:22.595Z,1569267622.595 [Default](DEBUG): Aggregate::initialize Default
2019-09-23T19:40:22.595Z,1569267622.595 [Default:B.GoToSurface] Running Loop=1
2019-09-23T19:40:22.595Z,1569267622.595 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-23T19:40:22.595Z,1569267622.595 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-23T19:40:22.595Z,1569267622.595 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-23T19:40:22.596Z,1569267622.596 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-23T19:40:22.596Z,1569267622.596 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-23T19:40:22.601Z,1569267622.601 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-23T19:40:22.601Z,1569267622.601 [Default:A.Wait] Running Loop=1
2019-09-23T19:40:22.601Z,1569267622.601 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-09-23T19:40:23.357Z,1569267623.357 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:26.185Z,1569267626.185 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:28.911Z,1569267628.911 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20190923T191556/Express0011.lzma
2019-09-23T19:40:29.421Z,1569267629.421 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:30.922Z,1569267630.922 [DataOverHttps](INFO): Moved sent file to Logs/20190923T191556/Express0011.lzma.bak
2019-09-23T19:40:30.922Z,1569267630.922 [DataOverHttps](INFO): SBD MOMSN=11804616
2019-09-23T19:40:32.249Z,1569267632.249 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:35.485Z,1569267635.485 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:35.948Z,1569267635.948 [Default:A.Wait](INFO): Done Waiting.
2019-09-23T19:40:35.948Z,1569267635.948 [Default:A.Wait] Stopped
2019-09-23T19:40:35.957Z,1569267635.957 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-09-23T19:40:36.352Z,1569267636.352 [Default:CheckIn] Running Loop=1
2019-09-23T19:40:36.352Z,1569267636.352 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-23T19:40:36.352Z,1569267636.352 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-23T19:40:36.722Z,1569267636.722 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-09-23T19:40:38.313Z,1569267638.313 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:41.137Z,1569267641.137 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:44.373Z,1569267644.373 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:47.201Z,1569267647.201 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:50.437Z,1569267650.437 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:53.261Z,1569267653.261 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:40:53.649Z,1569267653.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193933.00,A,3648.16018,N,12147.28128,W,0.117,0.00,230919,,,A*7E
2019-09-23T19:40:53.652Z,1569267653.652 [NAL9602](INFO): GPS fix at 20190923T193933: (36.802670, -121.788021)
2019-09-23T19:40:53.705Z,1569267653.705 [Default:CheckIn:Read_GPS] Stopped
2019-09-23T19:40:53.705Z,1569267653.705 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-23T19:40:54.110Z,1569267654.110 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-09-23T19:41:15.965Z,1569267675.965 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20190923T193657/Courier0004.lzma
2019-09-23T19:41:17.974Z,1569267677.974 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193657/Courier0004.lzma.bak
2019-09-23T19:41:17.974Z,1569267677.974 [DataOverHttps](INFO): SBD MOMSN=11804627
2019-09-23T19:41:26.114Z,1569267686.114 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-23T19:41:42.581Z,1569267702.581 [DataOverHttps](INFO): Sending 799 bytes from file Logs/20190923T193042/Express0001.lzma
2019-09-23T19:41:44.236Z,1569267704.236 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB4
2019-09-23T19:41:44.587Z,1569267704.587 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193042/Express0001.lzma.bak
2019-09-23T19:41:44.587Z,1569267704.587 [DataOverHttps](INFO): SBD MOMSN=11804630
2019-09-23T19:41:48.599Z,1569267708.599 [CommandLine](IMPORTANT): got command gfscan
2019-09-23T19:41:48.817Z,1569267708.817 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-23T19:41:55.021Z,1569267715.021 [CommandLine](IMPORTANT): got command failComponent
2019-09-23T19:41:55.021Z,1569267715.021 [CommandLine](IMPORTANT): Failed components:
2019-09-23T19:41:55.021Z,1569267715.021 [CommandLine](IMPORTANT): DVL_micro: Communications Fault
2019-09-23T19:41:59.891Z,1569267719.891 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004681
CHAN A1 (24V): 0.000885
CHAN A2 (12V): -0.005129
CHAN A3 (5V): -0.003031
CHAN B0 (3.3V): -0.002176
CHAN B1 (3.15aV): -0.001405
CHAN B2 (3.15bV): -0.000897
CHAN B3 (GND): -0.002292
OPEN: 0.005889
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-23T19:42:12.413Z,1569267732.413 [DataOverHttps](INFO): Sending 460 bytes from file Logs/20190923T193042/Express0005.lzma
2019-09-23T19:42:14.418Z,1569267734.418 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193042/Express0005.lzma.bak
2019-09-23T19:42:14.418Z,1569267734.418 [DataOverHttps](INFO): SBD MOMSN=11804650
2019-09-23T19:42:47.544Z,1569267767.544 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20190923T193042/Express0008.lzma
2019-09-23T19:42:49.550Z,1569267769.550 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193042/Express0008.lzma.bak
2019-09-23T19:42:49.550Z,1569267769.550 [DataOverHttps](INFO): SBD MOMSN=11804661
2019-09-23T19:42:58.403Z,1569267778.403 [CommandLine](IMPORTANT): got command failComponent
2019-09-23T19:42:58.403Z,1569267778.403 [CommandLine](IMPORTANT): Failed components:
2019-09-23T19:42:58.403Z,1569267778.403 [CommandLine](IMPORTANT): DVL_micro: Communications Fault
2019-09-23T19:43:01.077Z,1569267781.077 [CommandLine](IMPORTANT): got command gfscan
2019-09-23T19:43:01.554Z,1569267781.554 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-23T19:43:12.193Z,1569267792.193 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003206
CHAN A1 (24V): 0.001203
CHAN A2 (12V): -0.004958
CHAN A3 (5V): -0.003704
CHAN B0 (3.3V): -0.001867
CHAN B1 (3.15aV): -0.001846
CHAN B2 (3.15bV): -0.001041
CHAN B3 (GND): -0.002735
OPEN: 0.005800
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-23T19:43:26.443Z,1569267806.443 [DataOverHttps](INFO): Sending 855 bytes from file Logs/20190923T193657/Express0001.lzma
2019-09-23T19:43:28.443Z,1569267808.443 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193657/Express0001.lzma.bak
2019-09-23T19:43:28.444Z,1569267808.444 [DataOverHttps](INFO): SBD MOMSN=11804666
2019-09-23T19:43:55.436Z,1569267835.436 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-09-23T19:43:55.439Z,1569267835.439 [BPC1](INFO): Received data from all battery sticks.
2019-09-23T19:44:03.206Z,1569267843.206 [DataOverHttps](INFO): Sending 506 bytes from file Logs/20190923T193657/Express0005.lzma
2019-09-23T19:44:05.210Z,1569267845.210 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193657/Express0005.lzma.bak
2019-09-23T19:44:05.210Z,1569267845.210 [DataOverHttps](INFO): SBD MOMSN=11804701
2019-09-23T19:44:12.855Z,1569267852.855 [Default:CheckIn:Read_Iridium] Stopped
2019-09-23T19:44:12.855Z,1569267852.855 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-23T19:44:12.855Z,1569267852.855 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-23T19:44:43.666Z,1569267883.666 [CommandLine](IMPORTANT): got command gfscan
2019-09-23T19:44:43.000Z,1569267884.000 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-23T19:44:54.897Z,1569267894.897 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.035965
CHAN A1 (24V): 0.019921
CHAN A2 (12V): -0.018314
CHAN A3 (5V): -0.027612
CHAN B0 (3.3V): -0.012697
CHAN B1 (3.15aV): -0.007376
CHAN B2 (3.15bV): -0.004699
CHAN B3 (GND): -0.016904
OPEN: 0.005639
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-23T19:45:50.009Z,1569267950.009 [CommandLine](IMPORTANT): got command failComponent none DVL_micro
2019-09-23T19:45:50.009Z,1569267950.009 [DVL_micro] No Fault, FailCount= 5
2019-09-23T19:45:50.010Z,1569267950.010 [CommandLine](IMPORTANT): DVL_micro failureMode is No Fault
2019-09-23T19:45:50.124Z,1569267950.124 [DVL_micro](INFO): Initializing
2019-09-23T19:45:54.166Z,1569267954.166 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-09-23T19:45:54.166Z,1569267954.166 [DVL_micro] Data Fault, FailCount= 6
2019-09-23T19:45:54.166Z,1569267954.166 [DVL_micro](ERROR): Data Fault
2019-09-23T19:45:54.263Z,1569267954.263 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-09-23T19:45:54.264Z,1569267954.264 [CBIT](CRITICAL): Data Fault in component: DVL_micro
2019-09-23T19:45:54.645Z,1569267954.645 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:45:54.719Z,1569267954.719 [CBIT](INFO): Critical error at 20190923T194554
2019-09-23T19:46:40.913Z,1569268000.913 [CommandLine](IMPORTANT): got command configSet DVL_micro.loadAtStartup 0.000000 bool persist
2019-09-23T19:46:40.914Z,1569268000.914 [CommandLine](IMPORTANT): configSet DVL_micro.loadAtStartup requires a restart to take effect.
2019-09-23T19:47:01.093Z,1569268021.093 [CommandLine](IMPORTANT): got command failComponent hardware DVL_micro
2019-09-23T19:47:01.093Z,1569268021.093 [DVL_micro] Hardware Fault, FailCount= 6
2019-09-23T19:47:01.094Z,1569268021.094 [DVL_micro](ERROR): Hardware Fault
2019-09-23T19:47:01.094Z,1569268021.094 [CommandLine](IMPORTANT): DVL_micro failureMode is Hardware Fault
2019-09-23T19:47:17.761Z,1569268037.761 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB4
2019-09-23T19:47:21.050Z,1569268041.050 [CommandLine](IMPORTANT): got command gfscan
2019-09-23T19:47:21.110Z,1569268041.110 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-23T19:47:32.048Z,1569268052.048 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004669
CHAN A1 (24V): 0.000805
CHAN A2 (12V): -0.004945
CHAN A3 (5V): -0.003970
CHAN B0 (3.3V): -0.001707
CHAN B1 (3.15aV): -0.001650
CHAN B2 (3.15bV): -0.000697
CHAN B3 (GND): -0.003081
OPEN: 0.005809
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-23T19:47:44.302Z,1569268064.302 [CommandLine](IMPORTANT): got command report touch BR_Ping1D.distance
2019-09-23T19:47:44.523Z,1569268064.523 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:44.938Z,1569268064.938 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:45.337Z,1569268065.337 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:45.730Z,1569268065.730 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:46.150Z,1569268066.150 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:46.549Z,1569268066.549 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:46.934Z,1569268066.934 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:47.345Z,1569268067.345 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:47.769Z,1569268067.769 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:48.173Z,1569268068.173 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:48.580Z,1569268068.580 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:48.974Z,1569268068.974 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:49.354Z,1569268069.354 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:49.773Z,1569268069.773 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:50.213Z,1569268070.213 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:50.568Z,1569268070.568 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:50.982Z,1569268070.982 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:51.402Z,1569268071.402 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:51.812Z,1569268071.812 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:52.214Z,1569268072.214 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:52.598Z,1569268072.598 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:53.005Z,1569268073.005 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:53.417Z,1569268073.417 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:53.800Z,1569268073.800 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:54.214Z,1569268074.214 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:54.619Z,1569268074.619 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:55.052Z,1569268075.052 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:55.435Z,1569268075.435 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:55.885Z,1569268075.885 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:56.266Z,1569268076.266 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:56.641Z,1569268076.641 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:57.050Z,1569268077.050 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:57.387Z,1569268077.387 [CommandLine](IMPORTANT): got command report touch BR_Ping1D.confidence
2019-09-23T19:47:57.475Z,1569268077.475 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:57.475Z,1569268077.475 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:47:57.840Z,1569268077.840 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:57.853Z,1569268077.853 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:47:58.264Z,1569268078.264 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:58.269Z,1569268078.269 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:47:58.657Z,1569268078.657 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:58.658Z,1569268078.658 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:47:59.051Z,1569268079.051 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:59.052Z,1569268079.052 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:47:59.485Z,1569268079.485 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:59.486Z,1569268079.486 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:47:59.881Z,1569268079.881 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:47:59.881Z,1569268079.881 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:00.307Z,1569268080.307 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:00.308Z,1569268080.308 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:00.681Z,1569268080.681 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:00.682Z,1569268080.682 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:01.106Z,1569268081.106 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:01.107Z,1569268081.107 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:01.481Z,1569268081.481 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:01.482Z,1569268081.482 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:01.892Z,1569268081.892 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:01.893Z,1569268081.893 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:02.283Z,1569268082.283 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:02.283Z,1569268082.283 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:02.691Z,1569268082.691 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:02.692Z,1569268082.692 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:03.161Z,1569268083.161 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:03.162Z,1569268083.162 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:03.596Z,1569268083.596 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:03.605Z,1569268083.605 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:03.982Z,1569268083.982 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:03.983Z,1569268083.983 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:04.382Z,1569268084.382 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:04.383Z,1569268084.383 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:04.753Z,1569268084.753 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:04.753Z,1569268084.753 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:05.169Z,1569268085.169 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:05.170Z,1569268085.170 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:05.574Z,1569268085.574 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:05.575Z,1569268085.575 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:05.974Z,1569268085.974 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:05.975Z,1569268085.975 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:06.449Z,1569268086.449 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:06.450Z,1569268086.450 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:06.816Z,1569268086.816 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:06.829Z,1569268086.829 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:07.222Z,1569268087.222 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:07.223Z,1569268087.223 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:07.677Z,1569268087.677 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:07.678Z,1569268087.678 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:08.014Z,1569268088.014 [CommandLine](IMPORTANT): got command report touch BR_Ping1D.gainIndex
2019-09-23T19:48:08.038Z,1569268088.038 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:08.038Z,1569268088.038 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:08.446Z,1569268088.446 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:08.447Z,1569268088.447 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:08.447Z,1569268088.447 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:08.847Z,1569268088.847 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:08.848Z,1569268088.848 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:08.848Z,1569268088.848 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:09.239Z,1569268089.239 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:09.240Z,1569268089.240 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:09.257Z,1569268089.257 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:09.659Z,1569268089.659 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:09.659Z,1569268089.659 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:09.660Z,1569268089.660 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:10.077Z,1569268090.077 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:10.078Z,1569268090.078 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:10.078Z,1569268090.078 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:10.455Z,1569268090.455 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:10.455Z,1569268090.455 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:10.456Z,1569268090.456 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:10.829Z,1569268090.829 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:10.829Z,1569268090.829 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:10.830Z,1569268090.830 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:11.305Z,1569268091.305 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:11.306Z,1569268091.306 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:11.306Z,1569268091.306 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:11.622Z,1569268091.622 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:11.623Z,1569268091.623 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:11.623Z,1569268091.623 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:12.039Z,1569268092.039 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:12.040Z,1569268092.040 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:12.053Z,1569268092.053 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:12.548Z,1569268092.548 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:12.569Z,1569268092.569 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:12.569Z,1569268092.569 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:12.917Z,1569268092.917 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:12.917Z,1569268092.917 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:12.918Z,1569268092.918 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:13.278Z,1569268093.278 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:13.279Z,1569268093.279 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:13.279Z,1569268093.279 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:13.721Z,1569268093.721 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:13.722Z,1569268093.722 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:13.722Z,1569268093.722 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:14.070Z,1569268094.070 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:14.071Z,1569268094.071 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:14.071Z,1569268094.071 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:14.494Z,1569268094.494 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:14.495Z,1569268094.495 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:14.495Z,1569268094.495 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:14.969Z,1569268094.969 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:14.970Z,1569268094.970 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:14.970Z,1569268094.970 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:15.331Z,1569268095.331 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:15.332Z,1569268095.332 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:15.345Z,1569268095.345 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:15.691Z,1569268095.691 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:15.691Z,1569268095.691 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:15.692Z,1569268095.692 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:16.141Z,1569268096.141 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:16.141Z,1569268096.141 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:16.142Z,1569268096.142 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:16.509Z,1569268096.509 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:16.510Z,1569268096.510 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:16.510Z,1569268096.510 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:16.919Z,1569268096.919 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:16.920Z,1569268096.920 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:16.933Z,1569268096.933 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:17.325Z,1569268097.325 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:17.326Z,1569268097.326 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:17.326Z,1569268097.326 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:17.730Z,1569268097.730 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:17.731Z,1569268097.731 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:17.731Z,1569268097.731 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:18.146Z,1569268098.146 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:18.147Z,1569268098.147 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:18.147Z,1569268098.147 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:18.577Z,1569268098.577 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:18.577Z,1569268098.577 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:18.578Z,1569268098.578 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:18.910Z,1569268098.910 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:18.911Z,1569268098.911 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:18.911Z,1569268098.911 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:19.314Z,1569268099.314 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:19.315Z,1569268099.315 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:19.315Z,1569268099.315 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:19.776Z,1569268099.776 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:19.777Z,1569268099.777 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:19.777Z,1569268099.777 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:20.166Z,1569268100.166 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:20.166Z,1569268100.166 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:20.167Z,1569268100.167 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:20.563Z,1569268100.563 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:20.563Z,1569268100.563 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:20.564Z,1569268100.564 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:20.948Z,1569268100.948 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:20.949Z,1569268100.949 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:20.949Z,1569268100.949 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:21.773Z,1569268101.773 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:48:22.552Z,1569268102.552 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:22.561Z,1569268102.561 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:22.562Z,1569268102.562 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:22.943Z,1569268102.943 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:22.943Z,1569268102.943 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:22.944Z,1569268102.944 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:23.294Z,1569268103.294 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:23.295Z,1569268103.295 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:23.295Z,1569268103.295 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:23.782Z,1569268103.782 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:23.783Z,1569268103.783 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:23.783Z,1569268103.783 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:24.147Z,1569268104.147 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:24.148Z,1569268104.148 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:24.153Z,1569268104.153 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:24.553Z,1569268104.553 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:24.554Z,1569268104.554 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:24.554Z,1569268104.554 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:24.927Z,1569268104.927 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:24.927Z,1569268104.927 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:24.928Z,1569268104.928 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:25.359Z,1569268105.359 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:25.360Z,1569268105.360 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:25.360Z,1569268105.360 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:25.771Z,1569268105.771 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:25.772Z,1569268105.772 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:25.772Z,1569268105.772 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:26.137Z,1569268106.137 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:26.138Z,1569268106.138 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:26.138Z,1569268106.138 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:26.562Z,1569268106.562 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:26.563Z,1569268106.563 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:26.564Z,1569268106.564 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:26.814Z,1569268106.814 [CommandLine](IMPORTANT): got command report touch height_above_sea_floor
2019-09-23T19:48:26.979Z,1569268106.979 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:26.980Z,1569268106.980 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:26.981Z,1569268106.981 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:27.390Z,1569268107.390 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:27.391Z,1569268107.391 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:27.391Z,1569268107.391 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:27.765Z,1569268107.765 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:27.765Z,1569268107.765 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:27.766Z,1569268107.766 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:28.205Z,1569268108.205 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:28.206Z,1569268108.206 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:28.206Z,1569268108.206 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:28.589Z,1569268108.589 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:28.590Z,1569268108.590 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:28.591Z,1569268108.591 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:28.984Z,1569268108.984 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:28.989Z,1569268108.989 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:28.989Z,1569268108.989 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:29.388Z,1569268109.388 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:29.388Z,1569268109.388 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:29.389Z,1569268109.389 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:29.887Z,1569268109.887 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:29.888Z,1569268109.888 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:29.888Z,1569268109.888 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:30.226Z,1569268110.226 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:30.226Z,1569268110.226 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:30.227Z,1569268110.227 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:30.666Z,1569268110.666 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:30.667Z,1569268110.667 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:30.667Z,1569268110.667 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:31.090Z,1569268111.090 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:31.091Z,1569268111.091 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:31.092Z,1569268111.092 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:31.428Z,1569268111.428 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:31.441Z,1569268111.441 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:31.442Z,1569268111.442 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:31.873Z,1569268111.873 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:31.874Z,1569268111.874 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:31.874Z,1569268111.874 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:32.259Z,1569268112.259 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:32.259Z,1569268112.259 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:32.260Z,1569268112.260 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:32.685Z,1569268112.685 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:32.686Z,1569268112.686 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:32.687Z,1569268112.687 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:33.085Z,1569268113.085 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:33.086Z,1569268113.086 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:33.086Z,1569268113.086 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:33.454Z,1569268113.454 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:33.455Z,1569268113.455 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:33.455Z,1569268113.455 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:33.885Z,1569268113.885 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:33.886Z,1569268113.886 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:33.886Z,1569268113.886 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:34.289Z,1569268114.289 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:34.290Z,1569268114.290 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:34.290Z,1569268114.290 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:34.657Z,1569268114.657 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:34.658Z,1569268114.658 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:34.658Z,1569268114.658 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:35.066Z,1569268115.066 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:35.067Z,1569268115.067 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:35.067Z,1569268115.067 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:35.531Z,1569268115.531 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:35.532Z,1569268115.532 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:35.532Z,1569268115.532 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:35.866Z,1569268115.866 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:35.867Z,1569268115.867 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:35.867Z,1569268115.867 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:36.280Z,1569268116.280 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:36.289Z,1569268116.289 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:36.289Z,1569268116.289 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:36.580Z,1569268116.580 [CommandLine](INFO): End of History
2019-09-23T19:48:36.627Z,1569268116.627 [CommandLine](INFO): End of History
2019-09-23T19:48:36.629Z,1569268116.629 [CommandLine](INFO): End of History
2019-09-23T19:48:36.631Z,1569268116.631 [CommandLine](INFO): End of History
2019-09-23T19:48:36.669Z,1569268116.669 [CommandLine](INFO): End of History
2019-09-23T19:48:36.671Z,1569268116.671 [CommandLine](INFO): End of History
2019-09-23T19:48:36.673Z,1569268116.673 [CommandLine](INFO): End of History
2019-09-23T19:48:36.675Z,1569268116.675 [CommandLine](INFO): End of History
2019-09-23T19:48:36.707Z,1569268116.707 [CommandLine](INFO): End of History
2019-09-23T19:48:36.709Z,1569268116.709 [CommandLine](INFO): End of History
2019-09-23T19:48:36.711Z,1569268116.711 [CommandLine](INFO): End of History
2019-09-23T19:48:36.737Z,1569268116.737 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:36.738Z,1569268116.738 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:36.738Z,1569268116.738 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:36.740Z,1569268116.740 [CommandLine](INFO): End of History
2019-09-23T19:48:37.048Z,1569268117.048 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:37.049Z,1569268117.049 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:37.050Z,1569268117.050 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:37.456Z,1569268117.456 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:37.462Z,1569268117.462 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:37.462Z,1569268117.462 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:37.876Z,1569268117.876 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:37.877Z,1569268117.877 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:37.877Z,1569268117.877 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:38.328Z,1569268118.328 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:38.373Z,1569268118.373 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:38.374Z,1569268118.374 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:38.696Z,1569268118.696 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:38.701Z,1569268118.701 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:38.702Z,1569268118.702 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:39.078Z,1569268119.078 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:39.078Z,1569268119.078 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:39.079Z,1569268119.079 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:39.463Z,1569268119.463 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:39.463Z,1569268119.463 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:39.464Z,1569268119.464 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:39.874Z,1569268119.874 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:39.874Z,1569268119.874 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:39.875Z,1569268119.875 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:40.299Z,1569268120.299 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:40.300Z,1569268120.300 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:40.300Z,1569268120.300 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:40.700Z,1569268120.700 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:40.705Z,1569268120.705 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:40.706Z,1569268120.706 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:41.085Z,1569268121.085 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:41.086Z,1569268121.086 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:41.086Z,1569268121.086 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:41.496Z,1569268121.496 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:41.497Z,1569268121.497 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:41.497Z,1569268121.497 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:41.905Z,1569268121.905 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:41.906Z,1569268121.906 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:41.906Z,1569268121.906 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:42.308Z,1569268122.308 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:42.313Z,1569268122.313 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:42.313Z,1569268122.313 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:42.733Z,1569268122.733 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:42.733Z,1569268122.733 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:42.734Z,1569268122.734 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:43.200Z,1569268123.200 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:43.200Z,1569268123.200 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:43.217Z,1569268123.217 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:43.543Z,1569268123.543 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:43.544Z,1569268123.544 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:43.544Z,1569268123.544 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:43.965Z,1569268123.965 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:43.966Z,1569268123.966 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:43.966Z,1569268123.966 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:44.366Z,1569268124.366 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:44.367Z,1569268124.367 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:44.367Z,1569268124.367 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:44.754Z,1569268124.754 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:44.755Z,1569268124.755 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:44.755Z,1569268124.755 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:45.196Z,1569268125.196 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:45.197Z,1569268125.197 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:45.198Z,1569268125.198 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:45.567Z,1569268125.567 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:45.568Z,1569268125.568 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:45.568Z,1569268125.568 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:45.955Z,1569268125.955 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:45.955Z,1569268125.955 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:45.956Z,1569268125.956 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:46.393Z,1569268126.393 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:46.394Z,1569268126.394 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:46.394Z,1569268126.394 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:46.798Z,1569268126.798 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:46.799Z,1569268126.799 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:46.799Z,1569268126.799 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:47.202Z,1569268127.202 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:47.202Z,1569268127.202 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:47.203Z,1569268127.203 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:47.597Z,1569268127.597 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:47.598Z,1569268127.598 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:47.598Z,1569268127.598 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:47.966Z,1569268127.966 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:47.967Z,1569268127.967 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:47.967Z,1569268127.967 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:48.377Z,1569268128.377 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:48.378Z,1569268128.378 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:48.378Z,1569268128.378 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:48.777Z,1569268128.777 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:48.777Z,1569268128.777 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:48.778Z,1569268128.778 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:49.181Z,1569268129.181 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:49.182Z,1569268129.182 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:49.182Z,1569268129.182 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:49.578Z,1569268129.578 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:49.579Z,1569268129.579 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:49.580Z,1569268129.580 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:49.992Z,1569268129.992 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:49.993Z,1569268129.993 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:49.993Z,1569268129.993 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:50.392Z,1569268130.392 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:50.397Z,1569268130.397 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:50.397Z,1569268130.397 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:50.833Z,1569268130.833 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:50.834Z,1569268130.834 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:50.834Z,1569268130.834 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:51.197Z,1569268131.197 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:51.197Z,1569268131.197 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:51.198Z,1569268131.198 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:51.612Z,1569268131.612 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:51.617Z,1569268131.617 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:51.617Z,1569268131.617 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:52.013Z,1569268132.013 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:52.013Z,1569268132.013 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:52.014Z,1569268132.014 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:52.453Z,1569268132.453 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:52.453Z,1569268132.453 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:52.454Z,1569268132.454 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:52.823Z,1569268132.823 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:52.824Z,1569268132.824 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:52.824Z,1569268132.824 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:53.226Z,1569268133.226 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:53.227Z,1569268133.227 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:53.227Z,1569268133.227 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:53.615Z,1569268133.615 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:53.615Z,1569268133.615 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:53.616Z,1569268133.616 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:54.010Z,1569268134.010 [Reporter](INFO): BR_Ping1D.distance 2007.000000 mm
2019-09-23T19:48:54.011Z,1569268134.011 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:48:54.011Z,1569268134.011 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:48:54.038Z,1569268134.038 [CommandLine](IMPORTANT): got command report clear
2019-09-23T19:48:59.477Z,1569268139.477 [CommandLine](IMPORTANT): got command report touch height_above_sea_floor
2019-09-23T19:49:13.382Z,1569268153.382 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-23T19:49:13.382Z,1569268153.382 [Default:CheckIn:C.Wait] Stopped
2019-09-23T19:49:13.383Z,1569268153.383 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-23T19:49:13.383Z,1569268153.383 [Default:CheckIn:D] Running Loop=1
2019-09-23T19:49:13.828Z,1569268153.828 [Default:CheckIn:D] Stopped
2019-09-23T19:49:13.836Z,1569268153.836 [Default:CheckIn:E] Running Loop=1
2019-09-23T19:49:14.223Z,1569268154.223 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.853861 min 
2019-09-23T19:49:14.225Z,1569268154.225 [Default:CheckIn:E] Stopped
2019-09-23T19:49:14.225Z,1569268154.225 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-23T19:49:14.225Z,1569268154.225 [Default:CheckIn] Stopped
2019-09-23T19:49:14.225Z,1569268154.225 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-23T19:49:14.225Z,1569268154.225 [Default:CheckIn](INFO): Running loop #2
2019-09-23T19:49:14.225Z,1569268154.225 [Default:CheckIn] Running Loop=2
2019-09-23T19:49:14.226Z,1569268154.226 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-23T19:49:14.226Z,1569268154.226 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-23T19:49:15.793Z,1569268155.793 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:49:16.176Z,1569268156.176 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194756.00,A,3648.16147,N,12147.27757,W,0.700,0.00,230919,,,A*7E
2019-09-23T19:49:16.178Z,1569268156.178 [NAL9602](INFO): GPS fix at 20190923T194756: (36.802691, -121.787959)
2019-09-23T19:49:16.242Z,1569268156.242 [Default:CheckIn:Read_GPS] Stopped
2019-09-23T19:49:16.243Z,1569268156.243 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-23T19:49:18.869Z,1569268158.869 [CommandLine](IMPORTANT): got command report touch BR_Ping1D.height_above_sea_floor
2019-09-23T19:49:24.788Z,1569268164.788 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20190923T193657/Courier0007.lzma
2019-09-23T19:49:26.794Z,1569268166.794 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193657/Courier0007.lzma.bak
2019-09-23T19:49:26.794Z,1569268166.794 [DataOverHttps](INFO): SBD MOMSN=11804734
2019-09-23T19:49:47.627Z,1569268187.627 [DataOverHttps](INFO): Sending 921 bytes from file Logs/20190923T193657/Express0008.lzma
2019-09-23T19:49:49.630Z,1569268189.630 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193657/Express0008.lzma.bak
2019-09-23T19:49:49.630Z,1569268189.630 [DataOverHttps](INFO): SBD MOMSN=11804738
2019-09-23T19:49:53.970Z,1569268193.970 [Default:CheckIn:Read_Iridium] Stopped
2019-09-23T19:49:53.970Z,1569268193.970 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-23T19:49:53.971Z,1569268193.971 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-23T19:49:59.967Z,1569268199.967 [NAL9602](INFO): SBD MO Status=2, MOMSN=1564, MT Status=2, MTMSN=0
2019-09-23T19:49:59.968Z,1569268199.968 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T19:50:30.295Z,1569268230.295 [NAL9602](INFO): SBD MO Status=2, MOMSN=1564, MT Status=2, MTMSN=0
2019-09-23T19:50:30.295Z,1569268230.295 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T19:51:07.463Z,1569268267.463 [NAL9602](INFO): SBD MO Status=2, MOMSN=1564, MT Status=2, MTMSN=0
2019-09-23T19:51:07.463Z,1569268267.463 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T19:51:48.264Z,1569268308.264 [NAL9602](INFO): SBD MO Status=2, MOMSN=1564, MT Status=2, MTMSN=0
2019-09-23T19:51:48.264Z,1569268308.264 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T19:54:18.146Z,1569268458.146 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-23T19:54:48.850Z,1569268488.850 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-23T19:54:54.563Z,1569268494.563 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-23T19:54:54.563Z,1569268494.563 [Default:CheckIn:C.Wait] Stopped
2019-09-23T19:54:54.563Z,1569268494.563 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-23T19:54:54.563Z,1569268494.563 [Default:CheckIn:D] Running Loop=1
2019-09-23T19:54:55.001Z,1569268495.001 [Default:CheckIn:D] Stopped
2019-09-23T19:54:55.002Z,1569268495.002 [Default:CheckIn:E] Running Loop=1
2019-09-23T19:54:55.377Z,1569268495.377 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.540112 min 
2019-09-23T19:54:55.377Z,1569268495.377 [Default:CheckIn:E] Stopped
2019-09-23T19:54:55.378Z,1569268495.378 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-23T19:54:55.378Z,1569268495.378 [Default:CheckIn] Stopped
2019-09-23T19:54:55.378Z,1569268495.378 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-23T19:54:55.378Z,1569268495.378 [Default:CheckIn](INFO): Running loop #3
2019-09-23T19:54:55.378Z,1569268495.378 [Default:CheckIn] Running Loop=3
2019-09-23T19:54:55.378Z,1569268495.378 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-23T19:54:55.378Z,1569268495.378 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-23T19:54:56.953Z,1569268496.953 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:54:57.341Z,1569268497.341 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195337.00,A,3648.16288,N,12147.28253,W,0.175,0.00,230919,,,A*76
2019-09-23T19:54:57.343Z,1569268497.343 [NAL9602](INFO): GPS fix at 20190923T195337: (36.802715, -121.788042)
2019-09-23T19:54:57.375Z,1569268497.375 [Default:CheckIn:Read_GPS] Stopped
2019-09-23T19:54:57.375Z,1569268497.375 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-23T19:55:07.116Z,1569268507.116 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190923T193657/Courier0010.lzma
2019-09-23T19:55:09.122Z,1569268509.122 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193657/Courier0010.lzma.bak
2019-09-23T19:55:09.122Z,1569268509.122 [DataOverHttps](INFO): SBD MOMSN=11804827
2019-09-23T19:55:28.038Z,1569268528.038 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-09-23T19:55:28.113Z,1569268528.113 [NAL9602](FAULT): received: 
+CSQ:0

OK64, 2, 0, 0, 0

OK
2019-09-23T19:55:28.113Z,1569268528.113 [NAL9602] Data Fault, FailCount= 1
2019-09-23T19:55:28.113Z,1569268528.113 [NAL9602](ERROR): Data Fault
2019-09-23T19:55:28.212Z,1569268528.212 [CBIT](ERROR): Data Fault in component: NAL9602
2019-09-23T19:55:28.443Z,1569268528.443 [NAL9602](INFO): Powering down
2019-09-23T19:55:29.309Z,1569268529.309 [CBIT](INFO): Clearing failed state for component NAL9602
2019-09-23T19:55:29.309Z,1569268529.309 [NAL9602] No Fault, FailCount= 1
2019-09-23T19:55:29.801Z,1569268529.801 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20190923T193657/Express0011.lzma
2019-09-23T19:55:31.806Z,1569268531.806 [DataOverHttps](INFO): Moved sent file to Logs/20190923T193657/Express0011.lzma.bak
2019-09-23T19:55:31.806Z,1569268531.806 [DataOverHttps](INFO): SBD MOMSN=11804830
2019-09-23T19:55:35.793Z,1569268535.793 [Default:CheckIn:Read_Iridium] Stopped
2019-09-23T19:55:35.793Z,1569268535.793 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-23T19:55:35.793Z,1569268535.793 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-23T19:55:54.355Z,1569268554.355 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-23T19:55:54.355Z,1569268554.355 [DVL_micro] No Fault, FailCount= 6
2019-09-23T19:55:54.712Z,1569268554.712 [DVL_micro](INFO): Initializing
2019-09-23T19:55:58.743Z,1569268558.743 [NAL9602](INFO): Powering up NAL9602
2019-09-23T19:56:08.448Z,1569268568.448 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T19:56:08.448Z,1569268568.448 [DVL_micro] Communications Fault, FailCount= 1
2019-09-23T19:56:08.448Z,1569268568.448 [DVL_micro](ERROR): Communications Fault
2019-09-23T19:56:08.512Z,1569268568.512 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T19:56:08.913Z,1569268568.913 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:56:09.650Z,1569268569.650 [NAL9602](INFO): NAL9602 initialized
2019-09-23T19:56:09.716Z,1569268569.717 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-23T19:56:09.720Z,1569268569.720 [DVL_micro] No Fault, FailCount= 1
2019-09-23T19:56:10.067Z,1569268570.067 [DVL_micro](INFO): Initializing
2019-09-23T19:56:23.790Z,1569268583.790 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T19:56:23.790Z,1569268583.790 [DVL_micro] Communications Fault, FailCount= 2
2019-09-23T19:56:23.790Z,1569268583.790 [DVL_micro](ERROR): Communications Fault
2019-09-23T19:56:23.879Z,1569268583.879 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T19:56:24.273Z,1569268584.273 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:56:25.101Z,1569268585.101 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-23T19:56:25.101Z,1569268585.101 [DVL_micro] No Fault, FailCount= 2
2019-09-23T19:56:25.426Z,1569268585.426 [DVL_micro](INFO): Initializing
2019-09-23T19:56:36.381Z,1569268596.381 [CommandLine](IMPORTANT): got command restart application
2019-09-23T19:56:37.388Z,1569268597.388 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:56:37.388Z,1569268597.388 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:37.436Z,1569268597.436 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-09-23T19:56:37.437Z,1569268597.437 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:37.437Z,1569268597.437 [CommandLine](INFO): Join timeout helper Thread ID is 1247
2019-09-23T19:56:37.438Z,1569268597.438 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-09-23T19:56:37.438Z,1569268597.438 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:37.438Z,1569268597.438 [NavChartDb](INFO): Join timeout helper Thread ID is 1248
2019-09-23T19:56:37.653Z,1569268597.653 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:56:37.653Z,1569268597.653 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:37.665Z,1569268597.665 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-09-23T19:56:37.665Z,1569268597.665 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:37.665Z,1569268597.665 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1249
2019-09-23T19:56:37.989Z,1569268597.989 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:56:37.989Z,1569268597.989 [WetLabsBB2FL](INFO): Powering down
2019-09-23T19:56:37.990Z,1569268597.990 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:38.001Z,1569268598.001 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-09-23T19:56:38.001Z,1569268598.001 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:38.001Z,1569268598.001 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1250
2019-09-23T19:56:38.113Z,1569268598.113 [CTD_Seabird](INFO): Powering down
2019-09-23T19:56:38.125Z,1569268598.125 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:56:38.125Z,1569268598.125 [CTD_Seabird](INFO): Powering down
2019-09-23T19:56:38.137Z,1569268598.137 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:38.153Z,1569268598.153 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-09-23T19:56:38.153Z,1569268598.153 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:38.153Z,1569268598.153 [Radio_Surface](INFO): Join timeout helper Thread ID is 1251
2019-09-23T19:56:38.497Z,1569268598.497 [Radio_Surface](INFO): Powering down
2019-09-23T19:56:38.498Z,1569268598.498 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:56:38.498Z,1569268598.498 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:38.513Z,1569268598.513 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-09-23T19:56:38.514Z,1569268598.514 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:38.514Z,1569268598.514 [DataOverHttps](INFO): Join timeout helper Thread ID is 1252
2019-09-23T19:56:38.813Z,1569268598.813 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:56:38.813Z,1569268598.813 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:38.821Z,1569268598.821 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-09-23T19:56:38.822Z,1569268598.822 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:38.822Z,1569268598.822 [logger](INFO): Join timeout helper Thread ID is 1253
2019-09-23T19:56:38.854Z,1569268598.854 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:56:38.854Z,1569268598.854 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:38.869Z,1569268598.869 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-09-23T19:56:38.869Z,1569268598.869 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:38.870Z,1569268598.870 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-09-23T19:56:38.870Z,1569268598.870 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:38.870Z,1569268598.870 [controlThread](INFO): Join timeout helper Thread ID is 1254
2019-09-23T19:56:39.125Z,1569268599.125 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:56:39.125Z,1569268599.125 [controlThread](DEBUG): Uninitializing ControlThread
2019-09-23T19:56:39.125Z,1569268599.125 [AHRS_M2](INFO): Powering down
2019-09-23T19:56:39.269Z,1569268599.269 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:56:39.270Z,1569268599.270 [NAL9602](INFO): Powering down
2019-09-23T19:56:39.271Z,1569268599.271 [DAT](INFO): Powering down
2019-09-23T19:56:39.389Z,1569268599.389 [BR_Ping1D](INFO): Powering down
2019-09-23T19:56:39.461Z,1569268599.461 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-09-23T19:56:39.462Z,1569268599.462 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-09-23T19:56:39.463Z,1569268599.463 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-09-23T19:56:39.463Z,1569268599.463 [MissionManager](INFO): Uninitializing Mission Default
2019-09-23T19:56:39.463Z,1569268599.463 [Default] Stopped
2019-09-23T19:56:39.463Z,1569268599.463 [Default](DEBUG): Aggregate::uninitialize Default
2019-09-23T19:56:39.464Z,1569268599.464 [Default:B.GoToSurface] Stopped
2019-09-23T19:56:39.464Z,1569268599.464 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-23T19:56:39.464Z,1569268599.464 [Default:CheckIn] Stopped
2019-09-23T19:56:39.464Z,1569268599.464 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-23T19:56:39.464Z,1569268599.464 [Default:CheckIn:C.Wait] Stopped
2019-09-23T19:56:39.464Z,1569268599.464 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-23T19:56:39.467Z,1569268599.467 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-09-23T19:56:39.467Z,1569268599.467 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-09-23T19:56:39.467Z,1569268599.467 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-09-23T19:56:39.468Z,1569268599.468 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-09-23T19:56:39.468Z,1569268599.468 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-09-23T19:56:39.468Z,1569268599.468 [BuoyancyServo](INFO): Powering down
2019-09-23T19:56:39.481Z,1569268599.481 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-09-23T19:56:39.481Z,1569268599.481 [ElevatorServo](INFO): Powering down
2019-09-23T19:56:39.482Z,1569268599.482 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-09-23T19:56:39.482Z,1569268599.482 [MassServo](INFO): Powering down
2019-09-23T19:56:39.482Z,1569268599.482 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-23T19:56:39.482Z,1569268599.482 [RudderServo](INFO): Powering down
2019-09-23T19:56:39.483Z,1569268599.483 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-09-23T19:56:39.483Z,1569268599.483 [ThrusterServo](INFO): Powering down
2019-09-23T19:56:39.484Z,1569268599.484 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-09-23T19:56:39.485Z,1569268599.485 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-09-23T19:56:39.485Z,1569268599.485 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-09-23T19:56:39.485Z,1569268599.485 [CBIT](DEBUG): Powering off loads.
2019-09-23T19:56:39.496Z,1569268599.496 [CBIT](DEBUG): Disabling WDT.
2019-09-23T19:56:39.508Z,1569268599.508 [CBIT](DEBUG): Opening all GF detection circuits.
2019-09-23T19:56:39.509Z,1569268599.509 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:39.602Z,1569268599.602 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:39.617Z,1569268599.617 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:39.658Z,1569268599.658 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:39.661Z,1569268599.661 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:39.695Z,1569268599.695 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:56:39.766Z,1569268599.766 [logger ThreadHandler](INFO): Thread cancelled.