2019-09-23T18:59:35.351Z,1569265175.351 [Supervisor](DEBUG): Initializing supervisor.
2019-09-23T18:59:35.354Z,1569265175.354 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-09-23T18:59:35.355Z,1569265175.355 [SyncHandler](INFO): Protected caller Thread ID is 6572
2019-09-23T18:59:35.355Z,1569265175.355 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-09-23T18:59:35.356Z,1569265175.356 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-09-23T18:59:35.357Z,1569265175.357 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6573
2019-09-23T18:59:35.360Z,1569265175.360 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-09-23T18:59:35.371Z,1569265175.371 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-09-23T18:59:35.372Z,1569265175.372 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-09-23T18:59:35.372Z,1569265175.372 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6574
2019-09-23T18:59:35.373Z,1569265175.373 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-09-23T18:59:35.374Z,1569265175.374 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-09-23T18:59:35.375Z,1569265175.375 [logger ThreadHandler](INFO): Protected caller Thread ID is 6575
2019-09-23T18:59:35.376Z,1569265175.376 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-09-23T18:59:35.377Z,1569265175.377 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-09-23T18:59:35.381Z,1569265175.381 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-09-23T18:59:35.938Z,1569265175.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-09-23T18:59:35.939Z,1569265175.939 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-09-23T18:59:36.078Z,1569265176.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-09-23T18:59:36.079Z,1569265176.079 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-09-23T18:59:36.157Z,1569265176.158 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-09-23T18:59:36.254Z,1569265176.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-09-23T18:59:36.255Z,1569265176.255 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-09-23T18:59:36.348Z,1569265176.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-09-23T18:59:36.349Z,1569265176.349 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-09-23T18:59:36.716Z,1569265176.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-09-23T18:59:36.717Z,1569265176.717 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-09-23T18:59:36.816Z,1569265176.816 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-09-23T18:59:36.817Z,1569265176.817 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-09-23T18:59:37.260Z,1569265177.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-09-23T18:59:37.261Z,1569265177.261 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-09-23T18:59:37.453Z,1569265177.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-09-23T18:59:37.454Z,1569265177.454 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-09-23T18:59:37.745Z,1569265177.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-09-23T18:59:37.746Z,1569265177.746 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-09-23T18:59:37.950Z,1569265177.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-09-23T18:59:37.951Z,1569265177.951 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-09-23T18:59:38.088Z,1569265178.088 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-09-23T18:59:38.089Z,1569265178.089 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-09-23T18:59:38.684Z,1569265178.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-09-23T18:59:38.684Z,1569265178.684 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-09-23T18:59:39.019Z,1569265179.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-09-23T18:59:39.020Z,1569265179.020 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-09-23T18:59:39.220Z,1569265179.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-09-23T18:59:39.222Z,1569265179.222 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-09-23T18:59:39.223Z,1569265179.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-09-23T18:59:39.418Z,1569265179.418 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-09-23T18:59:39.499Z,1569265179.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-09-23T18:59:39.629Z,1569265179.629 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-09-23T18:59:39.713Z,1569265179.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-09-23T18:59:39.936Z,1569265179.936 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-09-23T18:59:39.937Z,1569265179.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-09-23T18:59:40.029Z,1569265180.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-09-23T18:59:40.134Z,1569265180.134 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-09-23T18:59:40.226Z,1569265180.226 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-09-23T18:59:40.374Z,1569265180.374 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-09-23T18:59:40.478Z,1569265180.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-09-23T18:59:40.612Z,1569265180.612 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-09-23T18:59:40.613Z,1569265180.613 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-09-23T18:59:40.618Z,1569265180.618 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-09-23T18:59:40.631Z,1569265180.631 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-09-23T18:59:40.632Z,1569265180.632 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-09-23T18:59:40.707Z,1569265180.707 [VerticalControl](DEBUG): Construct VerticalControl.
2019-09-23T18:59:40.906Z,1569265180.906 [VerticalControl] Loaded
2019-09-23T18:59:40.907Z,1569265180.907 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-09-23T18:59:40.907Z,1569265180.907 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-09-23T18:59:41.133Z,1569265181.133 [HorizontalControl] Loaded
2019-09-23T18:59:41.134Z,1569265181.134 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-09-23T18:59:41.134Z,1569265181.134 [SpeedControl](DEBUG): Construct SpeedControl.
2019-09-23T18:59:41.140Z,1569265181.140 [SpeedControl] Loaded
2019-09-23T18:59:41.140Z,1569265181.140 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-09-23T18:59:41.141Z,1569265181.141 [LoopControl](DEBUG): Construct LoopControl.
2019-09-23T18:59:41.141Z,1569265181.141 [LoopControl] Loaded
2019-09-23T18:59:41.142Z,1569265181.142 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-09-23T18:59:41.142Z,1569265181.142 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-09-23T18:59:41.143Z,1569265181.143 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-09-23T18:59:41.326Z,1569265181.326 [BuoyancyServo] Loaded
2019-09-23T18:59:41.327Z,1569265181.327 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-09-23T18:59:41.338Z,1569265181.338 [ElevatorServo] Loaded
2019-09-23T18:59:41.349Z,1569265181.349 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-09-23T18:59:41.395Z,1569265181.395 [MassServo] Loaded
2019-09-23T18:59:41.396Z,1569265181.396 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-09-23T18:59:41.446Z,1569265181.446 [RudderServo] Loaded
2019-09-23T18:59:41.447Z,1569265181.447 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-09-23T18:59:41.506Z,1569265181.506 [ThrusterServo] Loaded
2019-09-23T18:59:41.506Z,1569265181.506 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-09-23T18:59:41.506Z,1569265181.506 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-09-23T18:59:41.507Z,1569265181.507 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-09-23T18:59:41.604Z,1569265181.604 [DepthRateCalculator] Loaded
2019-09-23T18:59:41.604Z,1569265181.604 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-09-23T18:59:41.609Z,1569265181.609 [PitchRateCalculator] Loaded
2019-09-23T18:59:41.610Z,1569265181.610 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-09-23T18:59:41.625Z,1569265181.625 [SpeedCalculator] Loaded
2019-09-23T18:59:41.625Z,1569265181.625 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-09-23T18:59:41.646Z,1569265181.646 [TempGradientCalculator] Loaded
2019-09-23T18:59:41.646Z,1569265181.646 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-09-23T18:59:41.651Z,1569265181.651 [YawRateCalculator] Loaded
2019-09-23T18:59:41.651Z,1569265181.651 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-09-23T18:59:41.681Z,1569265181.681 [ElevatorOffsetCalculator] Loaded
2019-09-23T18:59:41.681Z,1569265181.681 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-09-23T18:59:41.681Z,1569265181.681 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-09-23T18:59:41.682Z,1569265181.682 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-09-23T18:59:41.785Z,1569265181.785 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-09-23T18:59:41.785Z,1569265181.785 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-09-23T18:59:41.805Z,1569265181.805 [NavChart] Loaded
2019-09-23T18:59:41.806Z,1569265181.806 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-09-23T18:59:41.810Z,1569265181.810 [UniversalFixResidualReporter] Loaded
2019-09-23T18:59:41.810Z,1569265181.810 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-09-23T18:59:41.810Z,1569265181.810 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-09-23T18:59:41.811Z,1569265181.811 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-09-23T18:59:42.090Z,1569265182.090 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-09-23T18:59:42.095Z,1569265182.095 [AHRS_M2](INFO): created writer for : platform_orientation
2019-09-23T18:59:42.097Z,1569265182.097 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-09-23T18:59:42.102Z,1569265182.102 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-09-23T18:59:42.103Z,1569265182.103 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-09-23T18:59:42.107Z,1569265182.107 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-09-23T18:59:42.108Z,1569265182.108 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-09-23T18:59:42.113Z,1569265182.113 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-09-23T18:59:42.184Z,1569265182.184 [AHRS_M2] Loaded
2019-09-23T18:59:42.184Z,1569265182.184 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-09-23T18:59:42.257Z,1569265182.257 [DataOverHttps] Loaded
2019-09-23T18:59:42.257Z,1569265182.257 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-09-23T18:59:42.258Z,1569265182.258 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408124E0
2019-09-23T18:59:42.259Z,1569265182.259 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6654
2019-09-23T18:59:42.272Z,1569265182.272 [Depth_Keller] Loaded
2019-09-23T18:59:42.272Z,1569265182.272 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-09-23T18:59:42.277Z,1569265182.277 [DropWeight] Loaded
2019-09-23T18:59:42.278Z,1569265182.278 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-09-23T18:59:42.405Z,1569265182.405 [DVL_micro] Loaded
2019-09-23T18:59:42.405Z,1569265182.405 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-09-23T18:59:42.502Z,1569265182.502 [NAL9602] Loaded
2019-09-23T18:59:42.503Z,1569265182.503 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-09-23T18:59:42.518Z,1569265182.518 [Onboard] Loaded
2019-09-23T18:59:42.518Z,1569265182.518 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-09-23T18:59:42.522Z,1569265182.522 [Radio_Surface] Loaded
2019-09-23T18:59:42.522Z,1569265182.522 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-09-23T18:59:42.523Z,1569265182.523 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408424E0
2019-09-23T18:59:42.523Z,1569265182.523 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6655
2019-09-23T18:59:42.648Z,1569265182.648 [DAT] Loaded
2019-09-23T18:59:42.649Z,1569265182.649 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-09-23T18:59:44.156Z,1569265184.156 [BPC1] Loaded
2019-09-23T18:59:44.157Z,1569265184.157 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-09-23T18:59:44.177Z,1569265184.177 [BR_Ping1D] Loaded
2019-09-23T18:59:44.178Z,1569265184.178 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-09-23T18:59:44.178Z,1569265184.178 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-09-23T18:59:44.179Z,1569265184.179 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-09-23T18:59:44.250Z,1569265184.250 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-09-23T18:59:44.251Z,1569265184.251 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-09-23T18:59:44.348Z,1569265184.348 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-09-23T18:59:44.349Z,1569265184.349 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-09-23T18:59:44.380Z,1569265184.380 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-09-23T18:59:44.381Z,1569265184.381 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-09-23T18:59:44.557Z,1569265184.557 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-09-23T18:59:44.563Z,1569265184.563 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-09-23T18:59:44.563Z,1569265184.563 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-09-23T18:59:44.568Z,1569265184.568 [CTD_Seabird](INFO): created writer for : depth
2019-09-23T18:59:44.568Z,1569265184.568 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-09-23T18:59:44.573Z,1569265184.573 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-09-23T18:59:44.574Z,1569265184.574 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-09-23T18:59:44.579Z,1569265184.579 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-09-23T18:59:44.580Z,1569265184.580 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-09-23T18:59:44.585Z,1569265184.585 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-09-23T18:59:44.585Z,1569265184.585 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-09-23T18:59:44.591Z,1569265184.591 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-09-23T18:59:44.591Z,1569265184.591 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-09-23T18:59:44.596Z,1569265184.596 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-09-23T18:59:44.596Z,1569265184.596 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2019-09-23T18:59:44.602Z,1569265184.602 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2019-09-23T18:59:44.602Z,1569265184.602 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2019-09-23T18:59:44.603Z,1569265184.603 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2019-09-23T18:59:44.635Z,1569265184.635 [CTD_Seabird] Loaded
2019-09-23T18:59:44.635Z,1569265184.635 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-09-23T18:59:44.636Z,1569265184.636 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409D94E0
2019-09-23T18:59:44.637Z,1569265184.637 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6656
2019-09-23T18:59:44.651Z,1569265184.651 [PAR_Licor] Loaded
2019-09-23T18:59:44.652Z,1569265184.652 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-09-23T18:59:44.699Z,1569265184.699 [WetLabsBB2FL] Loaded
2019-09-23T18:59:44.699Z,1569265184.699 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-09-23T18:59:44.700Z,1569265184.700 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A094E0
2019-09-23T18:59:44.701Z,1569265184.701 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6657
2019-09-23T18:59:44.701Z,1569265184.701 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-09-23T18:59:44.702Z,1569265184.702 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-09-23T18:59:44.991Z,1569265184.991 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-09-23T18:59:44.992Z,1569265184.992 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-09-23T18:59:45.127Z,1569265185.127 [SBIT](DEBUG): Construct Startup Built In Test.
2019-09-23T18:59:45.138Z,1569265185.138 [SBIT] Loaded
2019-09-23T18:59:45.138Z,1569265185.138 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-09-23T18:59:45.139Z,1569265185.139 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-09-23T18:59:45.150Z,1569265185.150 [IBIT] Loaded
2019-09-23T18:59:45.151Z,1569265185.151 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-09-23T18:59:45.154Z,1569265185.154 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-09-23T18:59:45.290Z,1569265185.290 [CBIT] Loaded
2019-09-23T18:59:45.291Z,1569265185.291 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-09-23T18:59:45.291Z,1569265185.291 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-09-23T18:59:45.295Z,1569265185.295 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-09-23T18:59:45.296Z,1569265185.296 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-09-23T18:59:45.302Z,1569265185.302 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-09-23T18:59:45.303Z,1569265185.303 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AE84E0
2019-09-23T18:59:45.304Z,1569265185.304 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6658
2019-09-23T18:59:45.308Z,1569265185.308 [Supervisor](INFO): Main Thread ID is 5526
2019-09-23T18:59:45.308Z,1569265185.308 [Supervisor](DEBUG): Running supervisor.
2019-09-23T18:59:45.309Z,1569265185.309 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6659
2019-09-23T18:59:45.311Z,1569265185.311 [controlThread ThreadHandler](INFO): Handler Thread ID is 6660
2019-09-23T18:59:45.312Z,1569265185.312 [controlThread](DEBUG): Initializing ControlThread
2019-09-23T18:59:45.313Z,1569265185.313 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-09-23T18:59:45.314Z,1569265185.314 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-09-23T18:59:45.315Z,1569265185.315 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-09-23T18:59:45.315Z,1569265185.315 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-09-23T18:59:45.317Z,1569265185.317 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-09-23T18:59:45.317Z,1569265185.317 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-09-23T18:59:45.317Z,1569265185.317 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-09-23T18:59:45.318Z,1569265185.318 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-09-23T18:59:45.318Z,1569265185.318 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-09-23T18:59:45.318Z,1569265185.318 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-09-23T18:59:45.319Z,1569265185.319 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-09-23T18:59:45.320Z,1569265185.320 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-09-23T18:59:45.325Z,1569265185.325 [SBIT](INFO): Initialize SBIT Component.
2019-09-23T18:59:45.326Z,1569265185.326 [SBIT](IMPORTANT): git: 2019-09-10-9-g851767d
2019-09-23T18:59:45.326Z,1569265185.326 [SBIT](INFO): git hash: 851767d67e1cfa0383bee058d4dc44c68fc1ad67
2019-09-23T18:59:45.326Z,1569265185.326 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-09-23T18:59:45.327Z,1569265185.327 [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-23T18:59:45.328Z,1569265185.328 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2019-09-23T18:59:45.329Z,1569265185.329 [IBIT](INFO): Initialize IBIT Component.
2019-09-23T18:59:45.330Z,1569265185.330 [CBIT](DEBUG): Initialize CBIT Component.
2019-09-23T18:59:45.331Z,1569265185.331 [logger ThreadHandler](INFO): Handler Thread ID is 6661
2019-09-23T18:59:45.341Z,1569265185.341 [CBIT](DEBUG): Initialized mux pins.
2019-09-23T18:59:45.341Z,1569265185.341 [CBIT](DEBUG): Initializing the watchdog timer.
2019-09-23T18:59:45.349Z,1569265185.349 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6662
2019-09-23T18:59:45.350Z,1569265185.350 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-09-23T18:59:45.365Z,1569265185.365 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6663
2019-09-23T18:59:45.371Z,1569265185.371 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-09-23T18:59:45.371Z,1569265185.371 [CBIT](DEBUG): Initializing heartbeat.
2019-09-23T18:59:45.377Z,1569265185.377 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6664
2019-09-23T18:59:45.378Z,1569265185.378 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-09-23T18:59:45.381Z,1569265185.381 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-09-23T18:59:45.382Z,1569265185.382 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6666
2019-09-23T18:59:45.384Z,1569265185.384 [WetLabsBB2FL](INFO): Powering down
2019-09-23T18:59:45.417Z,1569265185.417 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6667
2019-09-23T18:59:45.422Z,1569265185.422 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-09-23T18:59:45.422Z,1569265185.422 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-09-23T18:59:45.422Z,1569265185.422 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-09-23T18:59:45.423Z,1569265185.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-09-23T18:59:45.423Z,1569265185.423 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-09-23T18:59:45.423Z,1569265185.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-09-23T18:59:45.423Z,1569265185.423 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-09-23T18:59:45.423Z,1569265185.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-09-23T18:59:45.424Z,1569265185.424 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-09-23T18:59:45.424Z,1569265185.424 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-09-23T18:59:45.424Z,1569265185.424 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-09-23T18:59:45.424Z,1569265185.424 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-09-23T18:59:45.424Z,1569265185.424 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-09-23T18:59:45.424Z,1569265185.424 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-09-23T18:59:45.425Z,1569265185.425 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-09-23T18:59:45.425Z,1569265185.425 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-09-23T18:59:45.441Z,1569265185.441 [CBIT](DEBUG): Deactivating GF circuits.
2019-09-23T18:59:45.441Z,1569265185.441 [CBIT](DEBUG): Deactivating emergency mode.
2019-09-23T18:59:45.477Z,1569265185.477 [CBIT](DEBUG): Backplane powered.
2019-09-23T18:59:45.478Z,1569265185.478 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-09-23T18:59:45.520Z,1569265185.520 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-23T18:59:45.582Z,1569265185.582 [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-23T18:59:45.583Z,1569265185.583 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-09-23T18:59:45.637Z,1569265185.637 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-09-23T18:59:45.638Z,1569265185.638 [Default:A.Wait](DEBUG): Construct Wait.
2019-09-23T18:59:45.640Z,1569265185.640 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-23T18:59:45.687Z,1569265185.687 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-09-23T18:59:45.689Z,1569265185.689 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-09-23T18:59:45.724Z,1569265185.724 [Default:E.Execute](DEBUG): Construct Execute.
2019-09-23T18:59:45.734Z,1569265185.734 [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-23T18:59:45.753Z,1569265185.753 [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-23T18:59:45.760Z,1569265185.760 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-23T18:59:45.769Z,1569265185.769 [Radio_Surface](INFO): Powering up
2019-09-23T18:59:45.822Z,1569265185.822 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-09-23T18:59:45.898Z,1569265185.898 [DVL_micro](INFO): Initializing
2019-09-23T18:59:45.978Z,1569265185.978 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-09-23T18:59:45.978Z,1569265185.978 [DAT](INFO): Powering up
2019-09-23T18:59:45.979Z,1569265185.979 [DAT](DEBUG): Initializing DAT.
2019-09-23T18:59:46.014Z,1569265186.014 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-09-23T18:59:46.095Z,1569265186.095 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-09-23T18:59:46.322Z,1569265186.322 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-09-23T18:59:46.329Z,1569265186.329 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-09-23T18:59:46.351Z,1569265186.351 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-09-23T18:59:46.357Z,1569265186.357 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-09-23T18:59:46.371Z,1569265186.371 [MassServo](DEBUG): Initializing EZServoServo.
2019-09-23T18:59:46.373Z,1569265186.373 [MassServo](DEBUG): Initializing MassServo.
2019-09-23T18:59:46.397Z,1569265186.397 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-23T18:59:46.405Z,1569265186.405 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-23T18:59:46.426Z,1569265186.426 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-09-23T18:59:46.433Z,1569265186.433 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-09-23T18:59:47.277Z,1569265187.277 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-09-23T18:59:47.277Z,1569265187.277 [RudderServo](FAULT): Rudder failed to initialize
2019-09-23T18:59:47.277Z,1569265187.277 [RudderServo] Communications Fault, FailCount= 1
2019-09-23T18:59:47.277Z,1569265187.277 [RudderServo](ERROR): Communications Fault
2019-09-23T18:59:47.389Z,1569265187.389 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-09-23T18:59:47.586Z,1569265187.586 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-23T18:59:47.586Z,1569265187.586 [RudderServo](INFO): Powering down
2019-09-23T18:59:48.267Z,1569265188.267 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-23T18:59:48.386Z,1569265188.386 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-23T18:59:48.390Z,1569265188.390 [CBIT](INFO): Clearing failed state for component RudderServo
2019-09-23T18:59:48.390Z,1569265188.390 [RudderServo] No Fault, FailCount= 1
2019-09-23T18:59:51.894Z,1569265191.894 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-09-23T18:59:51.894Z,1569265191.894 [BR_Ping1D] Data Fault, FailCount= 1
2019-09-23T18:59:51.894Z,1569265191.894 [BR_Ping1D](ERROR): Data Fault
2019-09-23T18:59:52.269Z,1569265192.269 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-09-23T18:59:52.334Z,1569265192.334 [BR_Ping1D](INFO): Powering down
2019-09-23T18:59:53.599Z,1569265193.599 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-09-23T18:59:53.599Z,1569265193.599 [BR_Ping1D] No Fault, FailCount= 1
2019-09-23T18:59:56.730Z,1569265196.730 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T18:59:56.730Z,1569265196.730 [DVL_micro] Communications Fault, FailCount= 1
2019-09-23T18:59:56.730Z,1569265196.730 [DVL_micro](ERROR): Communications Fault
2019-09-23T18:59:56.833Z,1569265196.833 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T18:59:57.201Z,1569265197.201 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T18:59:58.029Z,1569265198.029 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-23T18:59:58.029Z,1569265198.029 [DVL_micro] No Fault, FailCount= 1
2019-09-23T18:59:58.348Z,1569265198.348 [DVL_micro](INFO): Initializing
2019-09-23T18:59:59.166Z,1569265199.166 [DAT](INFO): setting local address to 5
2019-09-23T18:59:59.570Z,1569265199.570 [DAT](INFO): set local address to 5
2019-09-23T19:00:06.083Z,1569265206.083 [SBIT](IMPORTANT): Beginning Startup BIT
2019-09-23T19:00:06.096Z,1569265206.096 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-23T19:00:08.966Z,1569265208.966 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T19:00:08.967Z,1569265208.967 [DVL_micro] Communications Fault, FailCount= 2
2019-09-23T19:00:08.967Z,1569265208.967 [DVL_micro](ERROR): Communications Fault
2019-09-23T19:00:09.126Z,1569265209.126 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T19:00:09.433Z,1569265209.433 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:00:10.339Z,1569265210.339 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-23T19:00:10.339Z,1569265210.339 [DVL_micro] No Fault, FailCount= 2
2019-09-23T19:00:10.576Z,1569265210.576 [DVL_micro](INFO): Initializing
2019-09-23T19:00:12.591Z,1569265212.591 [NAL9602](INFO): Powering up NAL9602
2019-09-23T19:00:17.134Z,1569265217.134 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005393
CHAN A1 (24V): 0.001028
CHAN A2 (12V): -0.005117
CHAN A3 (5V): -0.003292
CHAN B0 (3.3V): -0.000857
CHAN B1 (3.15aV): -0.001581
CHAN B2 (3.15bV): -0.001265
CHAN B3 (GND): -0.001992
OPEN: 0.005698
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-23T19:00:21.070Z,1569265221.070 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T19:00:21.070Z,1569265221.070 [DVL_micro] Communications Fault, FailCount= 3
2019-09-23T19:00:21.070Z,1569265221.070 [DVL_micro](ERROR): Communications Fault
2019-09-23T19:00:21.673Z,1569265221.673 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T19:00:21.793Z,1569265221.793 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:00:22.654Z,1569265222.654 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-23T19:00:22.654Z,1569265222.654 [DVL_micro] No Fault, FailCount= 3
2019-09-23T19:00:22.904Z,1569265222.904 [DVL_micro](INFO): Initializing
2019-09-23T19:00:23.707Z,1569265223.707 [NAL9602](INFO): NAL9602 initialized
2019-09-23T19:00:24.566Z,1569265224.566 [NAL9602](DEBUG): Fix Requested
2019-09-23T19:00:26.147Z,1569265226.147 [CommandLine](IMPORTANT): got command report mod BR_Ping1D.distance
2019-09-23T19:00:26.355Z,1569265226.355 [Reporter](INFO): BR_Ping1D.distance no_value
2019-09-23T19:00:36.634Z,1569265236.634 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T19:00:36.634Z,1569265236.634 [DVL_micro] Communications Fault, FailCount= 4
2019-09-23T19:00:36.634Z,1569265236.634 [DVL_micro](ERROR): Communications Fault
2019-09-23T19:00:36.771Z,1569265236.771 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T19:00:37.109Z,1569265237.109 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:00:38.066Z,1569265238.066 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-23T19:00:38.066Z,1569265238.066 [DVL_micro] No Fault, FailCount= 4
2019-09-23T19:00:38.264Z,1569265238.264 [DVL_micro](INFO): Initializing
2019-09-23T19:00:52.151Z,1569265252.151 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-23T19:00:52.151Z,1569265252.151 [DVL_micro] Communications Fault, FailCount= 5
2019-09-23T19:00:52.151Z,1569265252.151 [DVL_micro](ERROR): Communications Fault
2019-09-23T19:00:52.370Z,1569265252.370 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-23T19:00:52.370Z,1569265252.370 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-23T19:00:52.390Z,1569265252.390 [CommandLine](IMPORTANT): got command report mod BR_Ping1D.confidence
2019-09-23T19:00:52.621Z,1569265252.621 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:00:52.764Z,1569265252.764 [CommandLine](FAULT): Scheduling is paused
2019-09-23T19:00:52.765Z,1569265252.765 [CBIT](INFO): Critical error at 20190923T190052
2019-09-23T19:00:52.765Z,1569265252.765 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-09-23T19:00:52.769Z,1569265252.769 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:00:54.284Z,1569265254.284 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:00:54.688Z,1569265254.688 [Reporter](INFO): BR_Ping1D.confidence 7.000000 %
2019-09-23T19:00:55.075Z,1569265255.075 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:00:57.100Z,1569265257.100 [Reporter](INFO): BR_Ping1D.confidence 7.000000 %
2019-09-23T19:00:57.587Z,1569265257.587 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:00:57.866Z,1569265257.866 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:00:59.883Z,1569265259.883 [SBIT](IMPORTANT): SBIT PASSED
2019-09-23T19:00:59.934Z,1569265259.934 [CommandLine](IMPORTANT): got command configSet list
2019-09-23T19:00:59.934Z,1569265259.934 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-09-23T19:00:59.935Z,1569265259.935 [CommandLine](IMPORTANT): BR_Ping1D.verbosity=0 count;

2019-09-23T19:00:59.935Z,1569265259.935 [CommandLine](IMPORTANT): CBIT.gf24Offset=150 microampere;

2019-09-23T19:00:59.935Z,1569265259.935 [CommandLine](IMPORTANT): VerticalControl.massDefault=0 millimeter;

2019-09-23T19:00:59.951Z,1569265259.951 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:00.288Z,1569265260.288 [MissionManager](IMPORTANT): Started mission Startup
2019-09-23T19:01:00.289Z,1569265260.289 [Startup] Running Loop=1
2019-09-23T19:01:00.289Z,1569265260.289 [Startup](DEBUG): Aggregate::initialize Startup
2019-09-23T19:01:00.289Z,1569265260.289 [Startup:A.GoToSurface] Running Loop=1
2019-09-23T19:01:00.289Z,1569265260.289 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-23T19:01:00.290Z,1569265260.290 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-23T19:01:00.290Z,1569265260.290 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-23T19:01:00.291Z,1569265260.291 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-23T19:01:00.291Z,1569265260.291 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-23T19:01:00.291Z,1569265260.291 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-23T19:01:00.297Z,1569265260.297 [Startup:StartupSatComms] Running Loop=1
2019-09-23T19:01:00.297Z,1569265260.297 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-09-23T19:01:00.297Z,1569265260.297 [Startup:StartupSatComms:A] Running Loop=1
2019-09-23T19:01:00.328Z,1569265260.328 [Reporter](INFO): BR_Ping1D.confidence 3.000000 %
2019-09-23T19:01:00.699Z,1569265260.699 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-09-23T19:01:00.712Z,1569265260.712 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:02.739Z,1569265262.739 [Reporter](INFO): BR_Ping1D.confidence 3.000000 %
2019-09-23T19:01:03.119Z,1569265263.119 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:03.524Z,1569265263.524 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:05.582Z,1569265265.582 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:06.388Z,1569265266.388 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:08.374Z,1569265268.374 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:09.163Z,1569265269.163 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:11.249Z,1569265271.249 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:12.024Z,1569265272.024 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:14.042Z,1569265274.042 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:14.827Z,1569265274.827 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:16.861Z,1569265276.861 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:17.696Z,1569265277.696 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:18.867Z,1569265278.867 [CommandLine](IMPORTANT): got command report touch BR_Ping1D.gainIndex
2019-09-23T19:01:19.624Z,1569265279.624 [NAL9602](INFO): SBD MO Status=2, MOMSN=1563, MT Status=2, MTMSN=0
2019-09-23T19:01:19.624Z,1569265279.624 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-23T19:01:19.702Z,1569265279.702 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:20.115Z,1569265280.115 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:20.476Z,1569265280.476 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:20.900Z,1569265280.900 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:22.514Z,1569265282.514 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:22.970Z,1569265282.970 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:23.322Z,1569265283.322 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:23.744Z,1569265283.744 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:25.356Z,1569265285.356 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:25.754Z,1569265285.754 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:26.152Z,1569265286.152 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:26.562Z,1569265286.562 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:28.196Z,1569265288.196 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:28.597Z,1569265288.597 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:29.016Z,1569265289.016 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:29.421Z,1569265289.421 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:31.013Z,1569265291.013 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:31.411Z,1569265291.411 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:31.794Z,1569265291.794 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:32.233Z,1569265292.233 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:33.828Z,1569265293.828 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:34.238Z,1569265294.238 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:34.632Z,1569265294.632 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:35.110Z,1569265295.110 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:36.642Z,1569265296.642 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:37.075Z,1569265297.075 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:37.462Z,1569265297.462 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:37.888Z,1569265297.888 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:39.498Z,1569265299.498 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:39.889Z,1569265299.889 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:40.287Z,1569265300.287 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:40.712Z,1569265300.712 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:42.324Z,1569265302.324 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:42.714Z,1569265302.714 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:43.117Z,1569265303.117 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:43.519Z,1569265303.519 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:45.167Z,1569265305.167 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:45.544Z,1569265305.544 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:45.948Z,1569265305.948 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:46.362Z,1569265306.362 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:47.982Z,1569265307.982 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:48.359Z,1569265308.359 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:48.772Z,1569265308.772 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:49.195Z,1569265309.195 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:50.815Z,1569265310.815 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:51.199Z,1569265311.199 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:51.591Z,1569265311.591 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:52.002Z,1569265312.002 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:53.665Z,1569265313.665 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:54.042Z,1569265314.042 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:54.448Z,1569265314.448 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:54.848Z,1569265314.848 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:56.474Z,1569265316.474 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:56.847Z,1569265316.847 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:01:57.256Z,1569265317.256 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:01:57.670Z,1569265317.670 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:01:59.293Z,1569265319.293 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:01:59.750Z,1569265319.750 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:00.106Z,1569265320.106 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:00.496Z,1569265320.496 [Startup:StartupSatComms:A](INFO): Timed out from 2019-09-23T19:01:00.3Z
2019-09-23T19:02:00.496Z,1569265320.496 [Startup:StartupSatComms:A] Stopped
2019-09-23T19:02:00.496Z,1569265320.496 [Startup:StartupSatComms:B] Running Loop=1
2019-09-23T19:02:00.524Z,1569265320.524 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:00.893Z,1569265320.893 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-09-23T19:02:02.112Z,1569265322.112 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:02.509Z,1569265322.509 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:02.902Z,1569265322.902 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:03.355Z,1569265323.355 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:04.930Z,1569265324.930 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:05.343Z,1569265325.343 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:05.724Z,1569265325.724 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:06.219Z,1569265326.219 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:07.796Z,1569265327.796 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:08.217Z,1569265328.217 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:08.587Z,1569265328.587 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:09.006Z,1569265329.006 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:10.623Z,1569265330.623 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:11.040Z,1569265331.040 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:11.411Z,1569265331.411 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:11.827Z,1569265331.827 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:13.435Z,1569265333.435 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:13.822Z,1569265333.822 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:14.218Z,1569265334.218 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:14.668Z,1569265334.668 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:14.703Z,1569265334.703 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004872
2019-09-23T19:02:16.300Z,1569265336.300 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:16.711Z,1569265336.711 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:17.073Z,1569265337.073 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:17.478Z,1569265337.478 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:19.099Z,1569265339.099 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:19.635Z,1569265339.635 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:19.916Z,1569265339.916 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:20.482Z,1569265340.482 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:21.943Z,1569265341.943 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:22.346Z,1569265342.346 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:22.724Z,1569265342.724 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:23.140Z,1569265343.140 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:24.240Z,1569265344.240 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190923T185200/Courier0004.lzma
2019-09-23T19:02:24.739Z,1569265344.739 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:25.124Z,1569265345.124 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:25.566Z,1569265345.566 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:25.944Z,1569265345.944 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:27.174Z,1569265347.174 [DataOverHttps](INFO): Moved sent file to Logs/20190923T185200/Courier0004.lzma.bak
2019-09-23T19:02:27.174Z,1569265347.174 [DataOverHttps](INFO): SBD MOMSN=11804324
2019-09-23T19:02:27.614Z,1569265347.614 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:27.999Z,1569265347.999 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:28.397Z,1569265348.397 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:28.782Z,1569265348.782 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:30.415Z,1569265350.415 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:30.786Z,1569265350.786 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:31.190Z,1569265351.190 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:31.599Z,1569265351.599 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:33.231Z,1569265353.231 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:33.753Z,1569265353.753 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:34.084Z,1569265354.084 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:34.475Z,1569265354.475 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:36.082Z,1569265356.082 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:36.484Z,1569265356.484 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:36.871Z,1569265356.871 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:37.279Z,1569265357.279 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:38.887Z,1569265358.887 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:39.271Z,1569265359.271 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:39.671Z,1569265359.671 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:40.107Z,1569265360.107 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:41.704Z,1569265361.704 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:42.100Z,1569265362.100 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:42.504Z,1569265362.504 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:42.916Z,1569265362.916 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:44.570Z,1569265364.570 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:44.962Z,1569265364.962 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:45.369Z,1569265365.369 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:45.855Z,1569265365.855 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-09-23T19:02:45.855Z,1569265365.855 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-09-23T19:02:45.880Z,1569265365.880 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-09-23T19:02:45.881Z,1569265365.881 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:46.168Z,1569265366.168 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-09-23T19:02:46.168Z,1569265366.168 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-09-23T19:02:47.396Z,1569265367.396 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:47.767Z,1569265367.767 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:48.156Z,1569265368.156 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:48.584Z,1569265368.584 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:48.664Z,1569265368.664 [DataOverHttps](INFO): Sending 102 bytes from file Logs/20190923T185935/Courier0000.lzma
2019-09-23T19:02:50.166Z,1569265370.166 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:50.587Z,1569265370.587 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:50.670Z,1569265370.670 [DataOverHttps](INFO): Moved sent file to Logs/20190923T185935/Courier0000.lzma.bak
2019-09-23T19:02:50.670Z,1569265370.670 [DataOverHttps](INFO): SBD MOMSN=11804327
2019-09-23T19:02:51.005Z,1569265371.005 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:51.415Z,1569265371.415 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:53.066Z,1569265373.066 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:53.462Z,1569265373.462 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:53.819Z,1569265373.819 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:54.274Z,1569265374.274 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:55.868Z,1569265375.868 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:56.250Z,1569265376.250 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:56.639Z,1569265376.639 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:57.070Z,1569265377.070 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:02:58.660Z,1569265378.660 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:02:59.072Z,1569265379.072 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:02:59.793Z,1569265379.793 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:02:59.989Z,1569265379.989 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:00.685Z,1569265380.685 [Startup:StartupSatComms:B](INFO): Timed out from 2019-09-23T19:02:00.5Z
2019-09-23T19:03:00.685Z,1569265380.685 [Startup:StartupSatComms:B] Stopped
2019-09-23T19:03:00.685Z,1569265380.685 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-09-23T19:03:00.685Z,1569265380.685 [Startup:StartupSatComms] Stopped
2019-09-23T19:03:00.685Z,1569265380.685 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-09-23T19:03:00.686Z,1569265380.686 [Startup](INFO): Completed Startup
2019-09-23T19:03:00.686Z,1569265380.686 [MissionManager](INFO): Startup is completed.
2019-09-23T19:03:00.687Z,1569265380.687 [MissionManager](INFO): Uninitializing Mission Startup
2019-09-23T19:03:00.687Z,1569265380.687 [Startup] Stopped
2019-09-23T19:03:00.687Z,1569265380.687 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-09-23T19:03:00.687Z,1569265380.687 [Startup:A.GoToSurface] Stopped
2019-09-23T19:03:00.687Z,1569265380.687 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-23T19:03:01.098Z,1569265381.098 [MissionManager](IMPORTANT): Started mission Default
2019-09-23T19:03:01.098Z,1569265381.098 [Default] Running Loop=1
2019-09-23T19:03:01.098Z,1569265381.098 [Default](DEBUG): Aggregate::initialize Default
2019-09-23T19:03:01.098Z,1569265381.098 [Default:B.GoToSurface] Running Loop=1
2019-09-23T19:03:01.098Z,1569265381.098 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-23T19:03:01.099Z,1569265381.099 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-23T19:03:01.099Z,1569265381.099 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-23T19:03:01.099Z,1569265381.099 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-23T19:03:01.099Z,1569265381.099 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-23T19:03:01.100Z,1569265381.100 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-23T19:03:01.100Z,1569265381.100 [Default:A.Wait] Running Loop=1
2019-09-23T19:03:01.100Z,1569265381.100 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-09-23T19:03:01.532Z,1569265381.532 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:01.942Z,1569265381.942 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:02.323Z,1569265382.323 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:02.739Z,1569265382.739 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:04.359Z,1569265384.359 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:04.744Z,1569265384.744 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:05.118Z,1569265385.118 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:05.572Z,1569265385.572 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:07.179Z,1569265387.179 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:07.559Z,1569265387.559 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:07.951Z,1569265387.951 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:08.376Z,1569265388.376 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:09.987Z,1569265389.987 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:10.387Z,1569265390.387 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:10.780Z,1569265390.780 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:11.187Z,1569265391.187 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:12.958Z,1569265392.958 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:13.378Z,1569265393.378 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:13.671Z,1569265393.671 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:14.086Z,1569265394.086 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:14.436Z,1569265394.436 [Default:A.Wait](INFO): Done Waiting.
2019-09-23T19:03:14.460Z,1569265394.460 [Default:A.Wait] Stopped
2019-09-23T19:03:14.460Z,1569265394.460 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-09-23T19:03:14.844Z,1569265394.844 [Default:CheckIn] Running Loop=1
2019-09-23T19:03:14.844Z,1569265394.844 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-23T19:03:14.857Z,1569265394.857 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-23T19:03:15.228Z,1569265395.228 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-09-23T19:03:15.718Z,1569265395.718 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:16.050Z,1569265396.050 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:16.501Z,1569265396.501 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:16.922Z,1569265396.922 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:18.539Z,1569265398.539 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:18.951Z,1569265398.951 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:19.326Z,1569265399.326 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:19.777Z,1569265399.777 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:21.292Z,1569265401.292 [DataOverHttps](INFO): Sending 848 bytes from file Logs/20190923T185200/Express0001.lzma
2019-09-23T19:03:21.369Z,1569265401.369 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:21.743Z,1569265401.743 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:22.120Z,1569265402.120 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:22.556Z,1569265402.556 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:23.294Z,1569265403.294 [DataOverHttps](INFO): Moved sent file to Logs/20190923T185200/Express0001.lzma.bak
2019-09-23T19:03:23.295Z,1569265403.295 [DataOverHttps](INFO): SBD MOMSN=11804331
2019-09-23T19:03:24.168Z,1569265404.168 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:24.583Z,1569265404.583 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:24.954Z,1569265404.954 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:25.414Z,1569265405.414 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:27.083Z,1569265407.083 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:27.440Z,1569265407.440 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:27.838Z,1569265407.838 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:28.235Z,1569265408.235 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:29.837Z,1569265409.837 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:30.222Z,1569265410.222 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:30.616Z,1569265410.616 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:31.048Z,1569265411.048 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:32.686Z,1569265412.686 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:33.051Z,1569265413.051 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:33.449Z,1569265413.449 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:33.853Z,1569265413.853 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:35.469Z,1569265415.469 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:35.912Z,1569265415.912 [Reporter](INFO): BR_Ping1D.gainIndex 6 count
2019-09-23T19:03:36.260Z,1569265416.260 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:36.729Z,1569265416.729 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:38.321Z,1569265418.321 [Reporter](INFO): BR_Ping1D.confidence 0.000000 %
2019-09-23T19:03:39.123Z,1569265419.123 [Reporter](INFO): BR_Ping1D.confidence 63.000000 %
2019-09-23T19:03:39.124Z,1569265419.124 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:03:39.825Z,1569265419.825 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:40.012Z,1569265420.012 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:41.594Z,1569265421.594 [Reporter](INFO): BR_Ping1D.confidence 63.000000 %
2019-09-23T19:03:42.383Z,1569265422.383 [Reporter](INFO): BR_Ping1D.confidence 78.000000 %
2019-09-23T19:03:42.383Z,1569265422.383 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:03:42.788Z,1569265422.788 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:43.216Z,1569265423.216 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:44.801Z,1569265424.801 [Reporter](INFO): BR_Ping1D.confidence 78.000000 %
2019-09-23T19:03:45.211Z,1569265425.211 [Reporter](INFO): BR_Ping1D.confidence 90.000000 %
2019-09-23T19:03:45.211Z,1569265425.211 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:03:45.620Z,1569265425.620 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:46.012Z,1569265426.012 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:47.654Z,1569265427.654 [Reporter](INFO): BR_Ping1D.confidence 90.000000 %
2019-09-23T19:03:48.511Z,1569265428.511 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:03:48.512Z,1569265428.512 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:03:48.870Z,1569265428.870 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:49.339Z,1569265429.339 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:50.882Z,1569265430.882 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:03:51.290Z,1569265431.290 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:03:51.704Z,1569265431.704 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:52.100Z,1569265432.100 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:54.047Z,1569265434.047 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:03:54.316Z,1569265434.316 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:03:54.715Z,1569265434.715 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:55.141Z,1569265435.141 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:56.715Z,1569265436.715 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:03:57.098Z,1569265437.098 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:03:57.497Z,1569265437.497 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:03:57.956Z,1569265437.956 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:03:59.589Z,1569265439.589 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:04:00.359Z,1569265440.359 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:04:00.788Z,1569265440.788 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:04:01.188Z,1569265441.188 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:04:02.786Z,1569265442.786 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:04:03.160Z,1569265443.160 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:04:03.569Z,1569265443.569 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:04:04.025Z,1569265444.025 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:04:05.587Z,1569265445.587 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:04:05.981Z,1569265445.981 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:04:06.349Z,1569265446.349 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:04:06.920Z,1569265446.920 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:04:08.433Z,1569265448.433 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:04:08.800Z,1569265448.800 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:04:09.211Z,1569265449.211 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:04:09.602Z,1569265449.602 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:04:11.215Z,1569265451.215 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:04:12.013Z,1569265452.013 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:04:12.425Z,1569265452.425 [Reporter](INFO): BR_Ping1D.confidence no_value
2019-09-23T19:04:12.786Z,1569265452.786 [CommandLine](IMPORTANT): got command restart application
2019-09-23T19:04:12.830Z,1569265452.830 [Reporter](INFO): BR_Ping1D.gainIndex 4 count
2019-09-23T19:04:13.794Z,1569265453.794 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:04:13.794Z,1569265453.794 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:13.893Z,1569265453.893 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-09-23T19:04:13.893Z,1569265453.893 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:13.914Z,1569265453.914 [CommandLine](INFO): Join timeout helper Thread ID is 6698
2019-09-23T19:04:13.929Z,1569265453.929 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-09-23T19:04:13.929Z,1569265453.929 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:13.930Z,1569265453.930 [NavChartDb](INFO): Join timeout helper Thread ID is 6699
2019-09-23T19:04:13.989Z,1569265453.989 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:04:13.989Z,1569265453.989 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.001Z,1569265454.001 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-09-23T19:04:14.001Z,1569265454.001 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.001Z,1569265454.001 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6700
2019-09-23T19:04:14.165Z,1569265454.165 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:04:14.165Z,1569265454.165 [WetLabsBB2FL](INFO): Powering down
2019-09-23T19:04:14.166Z,1569265454.166 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.177Z,1569265454.177 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-09-23T19:04:14.177Z,1569265454.177 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.177Z,1569265454.177 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6701
2019-09-23T19:04:14.436Z,1569265454.436 [Reporter](INFO): BR_Ping1D.confidence 100.000000 %
2019-09-23T19:04:14.473Z,1569265454.473 [CTD_Seabird](INFO): Powering down
2019-09-23T19:04:14.485Z,1569265454.485 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:04:14.485Z,1569265454.485 [CTD_Seabird](INFO): Powering down
2019-09-23T19:04:14.497Z,1569265454.497 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.509Z,1569265454.509 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-09-23T19:04:14.509Z,1569265454.509 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.509Z,1569265454.509 [Radio_Surface](INFO): Join timeout helper Thread ID is 6702
2019-09-23T19:04:14.510Z,1569265454.510 [Radio_Surface](INFO): Powering down
2019-09-23T19:04:14.511Z,1569265454.511 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:04:14.511Z,1569265454.511 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.512Z,1569265454.512 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-09-23T19:04:14.523Z,1569265454.523 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.523Z,1569265454.523 [DataOverHttps](INFO): Join timeout helper Thread ID is 6703
2019-09-23T19:04:14.858Z,1569265454.858 [Reporter](INFO): BR_Ping1D.gainIndex 3 count
2019-09-23T19:04:14.941Z,1569265454.941 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:04:14.941Z,1569265454.941 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.958Z,1569265454.958 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-09-23T19:04:14.958Z,1569265454.958 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.958Z,1569265454.958 [logger](INFO): Join timeout helper Thread ID is 6704
2019-09-23T19:04:14.981Z,1569265454.981 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:04:14.981Z,1569265454.981 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.998Z,1569265454.998 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-09-23T19:04:14.998Z,1569265454.998 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.998Z,1569265454.998 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-09-23T19:04:14.998Z,1569265454.998 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:14.998Z,1569265454.998 [controlThread](INFO): Join timeout helper Thread ID is 6705
2019-09-23T19:04:15.129Z,1569265455.129 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-23T19:04:15.129Z,1569265455.129 [controlThread](DEBUG): Uninitializing ControlThread
2019-09-23T19:04:15.130Z,1569265455.130 [AHRS_M2](INFO): Powering down
2019-09-23T19:04:15.202Z,1569265455.202 [DVL_micro](INFO): uninitialize:Powering down
2019-09-23T19:04:15.203Z,1569265455.203 [NAL9602](INFO): Powering down
2019-09-23T19:04:15.204Z,1569265455.204 [DAT](INFO): Powering down
2019-09-23T19:04:15.326Z,1569265455.326 [BR_Ping1D](INFO): Powering down
2019-09-23T19:04:15.398Z,1569265455.398 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-09-23T19:04:15.399Z,1569265455.399 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-09-23T19:04:15.399Z,1569265455.399 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-09-23T19:04:15.400Z,1569265455.400 [MissionManager](INFO): Uninitializing Mission Default
2019-09-23T19:04:15.400Z,1569265455.400 [Default] Stopped
2019-09-23T19:04:15.400Z,1569265455.400 [Default](DEBUG): Aggregate::uninitialize Default
2019-09-23T19:04:15.400Z,1569265455.400 [Default:B.GoToSurface] Stopped
2019-09-23T19:04:15.400Z,1569265455.400 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-23T19:04:15.400Z,1569265455.400 [Default:CheckIn] Stopped
2019-09-23T19:04:15.400Z,1569265455.400 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-23T19:04:15.400Z,1569265455.400 [Default:CheckIn:Read_GPS] Stopped
2019-09-23T19:04:15.403Z,1569265455.403 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-09-23T19:04:15.403Z,1569265455.403 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-09-23T19:04:15.404Z,1569265455.404 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-09-23T19:04:15.404Z,1569265455.404 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-09-23T19:04:15.404Z,1569265455.404 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-09-23T19:04:15.404Z,1569265455.404 [BuoyancyServo](INFO): Powering down
2019-09-23T19:04:15.417Z,1569265455.417 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-09-23T19:04:15.417Z,1569265455.417 [ElevatorServo](INFO): Powering down
2019-09-23T19:04:15.418Z,1569265455.418 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-09-23T19:04:15.418Z,1569265455.418 [MassServo](INFO): Powering down
2019-09-23T19:04:15.419Z,1569265455.419 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-23T19:04:15.419Z,1569265455.419 [RudderServo](INFO): Powering down
2019-09-23T19:04:15.420Z,1569265455.420 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-09-23T19:04:15.420Z,1569265455.420 [ThrusterServo](INFO): Powering down
2019-09-23T19:04:15.421Z,1569265455.421 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-09-23T19:04:15.421Z,1569265455.421 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-09-23T19:04:15.421Z,1569265455.421 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-09-23T19:04:15.422Z,1569265455.422 [CBIT](DEBUG): Powering off loads.
2019-09-23T19:04:15.433Z,1569265455.433 [CBIT](DEBUG): Disabling WDT.
2019-09-23T19:04:15.445Z,1569265455.445 [CBIT](DEBUG): Opening all GF detection circuits.
2019-09-23T19:04:15.446Z,1569265455.446 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:15.748Z,1569265455.748 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:15.758Z,1569265455.758 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:15.800Z,1569265455.800 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:15.803Z,1569265455.803 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:15.837Z,1569265455.837 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-23T19:04:15.893Z,1569265455.893 [logger ThreadHandler](INFO): Thread cancelled.