2021-03-25T16:19:42.127Z,1616689182.127 [Supervisor](DEBUG): Initializing supervisor.
2021-03-25T16:19:42.136Z,1616689182.136 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-03-25T16:19:42.136Z,1616689182.136 [SyncHandler](INFO): Protected caller Thread ID is 7303
2021-03-25T16:19:42.137Z,1616689182.137 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-03-25T16:19:42.138Z,1616689182.138 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-03-25T16:19:42.138Z,1616689182.138 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7304
2021-03-25T16:19:42.141Z,1616689182.141 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-03-25T16:19:42.166Z,1616689182.166 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-03-25T16:19:42.167Z,1616689182.167 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-03-25T16:19:42.171Z,1616689182.171 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7305
2021-03-25T16:19:42.172Z,1616689182.172 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-03-25T16:19:42.173Z,1616689182.173 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-03-25T16:19:42.173Z,1616689182.173 [logger ThreadHandler](INFO): Protected caller Thread ID is 7306
2021-03-25T16:19:42.176Z,1616689182.176 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-03-25T16:19:42.176Z,1616689182.176 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-03-25T16:19:42.182Z,1616689182.182 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-03-25T16:19:43.082Z,1616689183.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-03-25T16:19:43.082Z,1616689183.082 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-03-25T16:19:43.302Z,1616689183.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-03-25T16:19:43.308Z,1616689183.308 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-03-25T16:19:43.430Z,1616689183.430 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-03-25T16:19:43.752Z,1616689183.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-03-25T16:19:43.756Z,1616689183.756 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-03-25T16:19:43.908Z,1616689183.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2021-03-25T16:19:43.912Z,1616689183.912 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-03-25T16:19:44.052Z,1616689184.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-03-25T16:19:44.052Z,1616689184.052 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-03-25T16:19:44.652Z,1616689184.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-03-25T16:19:44.656Z,1616689184.656 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-03-25T16:19:44.810Z,1616689184.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-03-25T16:19:44.816Z,1616689184.816 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-03-25T16:19:45.862Z,1616689185.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-03-25T16:19:45.868Z,1616689185.868 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-03-25T16:19:46.166Z,1616689186.166 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-03-25T16:19:46.167Z,1616689186.167 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-03-25T16:19:46.622Z,1616689186.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-03-25T16:19:46.623Z,1616689186.623 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-03-25T16:19:46.958Z,1616689186.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-03-25T16:19:46.959Z,1616689186.959 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-03-25T16:19:47.186Z,1616689187.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-03-25T16:19:47.187Z,1616689187.187 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-03-25T16:19:48.204Z,1616689188.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-03-25T16:19:48.208Z,1616689188.208 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-03-25T16:19:48.708Z,1616689188.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-03-25T16:19:48.712Z,1616689188.712 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-03-25T16:19:49.022Z,1616689189.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-03-25T16:19:49.024Z,1616689189.024 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2021-03-25T16:19:49.028Z,1616689189.028 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2021-03-25T16:19:49.320Z,1616689189.320 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2021-03-25T16:19:49.442Z,1616689189.442 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2021-03-25T16:19:49.676Z,1616689189.676 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2021-03-25T16:19:49.798Z,1616689189.798 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2021-03-25T16:19:50.188Z,1616689190.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-03-25T16:19:50.192Z,1616689190.192 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2021-03-25T16:19:50.350Z,1616689190.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2021-03-25T16:19:50.514Z,1616689190.514 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2021-03-25T16:19:50.656Z,1616689190.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2021-03-25T16:19:50.885Z,1616689190.885 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2021-03-25T16:19:51.903Z,1616689191.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2021-03-25T16:19:52.087Z,1616689192.087 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2021-03-25T16:19:52.088Z,1616689192.088 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-03-25T16:19:52.108Z,1616689192.108 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-03-25T16:19:52.139Z,1616689192.139 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-03-25T16:19:52.140Z,1616689192.140 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-03-25T16:19:52.260Z,1616689192.260 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-03-25T16:19:52.261Z,1616689192.261 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-03-25T16:19:52.348Z,1616689192.348 [VerticalControl](DEBUG): Construct VerticalControl.
2021-03-25T16:19:52.430Z,1616689192.430 [VerticalControl] Loaded
2021-03-25T16:19:52.430Z,1616689192.430 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-03-25T16:19:52.431Z,1616689192.431 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-03-25T16:19:52.492Z,1616689192.492 [HorizontalControl] Loaded
2021-03-25T16:19:52.492Z,1616689192.492 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-03-25T16:19:52.493Z,1616689192.493 [SpeedControl](DEBUG): Construct SpeedControl.
2021-03-25T16:19:52.496Z,1616689192.496 [SpeedControl] Loaded
2021-03-25T16:19:52.496Z,1616689192.496 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-03-25T16:19:52.497Z,1616689192.497 [LoopControl](DEBUG): Construct LoopControl.
2021-03-25T16:19:52.497Z,1616689192.497 [LoopControl] Loaded
2021-03-25T16:19:52.498Z,1616689192.498 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-03-25T16:19:52.498Z,1616689192.498 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-03-25T16:19:52.499Z,1616689192.499 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-03-25T16:19:52.676Z,1616689192.676 [BuoyancyServo] Loaded
2021-03-25T16:19:52.676Z,1616689192.676 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-03-25T16:19:52.701Z,1616689192.701 [ElevatorServo] Loaded
2021-03-25T16:19:52.701Z,1616689192.701 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-03-25T16:19:52.724Z,1616689192.724 [MassServo] Loaded
2021-03-25T16:19:52.725Z,1616689192.725 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-03-25T16:19:52.748Z,1616689192.748 [RudderServo] Loaded
2021-03-25T16:19:52.748Z,1616689192.748 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-03-25T16:19:52.771Z,1616689192.771 [ThrusterServo] Loaded
2021-03-25T16:19:52.771Z,1616689192.771 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-03-25T16:19:52.772Z,1616689192.772 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-03-25T16:19:52.772Z,1616689192.772 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-03-25T16:19:52.827Z,1616689192.827 [DepthRateCalculator] Loaded
2021-03-25T16:19:52.835Z,1616689192.835 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-03-25T16:19:52.838Z,1616689192.838 [PitchRateCalculator] Loaded
2021-03-25T16:19:52.838Z,1616689192.838 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-03-25T16:19:52.846Z,1616689192.846 [SpeedCalculator] Loaded
2021-03-25T16:19:52.846Z,1616689192.846 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-03-25T16:19:52.865Z,1616689192.865 [TempGradientCalculator] Loaded
2021-03-25T16:19:52.865Z,1616689192.865 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-03-25T16:19:52.868Z,1616689192.868 [YawRateCalculator] Loaded
2021-03-25T16:19:52.868Z,1616689192.868 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-03-25T16:19:52.891Z,1616689192.891 [ElevatorOffsetCalculator] Loaded
2021-03-25T16:19:52.891Z,1616689192.891 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-03-25T16:19:52.892Z,1616689192.892 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-03-25T16:19:52.892Z,1616689192.892 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-03-25T16:19:53.017Z,1616689193.017 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-03-25T16:19:53.017Z,1616689193.017 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-03-25T16:19:53.036Z,1616689193.036 [NavChart] Loaded
2021-03-25T16:19:53.036Z,1616689193.036 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-03-25T16:19:53.040Z,1616689193.040 [UniversalFixResidualReporter] Loaded
2021-03-25T16:19:53.040Z,1616689193.040 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-03-25T16:19:53.041Z,1616689193.041 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-03-25T16:19:53.041Z,1616689193.041 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-03-25T16:19:53.856Z,1616689193.856 [DataOverHttps] Loaded
2021-03-25T16:19:53.856Z,1616689193.856 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-03-25T16:19:53.857Z,1616689193.857 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408514E0
2021-03-25T16:19:53.857Z,1616689193.857 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7389
2021-03-25T16:19:53.882Z,1616689193.882 [Depth_Keller] Loaded
2021-03-25T16:19:53.883Z,1616689193.883 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-03-25T16:19:53.961Z,1616689193.961 [NAL9602] Loaded
2021-03-25T16:19:53.961Z,1616689193.961 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-03-25T16:19:54.008Z,1616689194.008 [Onboard] Loaded
2021-03-25T16:19:54.008Z,1616689194.008 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-03-25T16:19:54.009Z,1616689194.009 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408814E0
2021-03-25T16:19:54.010Z,1616689194.010 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7390
2021-03-25T16:19:54.020Z,1616689194.020 [Radio_Surface] Loaded
2021-03-25T16:19:54.020Z,1616689194.020 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-03-25T16:19:54.024Z,1616689194.024 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2021-03-25T16:19:54.024Z,1616689194.024 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7391
2021-03-25T16:19:54.131Z,1616689194.131 [DAT] Loaded
2021-03-25T16:19:54.131Z,1616689194.131 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-03-25T16:19:55.320Z,1616689195.320 [BPC1] Loaded
2021-03-25T16:19:55.320Z,1616689195.320 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-03-25T16:19:55.321Z,1616689195.321 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-03-25T16:19:55.321Z,1616689195.321 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-03-25T16:19:55.480Z,1616689195.480 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-03-25T16:19:55.480Z,1616689195.480 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-03-25T16:19:55.635Z,1616689195.635 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-03-25T16:19:55.636Z,1616689195.636 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-03-25T16:19:55.685Z,1616689195.685 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-03-25T16:19:55.686Z,1616689195.686 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-03-25T16:19:56.071Z,1616689196.071 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2021-03-25T16:19:56.074Z,1616689196.074 [CTD_Seabird](INFO): created writer for : sea_water_density
2021-03-25T16:19:56.076Z,1616689196.076 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2021-03-25T16:19:56.079Z,1616689196.079 [CTD_Seabird](INFO): created writer for : depth
2021-03-25T16:19:56.079Z,1616689196.079 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2021-03-25T16:19:56.082Z,1616689196.082 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2021-03-25T16:19:56.082Z,1616689196.082 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2021-03-25T16:19:56.085Z,1616689196.085 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2021-03-25T16:19:56.086Z,1616689196.086 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2021-03-25T16:19:56.096Z,1616689196.096 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2021-03-25T16:19:56.097Z,1616689196.097 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2021-03-25T16:19:56.100Z,1616689196.100 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2021-03-25T16:19:56.101Z,1616689196.101 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2021-03-25T16:19:56.103Z,1616689196.103 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2021-03-25T16:19:56.125Z,1616689196.125 [CTD_Seabird] Loaded
2021-03-25T16:19:56.125Z,1616689196.125 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-03-25T16:19:56.132Z,1616689196.132 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A594E0
2021-03-25T16:19:56.132Z,1616689196.132 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7392
2021-03-25T16:19:56.170Z,1616689196.170 [ESPComponent] Loaded
2021-03-25T16:19:56.170Z,1616689196.170 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2021-03-25T16:19:56.195Z,1616689196.195 [PAR_Licor] Loaded
2021-03-25T16:19:56.195Z,1616689196.195 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-03-25T16:19:56.215Z,1616689196.215 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2021-03-25T16:19:56.216Z,1616689196.216 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2021-03-25T16:19:56.218Z,1616689196.218 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2021-03-25T16:19:56.218Z,1616689196.218 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2021-03-25T16:19:56.220Z,1616689196.220 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2021-03-25T16:19:56.220Z,1616689196.220 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2021-03-25T16:19:56.222Z,1616689196.222 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2021-03-25T16:19:56.222Z,1616689196.222 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2021-03-25T16:19:56.232Z,1616689196.232 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2021-03-25T16:19:56.232Z,1616689196.232 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2021-03-25T16:19:56.234Z,1616689196.234 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2021-03-25T16:19:56.234Z,1616689196.234 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2021-03-25T16:19:56.236Z,1616689196.236 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2021-03-25T16:19:56.236Z,1616689196.236 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2021-03-25T16:19:56.238Z,1616689196.238 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T16:19:56.241Z,1616689196.241 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T16:19:56.241Z,1616689196.241 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T16:19:56.241Z,1616689196.241 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T16:19:56.243Z,1616689196.243 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T16:19:56.243Z,1616689196.243 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T16:19:56.245Z,1616689196.245 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T16:19:56.245Z,1616689196.245 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2021-03-25T16:19:56.247Z,1616689196.247 [WetLabsBB2FL] Loaded
2021-03-25T16:19:56.255Z,1616689196.255 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-03-25T16:19:56.256Z,1616689196.256 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A894E0
2021-03-25T16:19:56.257Z,1616689196.257 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7393
2021-03-25T16:19:56.258Z,1616689196.258 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-03-25T16:19:56.258Z,1616689196.258 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-03-25T16:19:56.841Z,1616689196.841 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-03-25T16:19:56.848Z,1616689196.848 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-03-25T16:19:57.060Z,1616689197.060 [SBIT](DEBUG): Construct Startup Built In Test.
2021-03-25T16:19:57.076Z,1616689197.076 [SBIT] Loaded
2021-03-25T16:19:57.077Z,1616689197.077 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-03-25T16:19:57.077Z,1616689197.077 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-03-25T16:19:57.090Z,1616689197.090 [IBIT] Loaded
2021-03-25T16:19:57.090Z,1616689197.090 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-03-25T16:19:57.102Z,1616689197.102 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-03-25T16:19:57.248Z,1616689197.248 [CBIT] Loaded
2021-03-25T16:19:57.249Z,1616689197.249 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-03-25T16:19:57.249Z,1616689197.249 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-03-25T16:19:57.253Z,1616689197.253 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-03-25T16:19:57.260Z,1616689197.260 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-03-25T16:19:57.268Z,1616689197.268 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-03-25T16:19:57.283Z,1616689197.283 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B674E0
2021-03-25T16:19:57.283Z,1616689197.283 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7394
2021-03-25T16:19:57.292Z,1616689197.292 [Supervisor](INFO): Main Thread ID is 7297
2021-03-25T16:19:57.292Z,1616689197.292 [Supervisor](DEBUG): Running supervisor.
2021-03-25T16:19:57.293Z,1616689197.293 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7395
2021-03-25T16:19:57.300Z,1616689197.300 [controlThread ThreadHandler](INFO): Handler Thread ID is 7396
2021-03-25T16:19:57.301Z,1616689197.301 [controlThread](DEBUG): Initializing ControlThread
2021-03-25T16:19:57.302Z,1616689197.302 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-03-25T16:19:57.303Z,1616689197.303 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-03-25T16:19:57.304Z,1616689197.304 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-03-25T16:19:57.305Z,1616689197.305 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-03-25T16:19:57.306Z,1616689197.306 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-03-25T16:19:57.306Z,1616689197.306 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-03-25T16:19:57.306Z,1616689197.306 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-03-25T16:19:57.307Z,1616689197.307 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-03-25T16:19:57.312Z,1616689197.312 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-03-25T16:19:57.312Z,1616689197.312 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-03-25T16:19:57.313Z,1616689197.313 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-03-25T16:19:57.314Z,1616689197.314 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-03-25T16:19:57.324Z,1616689197.324 [SBIT](INFO): Initialize SBIT Component.
2021-03-25T16:19:57.324Z,1616689197.324 [SBIT](IMPORTANT): git: 2020-11-24-6-gcb638d650
2021-03-25T16:19:57.324Z,1616689197.324 [SBIT](INFO): git hash: cb638d650ac65501896d406bf5988c2fe747cbc6
2021-03-25T16:19:57.325Z,1616689197.325 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-03-25T16:19:57.326Z,1616689197.326 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020
2021-03-25T16:19:57.327Z,1616689197.327 [SBIT](INFO): Beginning SBIT in 34.000000 seconds.
2021-03-25T16:19:57.327Z,1616689197.327 [IBIT](INFO): Initialize IBIT Component.
2021-03-25T16:19:57.328Z,1616689197.328 [CBIT](DEBUG): Initialize CBIT Component.
2021-03-25T16:19:57.329Z,1616689197.329 [logger ThreadHandler](INFO): Handler Thread ID is 7397
2021-03-25T16:19:57.339Z,1616689197.339 [CBIT](DEBUG): Initialized mux pins.
2021-03-25T16:19:57.340Z,1616689197.340 [CBIT](DEBUG): Initializing the watchdog timer.
2021-03-25T16:19:57.360Z,1616689197.360 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7398
2021-03-25T16:19:57.361Z,1616689197.361 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-03-25T16:19:57.363Z,1616689197.363 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-03-25T16:19:57.364Z,1616689197.364 [CBIT](DEBUG): Initializing heartbeat.
2021-03-25T16:19:57.377Z,1616689197.377 [Onboard ThreadHandler](INFO): Handler Thread ID is 7399
2021-03-25T16:19:57.410Z,1616689197.410 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7400
2021-03-25T16:19:57.424Z,1616689197.424 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7401
2021-03-25T16:19:57.424Z,1616689197.424 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-03-25T16:19:57.433Z,1616689197.433 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7403
2021-03-25T16:19:57.434Z,1616689197.434 [WetLabsBB2FL](INFO): Powering down
2021-03-25T16:19:57.435Z,1616689197.435 [CBIT](DEBUG): Deactivating GF circuits.
2021-03-25T16:19:57.436Z,1616689197.436 [CBIT](DEBUG): Deactivating emergency mode.
2021-03-25T16:19:57.471Z,1616689197.471 [CBIT](DEBUG): Backplane powered.
2021-03-25T16:19:57.597Z,1616689197.597 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-03-25T16:19:57.612Z,1616689197.612 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7404
2021-03-25T16:19:57.742Z,1616689197.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-03-25T16:19:57.742Z,1616689197.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-03-25T16:19:57.742Z,1616689197.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-03-25T16:19:57.742Z,1616689197.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-03-25T16:19:57.742Z,1616689197.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-03-25T16:19:57.742Z,1616689197.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-03-25T16:19:57.742Z,1616689197.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-03-25T16:19:57.743Z,1616689197.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-03-25T16:19:57.743Z,1616689197.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-03-25T16:19:57.743Z,1616689197.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-03-25T16:19:57.743Z,1616689197.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-03-25T16:19:57.743Z,1616689197.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-03-25T16:19:57.743Z,1616689197.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-03-25T16:19:57.743Z,1616689197.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-03-25T16:19:57.744Z,1616689197.744 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-03-25T16:19:57.744Z,1616689197.744 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-03-25T16:19:57.785Z,1616689197.785 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-03-25T16:19:57.820Z,1616689197.820 [Radio_Surface](INFO): Powering up
2021-03-25T16:19:57.904Z,1616689197.904 [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>


2021-03-25T16:19:57.904Z,1616689197.904 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-03-25T16:19:58.609Z,1616689198.609 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-03-25T16:19:58.611Z,1616689198.611 [Default:A.Wait](DEBUG): Construct Wait.
2021-03-25T16:19:58.644Z,1616689198.644 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-03-25T16:19:58.720Z,1616689198.720 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-03-25T16:19:58.722Z,1616689198.722 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-03-25T16:19:58.748Z,1616689198.748 [Default:E.Execute](DEBUG): Construct Execute.
2021-03-25T16:19:58.776Z,1616689198.776 [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>


2021-03-25T16:19:58.780Z,1616689198.780 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,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,
2021-03-25T16:19:58.805Z,1616689198.805 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2021-03-25T16:19:58.828Z,1616689198.828 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-03-25T16:19:58.829Z,1616689198.829 [DAT](INFO): Powering up
2021-03-25T16:19:58.829Z,1616689198.829 [DAT](DEBUG): Initializing DAT.
2021-03-25T16:19:58.899Z,1616689198.899 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-03-25T16:19:59.005Z,1616689199.005 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-03-25T16:19:59.646Z,1616689199.646 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-03-25T16:19:59.652Z,1616689199.652 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-03-25T16:19:59.653Z,1616689199.653 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-03-25T16:19:59.668Z,1616689199.668 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-03-25T16:19:59.669Z,1616689199.669 [MassServo](DEBUG): Initializing EZServoServo.
2021-03-25T16:19:59.692Z,1616689199.692 [MassServo](DEBUG): Initializing MassServo.
2021-03-25T16:19:59.693Z,1616689199.693 [RudderServo](DEBUG): Initializing EZServoServo.
2021-03-25T16:19:59.712Z,1616689199.712 [RudderServo](DEBUG): Initializing RudderServo.
2021-03-25T16:19:59.713Z,1616689199.713 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-03-25T16:19:59.728Z,1616689199.728 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-03-25T16:20:09.157Z,1616689209.157 [DAT](INFO): DAT read: 

2021-03-25T16:20:09.159Z,1616689209.159 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2021-03-25T16:20:10.381Z,1616689210.381 [DAT](INFO): DAT read: MF Frequency Band

2021-03-25T16:20:10.383Z,1616689210.383 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.12.21

2021-03-25T16:20:10.391Z,1616689210.391 [DAT](INFO): DAT read: Mar 25 2021 16:18:10 

2021-03-25T16:20:11.197Z,1616689211.197 [DAT](INFO): DAT read: Features enabled [Bearing]

2021-03-25T16:20:11.199Z,1616689211.199 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2021-03-25T16:20:11.203Z,1616689211.203 [DAT](INFO): commRate: 800
2021-03-25T16:20:13.249Z,1616689213.249 [DAT](INFO): entering command mode
2021-03-25T16:20:13.641Z,1616689213.641 [DAT](INFO): DAT read: 

2021-03-25T16:20:13.641Z,1616689213.641 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:14.049Z,1616689214.049 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:14.469Z,1616689214.469 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:14.865Z,1616689214.865 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:15.273Z,1616689215.273 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:15.669Z,1616689215.669 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:16.073Z,1616689216.073 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:16.477Z,1616689216.477 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:16.881Z,1616689216.881 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:17.297Z,1616689217.297 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:17.461Z,1616689217.461 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004388
2021-03-25T16:20:17.689Z,1616689217.689 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:18.093Z,1616689218.093 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:18.501Z,1616689218.501 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:18.901Z,1616689218.901 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:19.305Z,1616689219.305 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:19.709Z,1616689219.709 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:20.113Z,1616689220.113 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:20.517Z,1616689220.517 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:20.921Z,1616689220.921 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:21.325Z,1616689221.325 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:21.729Z,1616689221.729 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:22.133Z,1616689222.133 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:22.537Z,1616689222.537 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:22.941Z,1616689222.941 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:23.349Z,1616689223.349 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:23.749Z,1616689223.749 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:24.153Z,1616689224.153 [NAL9602](INFO): Powering up NAL9602
2021-03-25T16:20:24.154Z,1616689224.154 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:24.557Z,1616689224.557 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:24.961Z,1616689224.961 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:25.365Z,1616689225.365 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:25.769Z,1616689225.769 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:26.169Z,1616689226.169 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:26.577Z,1616689226.577 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:26.981Z,1616689226.981 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:27.385Z,1616689227.385 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:27.785Z,1616689227.785 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:28.193Z,1616689228.193 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:28.593Z,1616689228.593 [DAT](DEBUG): checking for command mode acknowledgment
2021-03-25T16:20:28.593Z,1616689228.593 [DAT](FAULT): failed to enter command mode
2021-03-25T16:20:29.001Z,1616689229.001 [DAT](INFO): entering command mode
2021-03-25T16:20:29.405Z,1616689229.405 [DAT](INFO): DAT read: user:1>

2021-03-25T16:20:29.406Z,1616689229.406 [DAT](INFO): DAT read: Command '+++' not found

2021-03-25T16:20:29.406Z,1616689229.406 [DAT](INFO): DAT read: Error

2021-03-25T16:20:29.407Z,1616689229.407 [DAT](INFO): setting verbose to 3
2021-03-25T16:20:29.805Z,1616689229.805 [DAT](INFO): DAT read: user:2>

2021-03-25T16:20:29.806Z,1616689229.806 [DAT](INFO): DAT read: Verbose         | 3           

2021-03-25T16:20:29.806Z,1616689229.806 [DAT](INFO): set verbose to 3
2021-03-25T16:20:29.807Z,1616689229.807 [DAT](INFO): setting DatVerbose to 27440
2021-03-25T16:20:30.213Z,1616689230.213 [DAT](INFO): DAT read: user:3>

2021-03-25T16:20:30.214Z,1616689230.214 [DAT](INFO): DAT read: DatVerbose      | 27440       

2021-03-25T16:20:30.214Z,1616689230.214 [DAT](INFO): set DatVerbose to 27440
2021-03-25T16:20:30.215Z,1616689230.215 [DAT](INFO): setting transmit power to 8
2021-03-25T16:20:30.617Z,1616689230.617 [DAT](INFO): DAT read: user:4>

2021-03-25T16:20:30.618Z,1616689230.618 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2021-03-25T16:20:30.618Z,1616689230.618 [DAT](INFO): set transmit power to 8
2021-03-25T16:20:30.619Z,1616689230.619 [DAT](INFO): setting local address to 5
2021-03-25T16:20:31.021Z,1616689231.021 [DAT](INFO): DAT read: user:5>

2021-03-25T16:20:31.022Z,1616689231.022 [DAT](INFO): DAT read: LocalAddr       | 5           

2021-03-25T16:20:31.022Z,1616689231.022 [DAT](INFO): set local address to 5
2021-03-25T16:20:31.843Z,1616689231.843 [SBIT](IMPORTANT): Beginning Startup BIT
2021-03-25T16:20:31.848Z,1616689231.848 [CBIT](IMPORTANT): Beginning ground fault scan
2021-03-25T16:20:35.078Z,1616689235.078 [NAL9602](INFO): NAL9602 initialized
2021-03-25T16:20:39.489Z,1616689239.489 [CommandLine](IMPORTANT): got command failComponent
2021-03-25T16:20:39.489Z,1616689239.489 [CommandLine](IMPORTANT): Failed components:
2021-03-25T16:20:39.490Z,1616689239.490 [CommandLine](IMPORTANT): No failed Components.
2021-03-25T16:20:42.772Z,1616689242.772 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008008
CHAN A1 (24V): -0.008251
CHAN A2 (12V): -0.004293
CHAN A3 (5V): -0.002346
CHAN B0 (3.3V): -0.001211
CHAN B1 (3.15aV): -0.000546
CHAN B2 (3.15bV): -0.000991
CHAN B3 (GND): 0.000126
OPEN: 0.004986
Full Scale Calc: 4.765 mA, -1.589 mA
2021-03-25T16:21:25.982Z,1616689285.982 [SBIT](IMPORTANT): SBIT PASSED
2021-03-25T16:21:25.982Z,1616689285.982 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-03-25T16:21:25.983Z,1616689285.983 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2021-03-25T16:21:25.983Z,1616689285.983 [SBIT](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2021-03-25T16:21:25.983Z,1616689285.983 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2021-03-25T16:21:25.983Z,1616689285.983 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2021-03-25T16:21:25.983Z,1616689285.983 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2021-03-25T16:21:25.983Z,1616689285.983 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2021-03-25T16:21:25.984Z,1616689285.984 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2021-03-25T16:21:25.984Z,1616689285.984 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2021-03-25T16:21:25.984Z,1616689285.984 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter;

2021-03-25T16:21:25.984Z,1616689285.984 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2021-03-25T16:21:25.984Z,1616689285.984 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2021-03-25T16:21:25.984Z,1616689285.984 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2021-03-25T16:21:25.984Z,1616689285.984 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=168 cubic_centimeter;

2021-03-25T16:21:25.984Z,1616689285.984 [SBIT](IMPORTANT): VerticalControl.massDefault=3.7 millimeter;

2021-03-25T16:21:26.411Z,1616689286.411 [MissionManager](IMPORTANT): Started mission Startup
2021-03-25T16:21:26.412Z,1616689286.412 [Startup] Running Loop=1
2021-03-25T16:21:26.412Z,1616689286.412 [Startup](DEBUG): Aggregate::initialize Startup
2021-03-25T16:21:26.412Z,1616689286.412 [Startup:A.GoToSurface] Running Loop=1
2021-03-25T16:21:26.412Z,1616689286.412 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-03-25T16:21:26.413Z,1616689286.413 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-03-25T16:21:26.413Z,1616689286.413 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-03-25T16:21:26.413Z,1616689286.413 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-03-25T16:21:26.414Z,1616689286.414 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-03-25T16:21:26.414Z,1616689286.414 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-03-25T16:21:26.424Z,1616689286.424 [Startup:StartupSatComms] Running Loop=1
2021-03-25T16:21:26.424Z,1616689286.424 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-03-25T16:21:26.424Z,1616689286.424 [Startup:StartupSatComms:A] Running Loop=1
2021-03-25T16:21:26.784Z,1616689286.784 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-03-25T16:21:27.601Z,1616689287.601 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter
2021-03-25T16:21:27.602Z,1616689287.602 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2021-03-25T16:21:27.613Z,1616689287.613 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-03-25T16:21:57.524Z,1616689317.524 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-03-25T16:21:57.525Z,1616689317.525 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-03-25T16:21:57.525Z,1616689317.525 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-03-25T16:21:57.584Z,1616689317.584 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-03-25T16:21:57.972Z,1616689317.972 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-03-25T16:21:57.972Z,1616689317.972 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-03-25T16:22:07.016Z,1616689327.016 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2021-03-25T16:22:07.257Z,1616689327.257 [Reporter](INFO): platform_buoyancy_position 534.889648 cc
2021-03-25T16:22:07.669Z,1616689327.669 [Reporter](INFO): platform_buoyancy_position 528.443237 cc
2021-03-25T16:22:08.063Z,1616689328.063 [Reporter](INFO): platform_buoyancy_position 524.011353 cc
2021-03-25T16:22:08.479Z,1616689328.479 [Reporter](INFO): platform_buoyancy_position 517.833557 cc
2021-03-25T16:22:08.893Z,1616689328.893 [Reporter](INFO): platform_buoyancy_position 513.267334 cc
2021-03-25T16:22:09.285Z,1616689329.285 [Reporter](INFO): platform_buoyancy_position 506.955261 cc
2021-03-25T16:22:09.701Z,1616689329.701 [Reporter](INFO): platform_buoyancy_position 502.523376 cc
2021-03-25T16:22:10.105Z,1616689330.105 [Reporter](INFO): platform_buoyancy_position 497.688538 cc
2021-03-25T16:22:10.506Z,1616689330.506 [Reporter](INFO): platform_buoyancy_position 491.779358 cc
2021-03-25T16:22:10.897Z,1616689330.897 [Reporter](INFO): platform_buoyancy_position 487.481750 cc
2021-03-25T16:22:11.314Z,1616689331.314 [Reporter](INFO): platform_buoyancy_position 481.035339 cc
2021-03-25T16:22:11.703Z,1616689331.703 [Reporter](INFO): platform_buoyancy_position 476.737732 cc
2021-03-25T16:22:12.168Z,1616689332.168 [Reporter](INFO): platform_buoyancy_position 470.291321 cc
2021-03-25T16:22:12.550Z,1616689332.550 [Reporter](INFO): platform_buoyancy_position 465.993774 cc
2021-03-25T16:22:12.933Z,1616689332.933 [Reporter](INFO): platform_buoyancy_position 459.547363 cc
2021-03-25T16:22:13.349Z,1616689333.349 [Reporter](INFO): platform_buoyancy_position 455.249756 cc
2021-03-25T16:22:13.788Z,1616689333.788 [Reporter](INFO): platform_buoyancy_position 448.803345 cc
2021-03-25T16:22:14.149Z,1616689334.149 [Reporter](INFO): platform_buoyancy_position 444.640076 cc
2021-03-25T16:22:14.549Z,1616689334.549 [Reporter](INFO): platform_buoyancy_position 438.193665 cc
2021-03-25T16:22:14.953Z,1616689334.953 [Reporter](INFO): platform_buoyancy_position 433.761719 cc
2021-03-25T16:22:15.350Z,1616689335.350 [Reporter](INFO): platform_buoyancy_position 427.986816 cc
2021-03-25T16:22:15.745Z,1616689335.745 [Reporter](INFO): platform_buoyancy_position 423.017761 cc
2021-03-25T16:22:16.161Z,1616689336.161 [Reporter](INFO): platform_buoyancy_position 416.705627 cc
2021-03-25T16:22:16.549Z,1616689336.549 [Reporter](INFO): platform_buoyancy_position 412.408020 cc
2021-03-25T16:22:16.962Z,1616689336.962 [Reporter](INFO): platform_buoyancy_position 407.976135 cc
2021-03-25T16:22:17.357Z,1616689337.357 [Reporter](INFO): platform_buoyancy_position 401.664062 cc
2021-03-25T16:22:17.769Z,1616689337.769 [Reporter](INFO): platform_buoyancy_position 397.232117 cc
2021-03-25T16:22:18.189Z,1616689338.189 [Reporter](INFO): platform_buoyancy_position 390.920044 cc
2021-03-25T16:22:18.578Z,1616689338.578 [Reporter](INFO): platform_buoyancy_position 386.488159 cc
2021-03-25T16:22:18.969Z,1616689338.969 [Reporter](INFO): platform_buoyancy_position 380.176025 cc
2021-03-25T16:22:19.385Z,1616689339.385 [Reporter](INFO): platform_buoyancy_position 375.744141 cc
2021-03-25T16:22:19.774Z,1616689339.774 [Reporter](INFO): platform_buoyancy_position 369.432068 cc
2021-03-25T16:22:20.189Z,1616689340.189 [Reporter](INFO): platform_buoyancy_position 365.134460 cc
2021-03-25T16:22:20.589Z,1616689340.589 [Reporter](INFO): platform_buoyancy_position 358.553711 cc
2021-03-25T16:22:20.993Z,1616689340.993 [Reporter](INFO): platform_buoyancy_position 354.390442 cc
2021-03-25T16:22:21.445Z,1616689341.445 [Reporter](INFO): platform_buoyancy_position 348.078369 cc
2021-03-25T16:22:21.811Z,1616689341.811 [Reporter](INFO): platform_buoyancy_position 343.780762 cc
2021-03-25T16:22:22.224Z,1616689342.224 [Reporter](INFO): platform_buoyancy_position 338.945923 cc
2021-03-25T16:22:22.641Z,1616689342.641 [Reporter](INFO): platform_buoyancy_position 332.902466 cc
2021-03-25T16:22:23.037Z,1616689343.037 [Reporter](INFO): platform_buoyancy_position 327.261841 cc
2021-03-25T16:22:23.423Z,1616689343.423 [Reporter](INFO): platform_buoyancy_position 322.292725 cc
2021-03-25T16:22:23.842Z,1616689343.842 [Reporter](INFO): platform_buoyancy_position 317.726562 cc
2021-03-25T16:22:24.252Z,1616689344.252 [Reporter](INFO): platform_buoyancy_position 311.280151 cc
2021-03-25T16:22:24.629Z,1616689344.629 [Reporter](INFO): platform_buoyancy_position 306.982544 cc
2021-03-25T16:22:25.037Z,1616689345.037 [Reporter](INFO): platform_buoyancy_position 300.536133 cc
2021-03-25T16:22:25.454Z,1616689345.454 [Reporter](INFO): platform_buoyancy_position 296.372833 cc
2021-03-25T16:22:25.849Z,1616689345.849 [Reporter](INFO): platform_buoyancy_position 290.060730 cc
2021-03-25T16:22:26.245Z,1616689346.245 [Reporter](INFO): platform_buoyancy_position 284.688721 cc
2021-03-25T16:22:26.609Z,1616689346.609 [Startup:StartupSatComms:A](INFO): Timed out from 2021-03-25T16:21:26.4Z
2021-03-25T16:22:26.609Z,1616689346.609 [Startup:StartupSatComms:A] Stopped
2021-03-25T16:22:26.609Z,1616689346.609 [Startup:StartupSatComms:B] Running Loop=1
2021-03-25T16:22:26.665Z,1616689346.665 [Reporter](INFO): platform_buoyancy_position 279.048126 cc
2021-03-25T16:22:27.124Z,1616689347.124 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-03-25T16:22:27.176Z,1616689347.176 [Reporter](INFO): platform_buoyancy_position 272.601746 cc
2021-03-25T16:22:27.413Z,1616689347.413 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-03-25T16:22:27.502Z,1616689347.502 [Reporter](INFO): platform_buoyancy_position 268.304138 cc
2021-03-25T16:22:27.905Z,1616689347.905 [Reporter](INFO): platform_buoyancy_position 263.872223 cc
2021-03-25T16:22:28.218Z,1616689348.218 [NAL9602](INFO): SBD MO Status=2, MOMSN=4074, MT Status=2, MTMSN=0
2021-03-25T16:22:28.218Z,1616689348.218 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-03-25T16:22:28.334Z,1616689348.334 [Reporter](INFO): platform_buoyancy_position 257.694427 cc
2021-03-25T16:22:28.693Z,1616689348.693 [Reporter](INFO): platform_buoyancy_position 253.262558 cc
2021-03-25T16:22:29.095Z,1616689349.095 [Reporter](INFO): platform_buoyancy_position 248.562042 cc
2021-03-25T16:22:29.497Z,1616689349.497 [Reporter](INFO): platform_buoyancy_position 242.652832 cc
2021-03-25T16:22:29.883Z,1616689349.883 [Reporter](INFO): platform_buoyancy_position 237.952332 cc
2021-03-25T16:22:30.295Z,1616689350.295 [Reporter](INFO): platform_buoyancy_position 231.908844 cc
2021-03-25T16:22:30.689Z,1616689350.689 [Reporter](INFO): platform_buoyancy_position 227.476929 cc
2021-03-25T16:22:31.101Z,1616689351.101 [Reporter](INFO): platform_buoyancy_position 221.030518 cc
2021-03-25T16:22:31.495Z,1616689351.495 [Reporter](INFO): platform_buoyancy_position 216.867218 cc
2021-03-25T16:22:33.474Z,1616689353.474 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:22:35.197Z,1616689355.197 [Reporter](INFO): platform_buoyancy_position 201.691315 cc
2021-03-25T16:22:36.285Z,1616689356.285 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210317T144632/Courier0289.lzma
2021-03-25T16:22:37.286Z,1616689357.286 [DataOverHttps](INFO): Moved sent file to Logs/20210317T144632/Courier0289.lzma.bak
2021-03-25T16:22:37.286Z,1616689357.286 [DataOverHttps](INFO): SBD MOMSN=15446377
2021-03-25T16:22:48.826Z,1616689368.826 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:22:54.478Z,1616689374.478 [NAL9602](INFO): SBD MO Status=0, MOMSN=4074, MT Status=0, MTMSN=0
2021-03-25T16:22:54.478Z,1616689374.478 [NAL9602](INFO): No messages in MT queue
2021-03-25T16:22:54.843Z,1616689374.843 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210325T161942/Courier0000.lzma
2021-03-25T16:22:57.830Z,1616689377.830 [DataOverHttps](INFO): Moved sent file to Logs/20210325T161942/Courier0000.lzma.bak
2021-03-25T16:22:57.830Z,1616689377.830 [DataOverHttps](INFO): SBD MOMSN=15446379
2021-03-25T16:23:04.593Z,1616689384.593 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:23:11.951Z,1616689391.951 [CommandLine](IMPORTANT): got command report mod platform_mass_position
2021-03-25T16:23:12.274Z,1616689392.274 [Reporter](INFO): platform_mass_position 0.003700 m
2021-03-25T16:23:17.141Z,1616689397.141 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20210317T144632/Express0290.lzma
2021-03-25T16:23:18.142Z,1616689398.142 [DataOverHttps](INFO): Moved sent file to Logs/20210317T144632/Express0290.lzma.bak
2021-03-25T16:23:18.142Z,1616689398.142 [DataOverHttps](INFO): SBD MOMSN=15446382
2021-03-25T16:23:20.338Z,1616689400.338 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:23:26.816Z,1616689406.816 [Startup:StartupSatComms:B](INFO): Timed out from 2021-03-25T16:22:26.6Z
2021-03-25T16:23:26.816Z,1616689406.816 [Startup:StartupSatComms:B] Stopped
2021-03-25T16:23:26.816Z,1616689406.816 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-03-25T16:23:26.817Z,1616689406.817 [Startup:StartupSatComms] Stopped
2021-03-25T16:23:26.817Z,1616689406.817 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-03-25T16:23:26.817Z,1616689406.817 [Startup](INFO): Completed Startup
2021-03-25T16:23:26.817Z,1616689406.817 [MissionManager](INFO): Startup is completed.
2021-03-25T16:23:26.818Z,1616689406.818 [MissionManager](INFO): Uninitializing Mission Startup
2021-03-25T16:23:26.818Z,1616689406.818 [Startup] Stopped
2021-03-25T16:23:26.818Z,1616689406.818 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-03-25T16:23:26.818Z,1616689406.818 [Startup:A.GoToSurface] Stopped
2021-03-25T16:23:26.818Z,1616689406.818 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-03-25T16:23:27.227Z,1616689407.227 [MissionManager](IMPORTANT): Started mission Default
2021-03-25T16:23:27.231Z,1616689407.231 [Default] Running Loop=1
2021-03-25T16:23:27.231Z,1616689407.231 [Default](DEBUG): Aggregate::initialize Default
2021-03-25T16:23:27.232Z,1616689407.232 [Default:B.GoToSurface] Running Loop=1
2021-03-25T16:23:27.232Z,1616689407.232 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-03-25T16:23:27.232Z,1616689407.232 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-03-25T16:23:27.232Z,1616689407.232 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-03-25T16:23:27.232Z,1616689407.232 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-03-25T16:23:27.233Z,1616689407.233 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-03-25T16:23:27.233Z,1616689407.233 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-03-25T16:23:27.233Z,1616689407.233 [Default:A.Wait] Running Loop=1
2021-03-25T16:23:27.233Z,1616689407.233 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-03-25T16:23:36.494Z,1616689416.494 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:23:37.550Z,1616689417.550 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 10.000000 millimeter
2021-03-25T16:23:37.551Z,1616689417.551 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2021-03-25T16:23:37.813Z,1616689417.813 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-03-25T16:23:40.205Z,1616689420.205 [Reporter](INFO): platform_mass_position 0.003980 m
2021-03-25T16:23:40.569Z,1616689420.569 [Default:A.Wait](INFO): Done Waiting.
2021-03-25T16:23:40.569Z,1616689420.569 [Default:A.Wait] Stopped
2021-03-25T16:23:40.569Z,1616689420.569 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-03-25T16:23:40.653Z,1616689420.653 [Reporter](INFO): platform_mass_position 0.004334 m
2021-03-25T16:23:40.968Z,1616689420.968 [Default:CheckIn] Running Loop=1
2021-03-25T16:23:40.968Z,1616689420.968 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-03-25T16:23:40.968Z,1616689420.968 [Default:CheckIn:Read_GPS] Running Loop=1
2021-03-25T16:23:41.026Z,1616689421.026 [Reporter](INFO): platform_mass_position 0.004617 m
2021-03-25T16:23:41.353Z,1616689421.353 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-03-25T16:23:41.405Z,1616689421.405 [Reporter](INFO): platform_mass_position 0.004914 m
2021-03-25T16:23:41.694Z,1616689421.694 [DataOverHttps](INFO): Sending 1186 bytes from file Logs/20210325T161942/Express0001.lzma
2021-03-25T16:23:41.801Z,1616689421.801 [Reporter](INFO): platform_mass_position 0.005234 m
2021-03-25T16:23:42.203Z,1616689422.203 [Reporter](INFO): platform_mass_position 0.005543 m
2021-03-25T16:23:42.625Z,1616689422.625 [Reporter](INFO): platform_mass_position 0.005858 m
2021-03-25T16:23:42.693Z,1616689422.693 [DataOverHttps](INFO): Moved sent file to Logs/20210325T161942/Express0001.lzma.bak
2021-03-25T16:23:42.694Z,1616689422.694 [DataOverHttps](INFO): SBD MOMSN=15446388
2021-03-25T16:23:43.013Z,1616689423.013 [Reporter](INFO): platform_mass_position 0.006185 m
2021-03-25T16:23:43.430Z,1616689423.430 [Reporter](INFO): platform_mass_position 0.006510 m
2021-03-25T16:23:43.825Z,1616689423.825 [Reporter](INFO): platform_mass_position 0.006817 m
2021-03-25T16:23:44.235Z,1616689424.235 [Reporter](INFO): platform_mass_position 0.007130 m
2021-03-25T16:23:44.633Z,1616689424.633 [Reporter](INFO): platform_mass_position 0.007453 m
2021-03-25T16:23:45.029Z,1616689425.029 [Reporter](INFO): platform_mass_position 0.007759 m
2021-03-25T16:23:45.433Z,1616689425.433 [Reporter](INFO): platform_mass_position 0.008080 m
2021-03-25T16:23:45.829Z,1616689425.829 [Reporter](INFO): platform_mass_position 0.008392 m
2021-03-25T16:23:46.245Z,1616689426.245 [Reporter](INFO): platform_mass_position 0.008717 m
2021-03-25T16:23:46.657Z,1616689426.657 [Reporter](INFO): platform_mass_position 0.009040 m
2021-03-25T16:23:47.057Z,1616689427.057 [Reporter](INFO): platform_mass_position 0.009350 m
2021-03-25T16:23:47.467Z,1616689427.467 [Reporter](INFO): platform_mass_position 0.009663 m
2021-03-25T16:23:47.858Z,1616689427.858 [Reporter](INFO): platform_mass_position 0.009982 m
2021-03-25T16:23:52.261Z,1616689432.261 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:23:58.740Z,1616689438.740 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-03-25T16:23:58.740Z,1616689438.740 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-03-25T16:23:58.740Z,1616689438.740 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-03-25T16:23:58.784Z,1616689438.784 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-03-25T16:23:59.137Z,1616689439.137 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-03-25T16:23:59.137Z,1616689439.137 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-03-25T16:24:07.633Z,1616689447.633 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:24:23.362Z,1616689463.362 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:24:28.611Z,1616689468.611 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162318.00,A,3648.16970,N,12147.28044,W,0.194,339.77,250321,,,A*7A
2021-03-25T16:24:28.614Z,1616689468.614 [NAL9602](INFO): GPS fix at 20210325T162318: (36.802828, -121.788007)
2021-03-25T16:24:28.625Z,1616689468.625 [Default:CheckIn:Read_GPS] Stopped
2021-03-25T16:24:28.625Z,1616689468.625 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-03-25T16:24:29.038Z,1616689469.038 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-03-25T16:24:39.114Z,1616689479.114 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:24:40.455Z,1616689480.455 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20210325T161942/Courier0004.lzma
2021-03-25T16:24:41.457Z,1616689481.457 [DataOverHttps](INFO): Moved sent file to Logs/20210325T161942/Courier0004.lzma.bak
2021-03-25T16:24:41.457Z,1616689481.457 [DataOverHttps](INFO): SBD MOMSN=15446422
2021-03-25T16:25:00.929Z,1616689500.929 [NAL9602](INFO): Not Powering down - fast GPS
2021-03-25T16:25:04.616Z,1616689504.616 [DataOverHttps](INFO): Sending 388 bytes from file Logs/20210325T161942/Express0005.lzma
2021-03-25T16:25:05.618Z,1616689505.618 [DataOverHttps](INFO): Moved sent file to Logs/20210325T161942/Express0005.lzma.bak
2021-03-25T16:25:05.618Z,1616689505.618 [DataOverHttps](INFO): SBD MOMSN=15446425
2021-03-25T16:25:11.857Z,1616689511.857 [Default:CheckIn:Read_Iridium] Stopped
2021-03-25T16:25:11.857Z,1616689511.857 [Default:CheckIn:C.Wait] Running Loop=1
2021-03-25T16:25:11.857Z,1616689511.857 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-03-25T16:25:59.922Z,1616689559.922 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-03-25T16:25:59.923Z,1616689559.923 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-03-25T16:25:59.923Z,1616689559.923 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-03-25T16:25:59.935Z,1616689559.935 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-03-25T16:26:00.341Z,1616689560.341 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-03-25T16:26:00.341Z,1616689560.341 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-03-25T16:28:00.816Z,1616689680.816 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-03-25T16:28:00.816Z,1616689680.816 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-03-25T16:28:00.816Z,1616689680.816 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-03-25T16:28:00.828Z,1616689680.828 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-03-25T16:28:01.210Z,1616689681.210 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-03-25T16:28:01.210Z,1616689681.210 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-03-25T16:28:50.912Z,1616689730.912 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-03-25T16:28:54.928Z,1616689734.928 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.259109
2021-03-25T16:29:26.052Z,1616689766.052 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-03-25T16:29:38.935Z,1616689778.935 [BPC1](ERROR): BPC1A: No match for serial number FFC1 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2021-03-25T16:30:01.192Z,1616689801.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-03-25T16:30:01.964Z,1616689801.964 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-03-25T16:30:01.964Z,1616689801.964 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-03-25T16:30:01.964Z,1616689801.964 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-03-25T16:30:01.976Z,1616689801.976 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-03-25T16:30:02.388Z,1616689802.388 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-03-25T16:30:02.388Z,1616689802.388 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-03-25T16:30:12.501Z,1616689812.501 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-03-25T16:30:12.501Z,1616689812.501 [Default:CheckIn:C.Wait] Stopped
2021-03-25T16:30:12.501Z,1616689812.501 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-03-25T16:30:12.501Z,1616689812.501 [Default:CheckIn:D] Running Loop=1
2021-03-25T16:30:12.926Z,1616689812.926 [Default:CheckIn:D] Stopped
2021-03-25T16:30:12.926Z,1616689812.926 [Default:CheckIn:E] Running Loop=1
2021-03-25T16:30:13.298Z,1616689813.298 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.761646 min 
2021-03-25T16:30:13.298Z,1616689813.298 [Default:CheckIn:E] Stopped
2021-03-25T16:30:13.298Z,1616689813.298 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-03-25T16:30:13.298Z,1616689813.298 [Default:CheckIn] Stopped
2021-03-25T16:30:13.298Z,1616689813.298 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-03-25T16:30:13.299Z,1616689813.299 [Default:CheckIn](INFO): Running loop #2
2021-03-25T16:30:13.299Z,1616689813.299 [Default:CheckIn] Running Loop=2
2021-03-25T16:30:13.299Z,1616689813.299 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-03-25T16:30:13.299Z,1616689813.299 [Default:CheckIn:Read_GPS] Running Loop=1
2021-03-25T16:30:36.344Z,1616689836.344 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-03-25T16:31:11.488Z,1616689871.488 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-03-25T16:31:46.628Z,1616689906.628 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-03-25T16:32:03.180Z,1616689923.180 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-03-25T16:32:03.180Z,1616689923.180 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-03-25T16:32:03.180Z,1616689923.180 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-03-25T16:32:03.191Z,1616689923.191 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-03-25T16:32:03.598Z,1616689923.598 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-03-25T16:32:03.598Z,1616689923.598 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-03-25T16:32:14.874Z,1616689934.874 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:32:21.780Z,1616689941.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-03-25T16:32:31.045Z,1616689951.045 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:32:47.198Z,1616689967.198 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:32:56.928Z,1616689976.928 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-03-25T16:33:02.957Z,1616689982.957 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:33:08.630Z,1616689988.630 [NAL9602](INFO): SBD MO Status=0, MOMSN=4075, MT Status=0, MTMSN=0
2021-03-25T16:33:08.630Z,1616689988.630 [NAL9602](INFO): No messages in MT queue
2021-03-25T16:33:09.842Z,1616689989.842 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163200.00,A,3648.16806,N,12147.28500,W,0.894,0.00,250321,,,A*76
2021-03-25T16:33:09.844Z,1616689989.844 [NAL9602](INFO): GPS fix at 20210325T163200: (36.802801, -121.788083)
2021-03-25T16:33:09.854Z,1616689989.854 [Default:CheckIn:Read_GPS] Stopped
2021-03-25T16:33:09.854Z,1616689989.854 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-03-25T16:33:11.396Z,1616689991.396 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003084
2021-03-25T16:33:17.531Z,1616689997.531 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:33:22.808Z,1616690002.808 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210325T161942/Courier0007.lzma
2021-03-25T16:33:26.825Z,1616690006.825 [DataOverHttps](INFO): Moved sent file to Logs/20210325T161942/Courier0007.lzma.bak
2021-03-25T16:33:26.825Z,1616690006.825 [DataOverHttps](INFO): SBD MOMSN=15446435
2021-03-25T16:33:33.692Z,1616690013.692 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:33:42.160Z,1616690022.160 [NAL9602](INFO): Not Powering down - fast GPS
2021-03-25T16:33:49.034Z,1616690029.034 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:34:04.099Z,1616690044.099 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-03-25T16:34:04.099Z,1616690044.099 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-03-25T16:34:04.099Z,1616690044.099 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-03-25T16:34:04.160Z,1616690044.160 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-03-25T16:34:04.564Z,1616690044.564 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-03-25T16:34:04.564Z,1616690044.564 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-03-25T16:34:04.897Z,1616690044.897 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:34:20.251Z,1616690060.251 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2021-03-25T16:34:37.400Z,1616690077.400 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20210325T161942/Express0008.lzma
2021-03-25T16:34:38.401Z,1616690078.401 [DataOverHttps](INFO): Moved sent file to Logs/20210325T161942/Express0008.lzma.bak
2021-03-25T16:34:38.402Z,1616690078.402 [DataOverHttps](INFO): SBD MOMSN=15446438
2021-03-25T16:34:44.528Z,1616690084.528 [Default:CheckIn:Read_Iridium] Stopped
2021-03-25T16:34:44.529Z,1616690084.529 [Default:CheckIn:C.Wait] Running Loop=1
2021-03-25T16:34:44.529Z,1616690084.529 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-03-25T16:36:05.306Z,1616690165.306 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-03-25T16:36:05.307Z,1616690165.307 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-03-25T16:36:05.307Z,1616690165.307 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-03-25T16:36:05.318Z,1616690165.318 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-03-25T16:36:05.729Z,1616690165.729 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-03-25T16:36:05.729Z,1616690165.729 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-03-25T16:38:06.152Z,1616690286.152 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-03-25T16:38:06.152Z,1616690286.152 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-03-25T16:38:06.152Z,1616690286.152 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-03-25T16:38:06.164Z,1616690286.164 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-03-25T16:38:06.625Z,1616690286.625 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-03-25T16:38:06.625Z,1616690286.625 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-03-25T16:39:45.185Z,1616690385.185 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-03-25T16:39:45.185Z,1616690385.185 [Default:CheckIn:C.Wait] Stopped
2021-03-25T16:39:45.185Z,1616690385.185 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-03-25T16:39:45.185Z,1616690385.185 [Default:CheckIn:D] Running Loop=1
2021-03-25T16:39:45.593Z,1616690385.593 [Default:CheckIn:D] Stopped
2021-03-25T16:39:45.593Z,1616690385.593 [Default:CheckIn:E] Running Loop=1
2021-03-25T16:39:46.014Z,1616690386.014 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.306101 min 
2021-03-25T16:39:46.014Z,1616690386.014 [Default:CheckIn:E] Stopped
2021-03-25T16:39:46.014Z,1616690386.014 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-03-25T16:39:46.014Z,1616690386.014 [Default:CheckIn] Stopped
2021-03-25T16:39:46.014Z,1616690386.014 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-03-25T16:39:46.015Z,1616690386.015 [Default:CheckIn](INFO): Running loop #3
2021-03-25T16:39:46.015Z,1616690386.015 [Default:CheckIn] Running Loop=3
2021-03-25T16:39:46.015Z,1616690386.015 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-03-25T16:39:46.015Z,1616690386.015 [Default:CheckIn:Read_GPS] Running Loop=1
2021-03-25T16:39:48.010Z,1616690388.010 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163838.00,A,3648.16282,N,12147.28365,W,0.369,165.01,250321,,,A*7E
2021-03-25T16:39:48.012Z,1616690388.012 [NAL9602](INFO): GPS fix at 20210325T163838: (36.802714, -121.788061)
2021-03-25T16:39:48.071Z,1616690388.071 [Default:CheckIn:Read_GPS] Stopped
2021-03-25T16:39:48.071Z,1616690388.071 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-03-25T16:39:54.054Z,1616690394.054 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210325T161942/Courier0010.lzma
2021-03-25T16:39:55.061Z,1616690395.061 [DataOverHttps](INFO): Moved sent file to Logs/20210325T161942/Courier0010.lzma.bak
2021-03-25T16:39:55.062Z,1616690395.062 [DataOverHttps](INFO): SBD MOMSN=15446446
2021-03-25T16:40:06.967Z,1616690406.967 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read t