2020-07-16T14:24:46.502Z,1594909486.502 [Supervisor](DEBUG): Initializing supervisor.
2020-07-16T14:24:46.505Z,1594909486.505 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-07-16T14:24:46.506Z,1594909486.506 [SyncHandler](INFO): Protected caller Thread ID is 820
2020-07-16T14:24:46.506Z,1594909486.506 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-07-16T14:24:46.507Z,1594909486.507 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-07-16T14:24:46.507Z,1594909486.507 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 821
2020-07-16T14:24:46.510Z,1594909486.510 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-07-16T14:24:46.524Z,1594909486.524 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-07-16T14:24:46.525Z,1594909486.525 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-07-16T14:24:46.525Z,1594909486.525 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 822
2020-07-16T14:24:46.526Z,1594909486.526 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-07-16T14:24:46.527Z,1594909486.527 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-07-16T14:24:46.527Z,1594909486.527 [logger ThreadHandler](INFO): Protected caller Thread ID is 823
2020-07-16T14:24:46.529Z,1594909486.529 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-07-16T14:24:46.529Z,1594909486.529 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-07-16T14:24:46.533Z,1594909486.533 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-07-16T14:24:46.630Z,1594909486.630 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-07-16T14:24:46.631Z,1594909486.631 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-07-16T14:24:46.835Z,1594909486.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-07-16T14:24:46.837Z,1594909486.837 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-07-16T14:24:47.048Z,1594909487.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-07-16T14:24:47.049Z,1594909487.049 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-07-16T14:24:47.188Z,1594909487.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-07-16T14:24:47.189Z,1594909487.189 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-07-16T14:24:47.812Z,1594909487.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-07-16T14:24:47.813Z,1594909487.813 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-07-16T14:24:48.292Z,1594909488.292 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-07-16T14:24:48.293Z,1594909488.293 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-07-16T14:24:48.777Z,1594909488.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-07-16T14:24:48.777Z,1594909488.777 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-07-16T14:24:49.092Z,1594909489.092 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-07-16T14:24:49.094Z,1594909489.094 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-07-16T14:24:49.437Z,1594909489.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-07-16T14:24:49.438Z,1594909489.438 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-07-16T14:24:49.870Z,1594909489.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-07-16T14:24:49.871Z,1594909489.871 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-07-16T14:24:50.418Z,1594909490.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-07-16T14:24:50.420Z,1594909490.420 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-07-16T14:24:50.532Z,1594909490.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-07-16T14:24:50.533Z,1594909490.533 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-07-16T14:24:50.616Z,1594909490.616 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-07-16T14:24:50.719Z,1594909490.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-07-16T14:24:50.719Z,1594909490.719 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-07-16T14:24:50.918Z,1594909490.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-07-16T14:24:50.920Z,1594909490.920 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-07-16T14:24:51.134Z,1594909491.134 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-07-16T14:24:51.136Z,1594909491.136 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2020-07-16T14:24:51.138Z,1594909491.138 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2020-07-16T14:24:51.223Z,1594909491.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2020-07-16T14:24:51.462Z,1594909491.462 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-07-16T14:24:51.464Z,1594909491.464 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2020-07-16T14:24:51.560Z,1594909491.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2020-07-16T14:24:51.728Z,1594909491.728 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2020-07-16T14:24:51.935Z,1594909491.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2020-07-16T14:24:52.062Z,1594909492.062 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2020-07-16T14:24:52.207Z,1594909492.207 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2020-07-16T14:24:52.302Z,1594909492.302 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2020-07-16T14:24:52.457Z,1594909492.457 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2020-07-16T14:24:52.562Z,1594909492.562 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2020-07-16T14:24:52.661Z,1594909492.661 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-07-16T14:24:52.671Z,1594909492.671 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-07-16T14:24:52.800Z,1594909492.800 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-07-16T14:24:52.802Z,1594909492.802 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-07-16T14:24:52.980Z,1594909492.980 [BuoyancyServo] Loaded
2020-07-16T14:24:52.981Z,1594909492.981 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-07-16T14:24:53.014Z,1594909493.014 [ElevatorServo] Loaded
2020-07-16T14:24:53.014Z,1594909493.014 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-07-16T14:24:53.046Z,1594909493.046 [MassServo] Loaded
2020-07-16T14:24:53.046Z,1594909493.046 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-07-16T14:24:53.079Z,1594909493.079 [RudderServo] Loaded
2020-07-16T14:24:53.079Z,1594909493.079 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-07-16T14:24:53.110Z,1594909493.110 [ThrusterServo] Loaded
2020-07-16T14:24:53.110Z,1594909493.110 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-07-16T14:24:53.111Z,1594909493.111 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-07-16T14:24:53.113Z,1594909493.113 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-07-16T14:24:53.282Z,1594909493.282 [SBIT](DEBUG): Construct Startup Built In Test.
2020-07-16T14:24:53.311Z,1594909493.311 [SBIT] Loaded
2020-07-16T14:24:53.312Z,1594909493.312 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-07-16T14:24:53.312Z,1594909493.312 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-07-16T14:24:53.343Z,1594909493.343 [IBIT] Loaded
2020-07-16T14:24:53.343Z,1594909493.343 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-07-16T14:24:53.346Z,1594909493.346 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-07-16T14:24:53.745Z,1594909493.745 [CBIT] Loaded
2020-07-16T14:24:53.745Z,1594909493.745 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-07-16T14:24:53.745Z,1594909493.745 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-07-16T14:24:53.746Z,1594909493.746 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-07-16T14:24:54.049Z,1594909494.049 [Aanderaa_O2] Loaded
2020-07-16T14:24:54.049Z,1594909494.049 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2020-07-16T14:24:54.078Z,1594909494.078 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-07-16T14:24:54.083Z,1594909494.083 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-07-16T14:24:54.085Z,1594909494.085 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-07-16T14:24:54.089Z,1594909494.089 [CTD_Seabird](INFO): created writer for : depth
2020-07-16T14:24:54.090Z,1594909494.090 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-07-16T14:24:54.095Z,1594909494.095 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-07-16T14:24:54.096Z,1594909494.096 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-07-16T14:24:54.101Z,1594909494.101 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-07-16T14:24:54.102Z,1594909494.102 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-07-16T14:24:54.107Z,1594909494.107 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-07-16T14:24:54.108Z,1594909494.108 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-07-16T14:24:54.113Z,1594909494.113 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-07-16T14:24:54.114Z,1594909494.114 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-07-16T14:24:54.119Z,1594909494.119 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-07-16T14:24:54.147Z,1594909494.147 [CTD_Seabird] Loaded
2020-07-16T14:24:54.147Z,1594909494.147 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-07-16T14:24:54.148Z,1594909494.148 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406764E0
2020-07-16T14:24:54.149Z,1594909494.149 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 906
2020-07-16T14:24:54.199Z,1594909494.199 [ESPComponent] Loaded
2020-07-16T14:24:54.200Z,1594909494.200 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-07-16T14:24:54.231Z,1594909494.231 [PAR_Licor] Loaded
2020-07-16T14:24:54.231Z,1594909494.231 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-07-16T14:24:54.254Z,1594909494.254 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-07-16T14:24:54.254Z,1594909494.254 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-07-16T14:24:54.259Z,1594909494.259 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-07-16T14:24:54.259Z,1594909494.259 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-07-16T14:24:54.263Z,1594909494.263 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-07-16T14:24:54.263Z,1594909494.263 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-07-16T14:24:54.268Z,1594909494.268 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-07-16T14:24:54.268Z,1594909494.268 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-07-16T14:24:54.272Z,1594909494.272 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-07-16T14:24:54.273Z,1594909494.273 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-07-16T14:24:54.277Z,1594909494.277 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-07-16T14:24:54.277Z,1594909494.277 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-07-16T14:24:54.281Z,1594909494.281 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-07-16T14:24:54.282Z,1594909494.282 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-07-16T14:24:54.286Z,1594909494.286 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-07-16T14:24:54.291Z,1594909494.291 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-07-16T14:24:54.291Z,1594909494.291 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-16T14:24:54.291Z,1594909494.291 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-16T14:24:54.295Z,1594909494.295 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-16T14:24:54.296Z,1594909494.296 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-16T14:24:54.300Z,1594909494.300 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-16T14:24:54.300Z,1594909494.300 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-16T14:24:54.305Z,1594909494.305 [WetLabsBB2FL] Loaded
2020-07-16T14:24:54.305Z,1594909494.305 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-07-16T14:24:54.306Z,1594909494.306 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A64E0
2020-07-16T14:24:54.307Z,1594909494.307 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 907
2020-07-16T14:24:54.307Z,1594909494.307 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-07-16T14:24:54.308Z,1594909494.308 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-07-16T14:24:54.363Z,1594909494.363 [DepthRateCalculator] Loaded
2020-07-16T14:24:54.363Z,1594909494.363 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-07-16T14:24:54.369Z,1594909494.369 [PitchRateCalculator] Loaded
2020-07-16T14:24:54.370Z,1594909494.370 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-07-16T14:24:54.386Z,1594909494.386 [SpeedCalculator] Loaded
2020-07-16T14:24:54.386Z,1594909494.386 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-07-16T14:24:54.408Z,1594909494.408 [TempGradientCalculator] Loaded
2020-07-16T14:24:54.408Z,1594909494.408 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-07-16T14:24:54.414Z,1594909494.414 [YawRateCalculator] Loaded
2020-07-16T14:24:54.414Z,1594909494.414 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-07-16T14:24:54.448Z,1594909494.448 [ElevatorOffsetCalculator] Loaded
2020-07-16T14:24:54.448Z,1594909494.448 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-07-16T14:24:54.448Z,1594909494.448 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-07-16T14:24:54.449Z,1594909494.449 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-07-16T14:24:54.552Z,1594909494.552 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-07-16T14:24:54.553Z,1594909494.553 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-07-16T14:24:54.594Z,1594909494.594 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-07-16T14:24:54.595Z,1594909494.595 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-07-16T14:24:54.734Z,1594909494.734 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-07-16T14:24:54.734Z,1594909494.734 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-07-16T14:24:54.756Z,1594909494.756 [NavChart] Loaded
2020-07-16T14:24:54.756Z,1594909494.756 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-07-16T14:24:54.760Z,1594909494.760 [UniversalFixResidualReporter] Loaded
2020-07-16T14:24:54.760Z,1594909494.760 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-07-16T14:24:54.761Z,1594909494.761 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-07-16T14:24:54.763Z,1594909494.763 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-07-16T14:24:55.747Z,1594909495.747 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-07-16T14:24:55.752Z,1594909495.752 [AHRS_M2](INFO): created writer for : platform_orientation
2020-07-16T14:24:55.752Z,1594909495.752 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-07-16T14:24:55.757Z,1594909495.757 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-07-16T14:24:55.758Z,1594909495.758 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-07-16T14:24:55.763Z,1594909495.763 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-07-16T14:24:55.764Z,1594909495.764 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-07-16T14:24:55.769Z,1594909495.769 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-07-16T14:24:55.954Z,1594909495.954 [AHRS_M2] Loaded
2020-07-16T14:24:55.954Z,1594909495.954 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-07-16T14:24:56.101Z,1594909496.101 [DataOverHttps] Loaded
2020-07-16T14:24:56.102Z,1594909496.102 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-07-16T14:24:56.103Z,1594909496.103 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409C64E0
2020-07-16T14:24:56.103Z,1594909496.103 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 908
2020-07-16T14:24:56.134Z,1594909496.134 [Depth_Keller] Loaded
2020-07-16T14:24:56.134Z,1594909496.134 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-07-16T14:24:56.139Z,1594909496.139 [DropWeight] Loaded
2020-07-16T14:24:56.140Z,1594909496.140 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-07-16T14:24:56.206Z,1594909496.206 [DVL_micro] Loaded
2020-07-16T14:24:56.206Z,1594909496.206 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2020-07-16T14:24:56.317Z,1594909496.317 [NAL9602] Loaded
2020-07-16T14:24:56.318Z,1594909496.318 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-07-16T14:24:56.357Z,1594909496.357 [Onboard] Loaded
2020-07-16T14:24:56.357Z,1594909496.357 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-07-16T14:24:56.358Z,1594909496.358 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409F64E0
2020-07-16T14:24:56.359Z,1594909496.359 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 909
2020-07-16T14:24:56.379Z,1594909496.379 [Radio_Surface] Loaded
2020-07-16T14:24:56.379Z,1594909496.379 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-07-16T14:24:56.380Z,1594909496.380 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A264E0
2020-07-16T14:24:56.381Z,1594909496.381 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 910
2020-07-16T14:24:57.709Z,1594909497.709 [BPC1] Loaded
2020-07-16T14:24:57.709Z,1594909497.709 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-07-16T14:24:57.709Z,1594909497.709 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-07-16T14:24:57.710Z,1594909497.710 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-07-16T14:24:57.836Z,1594909497.836 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-07-16T14:24:57.837Z,1594909497.837 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-07-16T14:24:57.901Z,1594909497.901 [VerticalControl](DEBUG): Construct VerticalControl.
2020-07-16T14:24:57.988Z,1594909497.988 [VerticalControl] Loaded
2020-07-16T14:24:57.989Z,1594909497.989 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-07-16T14:24:57.989Z,1594909497.989 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-07-16T14:24:58.064Z,1594909498.064 [HorizontalControl] Loaded
2020-07-16T14:24:58.064Z,1594909498.064 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-07-16T14:24:58.065Z,1594909498.065 [SpeedControl](DEBUG): Construct SpeedControl.
2020-07-16T14:24:58.067Z,1594909498.067 [SpeedControl] Loaded
2020-07-16T14:24:58.067Z,1594909498.067 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-07-16T14:24:58.067Z,1594909498.067 [LoopControl](DEBUG): Construct LoopControl.
2020-07-16T14:24:58.068Z,1594909498.068 [LoopControl] Loaded
2020-07-16T14:24:58.068Z,1594909498.068 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-07-16T14:24:58.069Z,1594909498.069 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-07-16T14:24:58.069Z,1594909498.069 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-07-16T14:24:58.100Z,1594909498.100 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-07-16T14:24:58.100Z,1594909498.100 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-07-16T14:24:58.470Z,1594909498.470 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-07-16T14:24:58.474Z,1594909498.474 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-07-16T14:24:58.475Z,1594909498.475 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-07-16T14:24:58.481Z,1594909498.481 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-07-16T14:24:58.482Z,1594909498.482 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B5D4E0
2020-07-16T14:24:58.483Z,1594909498.483 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 912
2020-07-16T14:24:58.487Z,1594909498.487 [Supervisor](INFO): Main Thread ID is 816
2020-07-16T14:24:58.487Z,1594909498.487 [Supervisor](DEBUG): Running supervisor.
2020-07-16T14:24:58.488Z,1594909498.488 [CommandLine ThreadHandler](INFO): Handler Thread ID is 913
2020-07-16T14:24:58.490Z,1594909498.490 [controlThread ThreadHandler](INFO): Handler Thread ID is 914
2020-07-16T14:24:58.491Z,1594909498.491 [controlThread](DEBUG): Initializing ControlThread
2020-07-16T14:24:58.493Z,1594909498.493 [SBIT](INFO): Initialize SBIT Component.
2020-07-16T14:24:58.493Z,1594909498.493 [SBIT](IMPORTANT): git: 2020-07-06
2020-07-16T14:24:58.493Z,1594909498.493 [SBIT](INFO): git hash: 27d6d613a5802cbc38609e1e9bc8b2bacbda1d86
2020-07-16T14:24:58.494Z,1594909498.494 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-07-16T14:24:58.495Z,1594909498.495 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019
2020-07-16T14:24:58.496Z,1594909498.496 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2020-07-16T14:24:58.497Z,1594909498.497 [IBIT](INFO): Initialize IBIT Component.
2020-07-16T14:24:58.498Z,1594909498.498 [CBIT](DEBUG): Initialize CBIT Component.
2020-07-16T14:24:58.499Z,1594909498.499 [logger ThreadHandler](INFO): Handler Thread ID is 915
2020-07-16T14:24:58.509Z,1594909498.509 [CBIT](DEBUG): Initialized mux pins.
2020-07-16T14:24:58.509Z,1594909498.509 [CBIT](DEBUG): Initializing the watchdog timer.
2020-07-16T14:24:58.517Z,1594909498.517 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 916
2020-07-16T14:24:58.518Z,1594909498.518 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-07-16T14:24:58.522Z,1594909498.522 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 918
2020-07-16T14:24:58.523Z,1594909498.523 [WetLabsBB2FL](INFO): Powering down
2020-07-16T14:24:58.533Z,1594909498.533 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-07-16T14:24:58.533Z,1594909498.533 [CBIT](DEBUG): Initializing heartbeat.
2020-07-16T14:24:58.626Z,1594909498.626 [CBIT](DEBUG): Deactivating GF circuits.
2020-07-16T14:24:58.626Z,1594909498.626 [CBIT](DEBUG): Deactivating emergency mode.
2020-07-16T14:24:58.627Z,1594909498.627 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 919
2020-07-16T14:24:58.633Z,1594909498.633 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-07-16T14:24:58.635Z,1594909498.635 [Onboard ThreadHandler](INFO): Handler Thread ID is 920
2020-07-16T14:24:58.661Z,1594909498.661 [CBIT](DEBUG): Backplane powered.
2020-07-16T14:24:58.665Z,1594909498.665 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-07-16T14:24:58.665Z,1594909498.665 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-07-16T14:24:58.666Z,1594909498.666 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-07-16T14:24:58.666Z,1594909498.666 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-07-16T14:24:58.667Z,1594909498.667 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-07-16T14:24:58.667Z,1594909498.667 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-07-16T14:24:58.668Z,1594909498.668 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-07-16T14:24:58.669Z,1594909498.669 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-07-16T14:24:58.679Z,1594909498.679 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-07-16T14:24:58.687Z,1594909498.687 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-07-16T14:24:58.688Z,1594909498.688 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-07-16T14:24:58.689Z,1594909498.689 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-07-16T14:24:58.690Z,1594909498.690 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-07-16T14:24:58.775Z,1594909498.775 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 921
2020-07-16T14:24:58.846Z,1594909498.846 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 922
2020-07-16T14:24:58.858Z,1594909498.858 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-07-16T14:24:58.858Z,1594909498.858 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-07-16T14:24:58.858Z,1594909498.858 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-07-16T14:24:58.858Z,1594909498.858 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-07-16T14:24:58.858Z,1594909498.858 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-07-16T14:24:58.859Z,1594909498.859 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-07-16T14:24:58.859Z,1594909498.859 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-07-16T14:24:58.859Z,1594909498.859 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-07-16T14:24:58.859Z,1594909498.859 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-07-16T14:24:58.859Z,1594909498.859 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-07-16T14:24:58.859Z,1594909498.859 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-07-16T14:24:58.860Z,1594909498.860 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-07-16T14:24:58.860Z,1594909498.860 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-07-16T14:24:58.860Z,1594909498.860 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-07-16T14:24:58.860Z,1594909498.860 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-07-16T14:24:58.860Z,1594909498.860 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-07-16T14:24:58.916Z,1594909498.916 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-16T14:24:58.962Z,1594909498.962 [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>


2020-07-16T14:24:58.963Z,1594909498.963 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-07-16T14:24:59.046Z,1594909499.046 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-07-16T14:24:59.047Z,1594909499.047 [Default:A.Wait](DEBUG): Construct Wait.
2020-07-16T14:24:59.049Z,1594909499.049 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-16T14:24:59.079Z,1594909499.079 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-07-16T14:24:59.106Z,1594909499.106 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-07-16T14:24:59.112Z,1594909499.112 [Default:E.Execute](DEBUG): Construct Execute.
2020-07-16T14:24:59.133Z,1594909499.133 [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>


2020-07-16T14:24:59.155Z,1594909499.155 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-07-16T14:24:59.181Z,1594909499.181 [Radio_Surface](INFO): Powering up
2020-07-16T14:24:59.274Z,1594909499.274 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-07-16T14:24:59.579Z,1594909499.579 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-07-16T14:24:59.585Z,1594909499.585 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-07-16T14:24:59.586Z,1594909499.586 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-07-16T14:24:59.593Z,1594909499.593 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-07-16T14:24:59.594Z,1594909499.594 [MassServo](DEBUG): Initializing EZServoServo.
2020-07-16T14:24:59.601Z,1594909499.601 [MassServo](DEBUG): Initializing MassServo.
2020-07-16T14:24:59.602Z,1594909499.602 [RudderServo](DEBUG): Initializing EZServoServo.
2020-07-16T14:24:59.609Z,1594909499.609 [RudderServo](DEBUG): Initializing RudderServo.
2020-07-16T14:24:59.610Z,1594909499.610 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-07-16T14:24:59.617Z,1594909499.617 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-07-16T14:25:02.577Z,1594909502.577 [Aanderaa_O2](INFO): Powering down
2020-07-16T14:25:05.549Z,1594909505.549 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-07-16T14:25:16.610Z,1594909516.610 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-16T14:25:16.610Z,1594909516.610 [DVL_micro] Communications Fault, FailCount= 1
2020-07-16T14:25:16.610Z,1594909516.610 [DVL_micro](ERROR): Communications Fault
2020-07-16T14:25:16.690Z,1594909516.690 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-16T14:25:17.093Z,1594909517.093 [DVL_micro](INFO): Powering down
2020-07-16T14:25:17.897Z,1594909517.897 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-16T14:25:17.897Z,1594909517.897 [DVL_micro] No Fault, FailCount= 1
2020-07-16T14:25:26.715Z,1594909526.715 [NAL9602](INFO): Powering up NAL9602
2020-07-16T14:25:35.630Z,1594909535.630 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-16T14:25:35.630Z,1594909535.630 [DVL_micro] Communications Fault, FailCount= 2
2020-07-16T14:25:35.630Z,1594909535.630 [DVL_micro](ERROR): Communications Fault
2020-07-16T14:25:35.730Z,1594909535.730 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-16T14:25:36.077Z,1594909536.077 [DVL_micro](INFO): Powering down
2020-07-16T14:25:36.853Z,1594909536.853 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-16T14:25:36.853Z,1594909536.853 [DVL_micro] No Fault, FailCount= 2
2020-07-16T14:25:37.619Z,1594909537.619 [NAL9602](INFO): NAL9602 initialized
2020-07-16T14:25:42.173Z,1594909542.173 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2020-07-16T14:25:42.550Z,1594909542.550 [Reporter](INFO): platform_buoyancy_position 494.482574 cc
2020-07-16T14:25:42.923Z,1594909542.923 [Reporter](INFO): platform_buoyancy_position 488.170441 cc
2020-07-16T14:25:43.336Z,1594909543.336 [Reporter](INFO): platform_buoyancy_position 484.007172 cc
2020-07-16T14:25:43.783Z,1594909543.783 [Reporter](INFO): platform_buoyancy_position 477.292145 cc
2020-07-16T14:25:44.155Z,1594909544.155 [Reporter](INFO): platform_buoyancy_position 472.457367 cc
2020-07-16T14:25:44.581Z,1594909544.581 [Reporter](INFO): platform_buoyancy_position 466.682465 cc
2020-07-16T14:25:44.952Z,1594909544.952 [Reporter](INFO): platform_buoyancy_position 462.384857 cc
2020-07-16T14:25:45.361Z,1594909545.361 [Reporter](INFO): platform_buoyancy_position 455.938446 cc
2020-07-16T14:25:45.771Z,1594909545.771 [Reporter](INFO): platform_buoyancy_position 451.506561 cc
2020-07-16T14:25:46.175Z,1594909546.175 [Reporter](INFO): platform_buoyancy_position 447.208954 cc
2020-07-16T14:25:46.587Z,1594909546.587 [Reporter](INFO): platform_buoyancy_position 440.762543 cc
2020-07-16T14:25:47.003Z,1594909547.003 [Reporter](INFO): platform_buoyancy_position 434.450470 cc
2020-07-16T14:25:47.391Z,1594909547.391 [Reporter](INFO): platform_buoyancy_position 430.152863 cc
2020-07-16T14:25:47.762Z,1594909547.762 [Reporter](INFO): platform_buoyancy_position 425.318024 cc
2020-07-16T14:25:48.178Z,1594909548.178 [Reporter](INFO): platform_buoyancy_position 419.408844 cc
2020-07-16T14:25:48.590Z,1594909548.590 [Reporter](INFO): platform_buoyancy_position 415.111237 cc
2020-07-16T14:25:48.998Z,1594909548.998 [Reporter](INFO): platform_buoyancy_position 406.516052 cc
2020-07-16T14:25:49.395Z,1594909549.395 [Reporter](INFO): platform_buoyancy_position 402.218445 cc
2020-07-16T14:25:49.799Z,1594909549.799 [Reporter](INFO): platform_buoyancy_position 397.786530 cc
2020-07-16T14:25:50.200Z,1594909550.200 [Reporter](INFO): platform_buoyancy_position 393.488953 cc
2020-07-16T14:25:50.638Z,1594909550.638 [Reporter](INFO): platform_buoyancy_position 387.176849 cc
2020-07-16T14:25:51.043Z,1594909551.043 [Reporter](INFO): platform_buoyancy_position 382.610626 cc
2020-07-16T14:25:51.423Z,1594909551.423 [Reporter](INFO): platform_buoyancy_position 376.432831 cc
2020-07-16T14:25:51.816Z,1594909551.816 [Reporter](INFO): platform_buoyancy_position 372.000946 cc
2020-07-16T14:25:52.230Z,1594909552.230 [Reporter](INFO): platform_buoyancy_position 365.554535 cc
2020-07-16T14:25:52.619Z,1594909552.619 [Reporter](INFO): platform_buoyancy_position 361.256927 cc
2020-07-16T14:25:53.011Z,1594909553.011 [Reporter](INFO): platform_buoyancy_position 355.079132 cc
2020-07-16T14:25:53.423Z,1594909553.423 [Reporter](INFO): platform_buoyancy_position 350.512939 cc
2020-07-16T14:25:53.836Z,1594909553.836 [Reporter](INFO): platform_buoyancy_position 344.066528 cc
2020-07-16T14:25:54.231Z,1594909554.231 [Reporter](INFO): platform_buoyancy_position 339.903229 cc
2020-07-16T14:25:54.587Z,1594909554.587 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-16T14:25:54.587Z,1594909554.587 [DVL_micro] Communications Fault, FailCount= 3
2020-07-16T14:25:54.587Z,1594909554.587 [DVL_micro](ERROR): Communications Fault
2020-07-16T14:25:54.633Z,1594909554.633 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-16T14:25:54.634Z,1594909554.634 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2020-07-16T14:25:54.636Z,1594909554.636 [Reporter](INFO): platform_buoyancy_position 335.471344 cc
2020-07-16T14:25:55.061Z,1594909555.061 [DVL_micro](INFO): Powering down
2020-07-16T14:25:55.115Z,1594909555.115 [CommandLine](FAULT): Scheduling is paused
2020-07-16T14:25:55.116Z,1594909555.116 [CBIT](INFO): Critical error at 20200716T142554
2020-07-16T14:25:55.116Z,1594909555.116 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-07-16T14:25:55.120Z,1594909555.120 [Reporter](INFO): platform_buoyancy_position 329.159241 cc
2020-07-16T14:25:55.453Z,1594909555.453 [Reporter](INFO): platform_buoyancy_position 324.727325 cc
2020-07-16T14:25:55.851Z,1594909555.851 [Reporter](INFO): platform_buoyancy_position 318.280945 cc
2020-07-16T14:25:56.279Z,1594909556.279 [Reporter](INFO): platform_buoyancy_position 313.983337 cc
2020-07-16T14:25:56.679Z,1594909556.679 [Reporter](INFO): platform_buoyancy_position 308.342743 cc
2020-07-16T14:25:57.079Z,1594909557.079 [Reporter](INFO): platform_buoyancy_position 303.239319 cc
2020-07-16T14:25:57.483Z,1594909557.483 [Reporter](INFO): platform_buoyancy_position 296.792938 cc
2020-07-16T14:25:57.875Z,1594909557.875 [Reporter](INFO): platform_buoyancy_position 292.763947 cc
2020-07-16T14:25:58.263Z,1594909558.263 [SBIT](IMPORTANT): Beginning Startup BIT
2020-07-16T14:25:58.267Z,1594909558.267 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-16T14:25:58.269Z,1594909558.269 [Reporter](INFO): platform_buoyancy_position 286.720428 cc
2020-07-16T14:25:58.750Z,1594909558.750 [Reporter](INFO): platform_buoyancy_position 281.885620 cc
2020-07-16T14:25:59.109Z,1594909559.109 [Reporter](INFO): platform_buoyancy_position 275.439240 cc
2020-07-16T14:25:59.519Z,1594909559.519 [Reporter](INFO): platform_buoyancy_position 271.007324 cc
2020-07-16T14:26:00.102Z,1594909560.102 [Reporter](INFO): platform_buoyancy_position 264.695221 cc
2020-07-16T14:26:00.451Z,1594909560.451 [Reporter](INFO): platform_buoyancy_position 260.397644 cc
2020-07-16T14:26:00.827Z,1594909560.827 [Reporter](INFO): platform_buoyancy_position 253.951233 cc
2020-07-16T14:26:01.270Z,1594909561.270 [Reporter](INFO): platform_buoyancy_position 249.519302 cc
2020-07-16T14:26:01.647Z,1594909561.647 [Reporter](INFO): platform_buoyancy_position 245.221741 cc
2020-07-16T14:26:02.102Z,1594909562.102 [Reporter](INFO): platform_buoyancy_position 238.909637 cc
2020-07-16T14:26:06.027Z,1594909566.027 [Reporter](INFO): platform_buoyancy_position 224.808136 cc
2020-07-16T14:26:09.200Z,1594909569.200 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.000247
CHAN A1 (24V): -0.154922
CHAN A2 (12V): -0.004098
CHAN A3 (5V): -0.002742
CHAN B0 (3.3V): -0.001007
CHAN B1 (3.15aV): -0.001605
CHAN B2 (3.15bV): -0.000969
CHAN B3 (GND): -0.000280
OPEN: 0.004723
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-16T14:26:27.262Z,1594909587.262 [CommandLine](IMPORTANT): got command configSet list
2020-07-16T14:26:27.263Z,1594909587.263 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-07-16T14:26:27.263Z,1594909587.263 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-07-16T14:26:27.264Z,1594909587.264 [CommandLine](IMPORTANT): CBIT.gf24Offset=148 microampere;

2020-07-16T14:26:27.264Z,1594909587.264 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2020-07-16T14:26:27.264Z,1594909587.264 [CommandLine](IMPORTANT): VerticalControl.buoyancyDefault=223 cubic_centimeter;

2020-07-16T14:26:27.264Z,1594909587.264 [CommandLine](IMPORTANT): VerticalControl.massDefault=9.9 millimeter;

2020-07-16T14:26:51.986Z,1594909611.986 [SBIT](IMPORTANT): SBIT PASSED
2020-07-16T14:26:51.986Z,1594909611.986 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-07-16T14:26:51.987Z,1594909611.987 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-07-16T14:26:51.987Z,1594909611.987 [SBIT](IMPORTANT): CBIT.gf24Offset=148 microampere;

2020-07-16T14:26:51.988Z,1594909611.988 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2020-07-16T14:26:51.988Z,1594909611.988 [SBIT](IMPORTANT): VerticalControl.buoyancyDefault=223 cubic_centimeter;

2020-07-16T14:26:51.988Z,1594909611.988 [SBIT](IMPORTANT): VerticalControl.massDefault=9.9 millimeter;

2020-07-16T14:26:52.378Z,1594909612.378 [MissionManager](IMPORTANT): Started mission Startup
2020-07-16T14:26:52.378Z,1594909612.378 [Startup] Running Loop=1
2020-07-16T14:26:52.378Z,1594909612.378 [Startup](DEBUG): Aggregate::initialize Startup
2020-07-16T14:26:52.378Z,1594909612.378 [Startup:A.GoToSurface] Running Loop=1
2020-07-16T14:26:52.379Z,1594909612.379 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-16T14:26:52.379Z,1594909612.379 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-16T14:26:52.380Z,1594909612.380 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-16T14:26:52.380Z,1594909612.380 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-16T14:26:52.380Z,1594909612.380 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-16T14:26:52.381Z,1594909612.381 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-16T14:26:52.382Z,1594909612.382 [Startup:StartupSatComms] Running Loop=1
2020-07-16T14:26:52.382Z,1594909612.382 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-07-16T14:26:52.382Z,1594909612.382 [Startup:StartupSatComms:A] Running Loop=1
2020-07-16T14:26:52.783Z,1594909612.783 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-07-16T14:26:57.699Z,1594909617.699 [Reporter](INFO): platform_buoyancy_position 224.136627 cc
2020-07-16T14:26:58.076Z,1594909618.076 [Reporter](INFO): platform_buoyancy_position 225.479614 cc
2020-07-16T14:26:58.545Z,1594909618.545 [Reporter](INFO): platform_buoyancy_position 226.016815 cc
2020-07-16T14:26:58.915Z,1594909618.915 [Reporter](INFO): platform_buoyancy_position 228.031342 cc
2020-07-16T14:26:59.322Z,1594909619.322 [Reporter](INFO): platform_buoyancy_position 232.328918 cc
2020-07-16T14:26:59.723Z,1594909619.723 [Reporter](INFO): platform_buoyancy_position 236.626526 cc
2020-07-16T14:27:00.111Z,1594909620.111 [Reporter](INFO): platform_buoyancy_position 243.072937 cc
2020-07-16T14:27:00.574Z,1594909620.574 [Reporter](INFO): platform_buoyancy_position 247.773422 cc
2020-07-16T14:27:00.925Z,1594909620.925 [Reporter](INFO): platform_buoyancy_position 253.011154 cc
2020-07-16T14:27:01.335Z,1594909621.335 [Reporter](INFO): platform_buoyancy_position 258.114532 cc
2020-07-16T14:27:01.724Z,1594909621.724 [Reporter](INFO): platform_buoyancy_position 262.546417 cc
2020-07-16T14:27:02.115Z,1594909622.115 [Reporter](INFO): platform_buoyancy_position 268.992828 cc
2020-07-16T14:27:02.515Z,1594909622.515 [Reporter](INFO): platform_buoyancy_position 273.290436 cc
2020-07-16T14:27:02.921Z,1594909622.921 [Reporter](INFO): platform_buoyancy_position 279.602539 cc
2020-07-16T14:27:03.315Z,1594909623.315 [Reporter](INFO): platform_buoyancy_position 283.900146 cc
2020-07-16T14:27:03.771Z,1594909623.771 [Reporter](INFO): platform_buoyancy_position 290.346527 cc
2020-07-16T14:27:04.143Z,1594909624.143 [Reporter](INFO): platform_buoyancy_position 295.584229 cc
2020-07-16T14:27:04.575Z,1594909624.575 [Reporter](INFO): platform_buoyancy_position 301.090546 cc
2020-07-16T14:27:04.959Z,1594909624.959 [Reporter](INFO): platform_buoyancy_position 305.388123 cc
2020-07-16T14:27:05.369Z,1594909625.369 [Reporter](INFO): platform_buoyancy_position 311.968842 cc
2020-07-16T14:27:05.780Z,1594909625.780 [Reporter](INFO): platform_buoyancy_position 316.266449 cc
2020-07-16T14:27:06.151Z,1594909626.151 [Reporter](INFO): platform_buoyancy_position 322.578522 cc
2020-07-16T14:27:06.564Z,1594909626.564 [Reporter](INFO): platform_buoyancy_position 327.010437 cc
2020-07-16T14:27:06.966Z,1594909626.966 [Reporter](INFO): platform_buoyancy_position 333.053925 cc
2020-07-16T14:27:07.363Z,1594909627.363 [Reporter](INFO): platform_buoyancy_position 337.620148 cc
2020-07-16T14:27:07.767Z,1594909627.767 [Reporter](INFO): platform_buoyancy_position 343.395050 cc
2020-07-16T14:27:08.167Z,1594909628.167 [Reporter](INFO): platform_buoyancy_position 348.364136 cc
2020-07-16T14:27:08.598Z,1594909628.598 [Reporter](INFO): platform_buoyancy_position 354.810547 cc
2020-07-16T14:27:08.983Z,1594909628.983 [Reporter](INFO): platform_buoyancy_position 359.242432 cc
2020-07-16T14:27:09.386Z,1594909629.386 [Reporter](INFO): platform_buoyancy_position 365.151642 cc
2020-07-16T14:27:09.807Z,1594909629.807 [Reporter](INFO): platform_buoyancy_position 369.986450 cc
2020-07-16T14:27:10.218Z,1594909630.218 [Reporter](INFO): platform_buoyancy_position 375.224152 cc
2020-07-16T14:27:10.633Z,1594909630.633 [Reporter](INFO): platform_buoyancy_position 380.596130 cc
2020-07-16T14:27:11.031Z,1594909631.031 [Reporter](INFO): platform_buoyancy_position 386.639648 cc
2020-07-16T14:27:11.399Z,1594909631.399 [Reporter](INFO): platform_buoyancy_position 391.474426 cc
2020-07-16T14:27:11.881Z,1594909631.881 [Reporter](INFO): platform_buoyancy_position 397.786530 cc
2020-07-16T14:27:12.236Z,1594909632.236 [Reporter](INFO): platform_buoyancy_position 402.218445 cc
2020-07-16T14:27:12.647Z,1594909632.647 [Reporter](INFO): platform_buoyancy_position 408.530548 cc
2020-07-16T14:27:13.039Z,1594909633.039 [Reporter](INFO): platform_buoyancy_position 412.828156 cc
2020-07-16T14:27:13.431Z,1594909633.431 [Reporter](INFO): platform_buoyancy_position 417.260040 cc
2020-07-16T14:27:13.895Z,1594909633.895 [Reporter](INFO): platform_buoyancy_position 423.572113 cc
2020-07-16T14:27:14.231Z,1594909634.231 [Reporter](INFO): platform_buoyancy_position 427.869720 cc
2020-07-16T14:27:14.659Z,1594909634.659 [Reporter](INFO): platform_buoyancy_position 434.316132 cc
2020-07-16T14:27:15.079Z,1594909635.079 [Reporter](INFO): platform_buoyancy_position 439.419525 cc
2020-07-16T14:27:15.477Z,1594909635.477 [Reporter](INFO): platform_buoyancy_position 445.060150 cc
2020-07-16T14:27:15.879Z,1594909635.879 [Reporter](INFO): platform_buoyancy_position 449.492035 cc
2020-07-16T14:27:16.270Z,1594909636.270 [Reporter](INFO): platform_buoyancy_position 453.789642 cc
2020-07-16T14:27:16.663Z,1594909636.663 [Reporter](INFO): platform_buoyancy_position 460.236053 cc
2020-07-16T14:27:17.055Z,1594909637.055 [Reporter](INFO): platform_buoyancy_position 464.533661 cc
2020-07-16T14:27:17.468Z,1594909637.468 [Reporter](INFO): platform_buoyancy_position 470.845734 cc
2020-07-16T14:27:17.875Z,1594909637.875 [Reporter](INFO): platform_buoyancy_position 475.411957 cc
2020-07-16T14:27:18.299Z,1594909638.299 [Reporter](INFO): platform_buoyancy_position 481.589752 cc
2020-07-16T14:27:18.687Z,1594909638.687 [Reporter](INFO): platform_buoyancy_position 485.887360 cc
2020-07-16T14:27:19.080Z,1594909639.080 [Reporter](INFO): platform_buoyancy_position 492.333771 cc
2020-07-16T14:27:19.497Z,1594909639.497 [Reporter](INFO): platform_buoyancy_position 496.899933 cc
2020-07-16T14:27:19.887Z,1594909639.887 [Reporter](INFO): platform_buoyancy_position 503.077759 cc
2020-07-16T14:27:20.373Z,1594909640.373 [Reporter](INFO): platform_buoyancy_position 509.524139 cc
2020-07-16T14:27:20.742Z,1594909640.742 [Reporter](INFO): platform_buoyancy_position 513.821716 cc
2020-07-16T14:27:21.152Z,1594909641.152 [Reporter](INFO): platform_buoyancy_position 520.268127 cc
2020-07-16T14:27:21.555Z,1594909641.555 [Reporter](INFO): platform_buoyancy_position 524.565735 cc
2020-07-16T14:27:21.941Z,1594909641.941 [Reporter](INFO): platform_buoyancy_position 529.534790 cc
2020-07-16T14:27:22.354Z,1594909642.354 [Reporter](INFO): platform_buoyancy_position 535.444031 cc
2020-07-16T14:27:22.735Z,1594909642.735 [Reporter](INFO): platform_buoyancy_position 539.741638 cc
2020-07-16T14:27:23.151Z,1594909643.151 [Reporter](INFO): platform_buoyancy_position 546.053711 cc
2020-07-16T14:27:23.549Z,1594909643.549 [Reporter](INFO): platform_buoyancy_position 550.485596 cc
2020-07-16T14:27:23.943Z,1594909643.943 [Reporter](INFO): platform_buoyancy_position 556.797729 cc
2020-07-16T14:27:24.331Z,1594909644.331 [Reporter](INFO): platform_buoyancy_position 561.229614 cc
2020-07-16T14:27:24.731Z,1594909644.731 [Reporter](INFO): platform_buoyancy_position 565.527222 cc
2020-07-16T14:27:25.143Z,1594909645.143 [Reporter](INFO): platform_buoyancy_position 571.839294 cc
2020-07-16T14:27:25.633Z,1594909645.633 [Reporter](INFO): platform_buoyancy_position 578.420044 cc
2020-07-16T14:27:25.651Z,1594909645.651 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004853
2020-07-16T14:27:25.995Z,1594909645.995 [Reporter](INFO): platform_buoyancy_position 582.583313 cc
2020-07-16T14:27:26.395Z,1594909646.395 [Reporter](INFO): platform_buoyancy_position 587.015198 cc
2020-07-16T14:27:26.779Z,1594909646.779 [Reporter](INFO): platform_buoyancy_position 593.461609 cc
2020-07-16T14:27:27.223Z,1594909647.223 [Reporter](INFO): platform_buoyancy_position 597.759216 cc
2020-07-16T14:27:27.571Z,1594909647.571 [Reporter](INFO): platform_buoyancy_position 602.191101 cc
2020-07-16T14:27:27.987Z,1594909647.987 [Reporter](INFO): platform_buoyancy_position 608.368896 cc
2020-07-16T14:27:28.393Z,1594909648.393 [Reporter](INFO): platform_buoyancy_position 614.681030 cc
2020-07-16T14:27:28.775Z,1594909648.775 [Reporter](INFO): platform_buoyancy_position 619.112915 cc
2020-07-16T14:27:29.179Z,1594909649.179 [Reporter](INFO): platform_buoyancy_position 623.813416 cc
2020-07-16T14:27:29.594Z,1594909649.594 [Reporter](INFO): platform_buoyancy_position 629.991211 cc
2020-07-16T14:27:29.987Z,1594909649.987 [Reporter](INFO): platform_buoyancy_position 634.288818 cc
2020-07-16T14:27:30.387Z,1594909650.387 [Reporter](INFO): platform_buoyancy_position 640.600952 cc
2020-07-16T14:27:30.820Z,1594909650.820 [Reporter](INFO): platform_buoyancy_position 646.241516 cc
2020-07-16T14:27:31.219Z,1594909651.219 [Reporter](INFO): platform_buoyancy_position 651.479248 cc
2020-07-16T14:27:31.627Z,1594909651.627 [Reporter](INFO): platform_buoyancy_position 655.911133 cc
2020-07-16T14:27:32.015Z,1594909652.015 [Reporter](INFO): platform_buoyancy_position 662.088928 cc
2020-07-16T14:27:32.411Z,1594909652.411 [Reporter](INFO): platform_buoyancy_position 666.386536 cc
2020-07-16T14:27:32.815Z,1594909652.815 [Reporter](INFO): platform_buoyancy_position 671.221313 cc
2020-07-16T14:27:33.238Z,1594909653.238 [Reporter](INFO): platform_buoyancy_position 677.130554 cc
2020-07-16T14:27:33.627Z,1594909653.627 [Reporter](INFO): platform_buoyancy_position 681.562439 cc
2020-07-16T14:27:34.023Z,1594909654.023 [Reporter](INFO): platform_buoyancy_position 688.008850 cc
2020-07-16T14:27:34.431Z,1594909654.431 [Reporter](INFO): platform_buoyancy_position 692.306458 cc
2020-07-16T14:27:34.850Z,1594909654.850 [Reporter](INFO): platform_buoyancy_position 698.618530 cc
2020-07-16T14:27:35.243Z,1594909655.243 [Reporter](INFO): platform_buoyancy_position 703.184753 cc
2020-07-16T14:27:35.643Z,1594909655.643 [Reporter](INFO): platform_buoyancy_position 709.496826 cc
2020-07-16T14:27:36.084Z,1594909656.084 [Reporter](INFO): platform_buoyancy_position 713.794434 cc
2020-07-16T14:27:36.474Z,1594909656.474 [Reporter](INFO): platform_buoyancy_position 720.106506 cc
2020-07-16T14:27:36.879Z,1594909656.879 [Reporter](INFO): platform_buoyancy_position 724.538452 cc
2020-07-16T14:27:37.289Z,1594909657.289 [Reporter](INFO): platform_buoyancy_position 730.984863 cc
2020-07-16T14:27:37.667Z,1594909657.667 [Reporter](INFO): platform_buoyancy_position 735.148132 cc
2020-07-16T14:27:38.079Z,1594909658.079 [Reporter](INFO): platform_buoyancy_position 739.580017 cc
2020-07-16T14:27:38.471Z,1594909658.471 [Reporter](INFO): platform_buoyancy_position 745.892151 cc
2020-07-16T14:27:38.958Z,1594909658.958 [Reporter](INFO): platform_buoyancy_position 752.338562 cc
2020-07-16T14:27:39.287Z,1594909659.287 [Reporter](INFO): platform_buoyancy_position 756.770447 cc
2020-07-16T14:27:39.707Z,1594909659.707 [Reporter](INFO): platform_buoyancy_position 763.216858 cc
2020-07-16T14:27:40.114Z,1594909660.114 [Reporter](INFO): platform_buoyancy_position 767.514465 cc
2020-07-16T14:27:40.486Z,1594909660.486 [Reporter](INFO): platform_buoyancy_position 771.812012 cc
2020-07-16T14:27:40.971Z,1594909660.971 [Reporter](INFO): platform_buoyancy_position 778.392761 cc
2020-07-16T14:27:41.327Z,1594909661.327 [Reporter](INFO): platform_buoyancy_position 784.570557 cc
2020-07-16T14:27:41.730Z,1594909661.730 [Reporter](INFO): platform_buoyancy_position 788.868164 cc
2020-07-16T14:27:42.147Z,1594909662.147 [Reporter](INFO): platform_buoyancy_position 795.448853 cc
2020-07-16T14:27:42.527Z,1594909662.527 [Reporter](INFO): platform_buoyancy_position 799.612122 cc
2020-07-16T14:27:42.932Z,1594909662.932 [Reporter](INFO): platform_buoyancy_position 804.044067 cc
2020-07-16T14:27:43.323Z,1594909663.323 [Reporter](INFO): platform_buoyancy_position 810.356140 cc
2020-07-16T14:27:43.739Z,1594909663.739 [Reporter](INFO): platform_buoyancy_position 814.788025 cc
2020-07-16T14:27:44.130Z,1594909664.130 [Reporter](INFO): platform_buoyancy_position 821.234436 cc
2020-07-16T14:27:44.546Z,1594909664.546 [Reporter](INFO): platform_buoyancy_position 825.532043 cc
2020-07-16T14:27:44.945Z,1594909664.945 [Reporter](INFO): platform_buoyancy_position 831.844116 cc
2020-07-16T14:27:45.339Z,1594909665.339 [Reporter](INFO): platform_buoyancy_position 836.276062 cc
2020-07-16T14:27:45.751Z,1594909665.751 [Reporter](INFO): platform_buoyancy_position 842.453857 cc
2020-07-16T14:27:46.163Z,1594909666.163 [Reporter](INFO): platform_buoyancy_position 846.885742 cc
2020-07-16T14:27:46.325Z,1594909666.325 [CommandLine](IMPORTANT): got command configSet list
2020-07-16T14:27:46.326Z,1594909666.326 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-07-16T14:27:46.326Z,1594909666.326 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-07-16T14:27:46.327Z,1594909666.327 [CommandLine](IMPORTANT): CBIT.gf24Offset=148 microampere;

2020-07-16T14:27:46.327Z,1594909666.327 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2020-07-16T14:27:46.327Z,1594909666.327 [CommandLine](IMPORTANT): VerticalControl.massDefault=9.9 millimeter;

2020-07-16T14:27:46.573Z,1594909666.573 [Reporter](INFO): platform_buoyancy_position 853.197815 cc
2020-07-16T14:27:46.989Z,1594909666.989 [Reporter](INFO): platform_buoyancy_position 857.764038 cc
2020-07-16T14:27:47.387Z,1594909667.387 [Reporter](INFO): platform_buoyancy_position 863.136047 cc
2020-07-16T14:27:47.787Z,1594909667.787 [Reporter](INFO): platform_buoyancy_position 868.373718 cc
2020-07-16T14:27:48.167Z,1594909668.167 [Reporter](INFO): platform_buoyancy_position 872.805664 cc
2020-07-16T14:27:48.579Z,1594909668.579 [Reporter](INFO): platform_buoyancy_position 879.117737 cc
2020-07-16T14:27:48.979Z,1594909668.979 [Reporter](INFO): platform_buoyancy_position 883.415344 cc
2020-07-16T14:27:49.387Z,1594909669.387 [Reporter](INFO): platform_buoyancy_position 889.727417 cc
2020-07-16T14:27:49.799Z,1594909669.799 [Reporter](INFO): platform_buoyancy_position 894.159363 cc
2020-07-16T14:27:50.195Z,1594909670.195 [Reporter](INFO): platform_buoyancy_position 900.605774 cc
2020-07-16T14:27:50.587Z,1594909670.587 [Reporter](INFO): platform_buoyancy_position 904.903320 cc
2020-07-16T14:27:51.045Z,1594909671.045 [Reporter](INFO): platform_buoyancy_position 911.349731 cc
2020-07-16T14:27:51.417Z,1594909671.417 [Reporter](INFO): platform_buoyancy_position 915.781677 cc
2020-07-16T14:27:51.839Z,1594909671.839 [Reporter](INFO): platform_buoyancy_position 921.959473 cc
2020-07-16T14:27:52.257Z,1594909672.257 [Reporter](INFO): platform_buoyancy_position 926.525635 cc
2020-07-16T14:27:52.714Z,1594909672.714 [Startup:StartupSatComms:A](INFO): Timed out from 2020-07-16T14:26:52.4Z
2020-07-16T14:27:52.714Z,1594909672.714 [Startup:StartupSatComms:A] Stopped
2020-07-16T14:27:52.714Z,1594909672.714 [Startup:StartupSatComms:B] Running Loop=1
2020-07-16T14:27:52.766Z,1594909672.766 [Reporter](INFO): platform_buoyancy_position 932.972046 cc
2020-07-16T14:27:53.003Z,1594909673.003 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-07-16T14:27:53.083Z,1594909673.083 [Reporter](INFO): platform_buoyancy_position 937.135376 cc
2020-07-16T14:27:55.327Z,1594909675.327 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200714T203007/Courier0019.lzma
2020-07-16T14:27:56.523Z,1594909676.523 [DataOverHttps](INFO): Moved sent file to Logs/20200714T203007/Courier0019.lzma.bak
2020-07-16T14:27:56.523Z,1594909676.523 [DataOverHttps](INFO): SBD MOMSN=12443403
2020-07-16T14:27:57.255Z,1594909677.255 [Reporter](INFO): platform_buoyancy_position 952.311279 cc
2020-07-16T14:27:58.755Z,1594909678.755 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-07-16T14:27:58.755Z,1594909678.755 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-16T14:27:58.765Z,1594909678.765 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-16T14:27:59.313Z,1594909679.313 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-16T14:27:59.313Z,1594909679.313 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-07-16T14:28:11.920Z,1594909691.920 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20200716T142446/Courier0000.lzma
2020-07-16T14:28:12.922Z,1594909692.922 [DataOverHttps](INFO): Moved sent file to Logs/20200716T142446/Courier0000.lzma.bak
2020-07-16T14:28:12.923Z,1594909692.923 [DataOverHttps](INFO): SBD MOMSN=12443405
2020-07-16T14:28:29.802Z,1594909709.802 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20200714T203007/Express0020.lzma
2020-07-16T14:28:30.802Z,1594909710.802 [DataOverHttps](INFO): Moved sent file to Logs/20200714T203007/Express0020.lzma.bak
2020-07-16T14:28:30.803Z,1594909710.803 [DataOverHttps](INFO): SBD MOMSN=12443409
2020-07-16T14:28:46.538Z,1594909726.538 [DataOverHttps](INFO): Sending 930 bytes from file Logs/20200716T142446/Express0001.lzma
2020-07-16T14:28:47.514Z,1594909727.514 [DataOverHttps](INFO): Moved sent file to Logs/20200716T142446/Express0001.lzma.bak
2020-07-16T14:28:47.515Z,1594909727.515 [DataOverHttps](INFO): SBD MOMSN=12443413
2020-07-16T14:28:49.738Z,1594909729.738 [Startup:StartupSatComms:B] Stopped
2020-07-16T14:28:49.738Z,1594909729.738 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-07-16T14:28:49.738Z,1594909729.738 [Startup:StartupSatComms] Stopped
2020-07-16T14:28:49.738Z,1594909729.738 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-07-16T14:28:49.739Z,1594909729.739 [Startup](INFO): Completed Startup
2020-07-16T14:28:49.739Z,1594909729.739 [MissionManager](INFO): Startup is completed.
2020-07-16T14:28:49.739Z,1594909729.739 [MissionManager](INFO): Uninitializing Mission Startup
2020-07-16T14:28:49.739Z,1594909729.739 [Startup] Stopped
2020-07-16T14:28:49.739Z,1594909729.739 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-07-16T14:28:49.739Z,1594909729.739 [Startup:A.GoToSurface] Stopped
2020-07-16T14:28:49.740Z,1594909729.740 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-16T14:28:50.150Z,1594909730.150 [MissionManager](IMPORTANT): Started mission Default
2020-07-16T14:28:50.150Z,1594909730.150 [Default] Running Loop=1
2020-07-16T14:28:50.150Z,1594909730.150 [Default](DEBUG): Aggregate::initialize Default
2020-07-16T14:28:50.150Z,1594909730.150 [Default:B.GoToSurface] Running Loop=1
2020-07-16T14:28:50.150Z,1594909730.150 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-16T14:28:50.150Z,1594909730.150 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-16T14:28:50.151Z,1594909730.151 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-16T14:28:50.151Z,1594909730.151 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-16T14:28:50.151Z,1594909730.151 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-16T14:28:50.152Z,1594909730.152 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-16T14:28:50.152Z,1594909730.152 [Default:A.Wait] Running Loop=1
2020-07-16T14:28:50.152Z,1594909730.152 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-07-16T14:28:54.991Z,1594909734.991 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-16T14:28:54.991Z,1594909734.991 [DVL_micro] No Fault, FailCount= 3
2020-07-16T14:29:02.047Z,1594909742.047 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 223.000000 cubic_centimeter persist
2020-07-16T14:29:03.461Z,1594909743.461 [Default:A.Wait](INFO): Done Waiting.
2020-07-16T14:29:03.461Z,1594909743.461 [Default:A.Wait] Stopped
2020-07-16T14:29:03.461Z,1594909743.461 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-07-16T14:29:03.882Z,1594909743.882 [Default:CheckIn] Running Loop=1
2020-07-16T14:29:03.882Z,1594909743.882 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-16T14:29:03.882Z,1594909743.882 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-16T14:29:04.259Z,1594909744.259 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-07-16T14:29:07.038Z,1594909747.038 [CommandLine](IMPORTANT): got command configSet list
2020-07-16T14:29:07.039Z,1594909747.039 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-07-16T14:29:07.040Z,1594909747.040 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-07-16T14:29:07.040Z,1594909747.040 [CommandLine](IMPORTANT): CBIT.gf24Offset=148 microampere;

2020-07-16T14:29:07.041Z,1594909747.041 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2020-07-16T14:29:07.041Z,1594909747.041 [CommandLine](IMPORTANT): VerticalControl.buoyancyDefault=223 cubic_centimeter;

2020-07-16T14:29:07.041Z,1594909747.041 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=223 cubic_centimeter;

2020-07-16T14:29:07.041Z,1594909747.041 [CommandLine](IMPORTANT): VerticalControl.massDefault=9.9 millimeter;

2020-07-16T14:29:12.735Z,1594909752.735 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-16T14:29:12.735Z,1594909752.735 [DVL_micro] Communications Fault, FailCount= 1
2020-07-16T14:29:12.735Z,1594909752.735 [DVL_micro](ERROR): Communications Fault
2020-07-16T14:29:12.752Z,1594909752.752 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-16T14:29:13.213Z,1594909753.213 [DVL_micro](INFO): Powering down
2020-07-16T14:29:13.987Z,1594909753.987 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-16T14:29:13.987Z,1594909753.987 [DVL_micro] No Fault, FailCount= 1
2020-07-16T14:29:31.722Z,1594909771.722 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-16T14:29:31.722Z,1594909771.722 [DVL_micro] Communications Fault, FailCount= 2
2020-07-16T14:29:31.722Z,1594909771.722 [DVL_micro](ERROR): Communications Fault
2020-07-16T14:29:31.769Z,1594909771.769 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-16T14:29:32.193Z,1594909772.193 [DVL_micro](INFO): Powering down
2020-07-16T14:29:32.952Z,1594909772.952 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-16T14:29:32.952Z,1594909772.952 [DVL_micro] No Fault, FailCount= 2
2020-07-16T14:29:50.711Z,1594909790.711 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-16T14:29:50.711Z,1594909790.711 [DVL_micro] Communications Fault, FailCount= 3
2020-07-16T14:29:50.711Z,1594909790.711 [DVL_micro](ERROR): Communications Fault
2020-07-16T14:29:50.728Z,1594909790.728 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-16T14:29:50.728Z,1594909790.728 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2020-07-16T14:29:51.189Z,1594909791.189 [DVL_micro](INFO): Powering down
2020-07-16T14:29:51.239Z,1594909791.239 [CBIT](INFO): Critical error at 20200716T142950
2020-07-16T14:29:52.966Z,1594909792.966 [CommandLine](IMPORTANT): got command show variable dvl_micro
2020-07-16T14:29:52.998Z,1594909792.998 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup (bool)
2020-07-16T14:29:52.998Z,1594909792.998 [CommandLine](IMPORTANT): DVL_micro.simulateHardware (bool)
2020-07-16T14:29:52.998Z,1594909792.998 [CommandLine](IMPORTANT): DVL_micro.magDeviation (degree)
2020-07-16T14:29:52.999Z,1594909792.999 [CommandLine](IMPORTANT): DVL_micro.pitchOffset (degree)
2020-07-16T14:29:52.999Z,1594909792.999 [CommandLine](IMPORTANT): DVL_micro.power (watt)
2020-07-16T14:29:52.999Z,1594909792.999 [CommandLine](IMPORTANT): DVL_micro.rollOffset (degree)
2020-07-16T14:29:53.010Z,1594909793.010 [CommandLine](IMPORTANT): DVL_micro.loadControl (none)
2020-07-16T14:29:53.010Z,1594909793.010 [CommandLine](IMPORTANT): DVL_micro.uart (none)
2020-07-16T14:29:53.010Z,1594909793.010 [CommandLine](IMPORTANT): DVL_micro.baud (bit_per_second)
2020-07-16T14:29:53.090Z,1594909793.090 [CommandLine](IMPORTANT): DVL_micro.component_voltage (volt)
2020-07-16T14:29:53.090Z,1594909793.090 [CommandLine](IMPORTANT): DVL_micro.component_avgVoltage (volt)
2020-07-16T14:29:53.090Z,1594909793.090 [CommandLine](IMPORTANT): DVL_micro.component_current (milliampere)
2020-07-16T14:29:53.091Z,1594909793.091 [CommandLine](IMPORTANT): DVL_micro.component_avgCurrent (milliampere)
2020-07-16T14:29:53.091Z,1594909793.091 [CommandLine](IMPORTANT): DVL_micro.height_above_sea_floor (meter)
2020-07-16T14:29:53.091Z,1594909793.091 [CommandLine](IMPORTANT): DVL_micro.platform_velocity_wrt_ground (meter_per_second)
2020-07-16T14:29:53.092Z,1594909793.092 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_wrt_ground (meter_per_second)
2020-07-16T14:29:53.092Z,1594909793.092 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_wrt_ground (meter_per_second)
2020-07-16T14:29:53.092Z,1594909793.092 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_wrt_ground (meter_per_second)
2020-07-16T14:29:53.093Z,1594909793.093 [CommandLine](IMPORTANT): DVL_micro.BottomVelocityFlag (count)
2020-07-16T14:29:53.093Z,1594909793.093 [CommandLine](IMPORTANT): DVL_micro.Beam1Range (meter)
2020-07-16T14:29:53.093Z,1594909793.093 [CommandLine](IMPORTANT): DVL_micro.Beam2Range (meter)
2020-07-16T14:29:53.094Z,1594909793.094 [CommandLine](IMPORTANT): DVL_micro.Beam3Range (meter)
2020-07-16T14:29:53.094Z,1594909793.094 [CommandLine](IMPORTANT): DVL_micro.Beam4Range (meter)
2020-07-16T14:30:00.261Z,1594909800.261 [CommandLine](IMPORTANT): got command get DVL_micro.loadAtStartup
2020-07-16T14:30:00.262Z,1594909800.262 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup 1 bool
2020-07-16T14:30:04.350Z,1594909804.350 [CommandLine](IMPORTANT): got command gfscan
2020-07-16T14:30:04.476Z,1594909804.476 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-16T14:30:15.393Z,1594909815.393 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.006765
CHAN A1 (24V): -0.168064
CHAN A2 (12V): -0.003824
CHAN A3 (5V): -0.002583
CHAN B0 (3.3V): -0.001547
CHAN B1 (3.15aV): -0.000872
CHAN B2 (3.15bV): -0.000690
CHAN B3 (GND): -0.000074
OPEN: 0.004587
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-16T14:30:26.166Z,1594909826.166 [CommandLine](IMPORTANT): got command failComponent
2020-07-16T14:30:26.166Z,1594909826.166 [CommandLine](IMPORTANT): Failed components:
2020-07-16T14:30:26.166Z,1594909826.166 [CommandLine](IMPORTANT): DVL_micro: Communications Fault
2020-07-16T14:30:41.212Z,1594909841.212 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-07-16T14:30:41.846Z,1594909841.846 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-07-16T14:30:52.799Z,1594909852.799 [CommandLine](IMPORTANT): got command failComponent none DVL_micro
2020-07-16T14:30:52.799Z,1594909852.799 [DVL_micro] No Fault, FailCount= 3
2020-07-16T14:30:52.799Z,1594909852.799 [CommandLine](IMPORTANT): DVL_micro failureMode is No Fault
2020-07-16T14:30:59.821Z,1594909859.821 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-07-16T14:30:59.821Z,1594909859.821 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-16T14:30:59.842Z,1594909859.842 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-16T14:31:00.321Z,1594909860.321 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-16T14:31:00.321Z,1594909860.321 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-07-16T14:31:08.662Z,1594909868.662 [CommandLine](IMPORTANT): got command failComponent
2020-07-16T14:31:08.662Z,1594909868.662 [CommandLine](IMPORTANT): Failed components:
2020-07-16T14:31:08.662Z,1594909868.662 [CommandLine](IMPORTANT): No failed Components.
2020-07-16T14:31:10.719Z,1594909870.719 [DVL_micro](INFO): Failure count cleared after critical for DVL_micro
2020-07-16T14:31:16.950Z,1594909876.950 [CommandLine](IMPORTANT): got command gfscan
2020-07-16T14:31:16.996Z,1594909876.996 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-16T14:31:27.867Z,1594909887.867 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006898
CHAN A1 (24V): 0.003621
CHAN A2 (12V): -0.005688
CHAN A3 (5V): -0.003531
CHAN B0 (3.3V): -0.001320
CHAN B1 (3.15aV): -0.001362
CHAN B2 (3.15bV): -0.000781
CHAN B3 (GND): -0.000503
OPEN: 0.004933
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-16T14:31:29.101Z,1594909889.101 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2020-07-16T14:31:29.101Z,1594909889.101 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2020-07-16T14:31:29.104Z,1594909889.104 [BPC1](INFO): Received data from all battery sticks.
2020-07-16T14:31:56.951Z,1594909916.951 [CommandLine](IMPORTANT): got command restart system
2020-07-16T14:31:59.021Z,1594909919.021 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-16T14:31:59.021Z,1594909919.021 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-16T14:31:59.205Z,1594909919.205 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-07-16T14:31:59.205Z,1594909919.205 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-16T14:31:59.206Z,1594909919.206 [CommandLine](INFO): Join timeout helper Thread ID is 970
2020-07-16T14:31:59.206Z,1594909919.206 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-07-16T14:31:59.206Z,1594909919.206 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-16T14:31:59.207Z,1594909919.207 [NavChartDb](INFO): Join timeout helper Thread ID is 971
2020-07-16T14:31:59.357Z,1594909919.357 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-16T14:31:59.357Z,1594909919.357 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-16T14:31:59.373Z,1594909919.373 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-07-16T14:31:59.373Z,1594909919.373 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-16T14:31:59.373Z,1594909919.373 [Radio_Surface](INFO): Join timeout helper Thread ID is 972
2020-07-16T14:31:59.445Z,1594909919.445 [Radio_Surface](INFO): Powering down
2020-07-16T14:31:59.446Z,1594909919.446 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-16T14:31:59.446Z,1594909919.446 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-16T14:31:59.457Z,1594909919.457 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-07-16T14:31:59.457Z,1594909919.457 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-16T14:31:59.457Z,1594909919.457 [Onboard](INFO): Join timeout helper Thread ID is 973
2020-07-16T14:32:03.981Z,1594909923.