2020-08-26T17:55:40.840Z,1598464540.840 [Supervisor](DEBUG): Initializing supervisor.
2020-08-26T17:55:40.843Z,1598464540.843 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-08-26T17:55:40.844Z,1598464540.844 [SyncHandler](INFO): Protected caller Thread ID is 7746
2020-08-26T17:55:40.844Z,1598464540.844 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-08-26T17:55:40.845Z,1598464540.845 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-08-26T17:55:40.846Z,1598464540.846 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7747
2020-08-26T17:55:40.849Z,1598464540.849 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-08-26T17:55:40.862Z,1598464540.862 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-08-26T17:55:40.863Z,1598464540.863 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-08-26T17:55:40.863Z,1598464540.863 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7748
2020-08-26T17:55:40.864Z,1598464540.864 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-08-26T17:55:40.865Z,1598464540.865 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-08-26T17:55:40.865Z,1598464540.865 [logger ThreadHandler](INFO): Protected caller Thread ID is 7749
2020-08-26T17:55:40.867Z,1598464540.867 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-08-26T17:55:40.867Z,1598464540.867 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-08-26T17:55:40.869Z,1598464540.869 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-08-26T17:55:41.285Z,1598464541.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-08-26T17:55:41.285Z,1598464541.285 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-08-26T17:55:41.423Z,1598464541.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-08-26T17:55:41.423Z,1598464541.423 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-08-26T17:55:41.501Z,1598464541.501 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-08-26T17:55:41.688Z,1598464541.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-08-26T17:55:41.689Z,1598464541.689 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-08-26T17:55:41.784Z,1598464541.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-08-26T17:55:41.785Z,1598464541.785 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-08-26T17:55:41.879Z,1598464541.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-08-26T17:55:41.879Z,1598464541.879 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-08-26T17:55:42.267Z,1598464542.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-08-26T17:55:42.267Z,1598464542.267 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-08-26T17:55:42.367Z,1598464542.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-08-26T17:55:42.368Z,1598464542.368 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-08-26T17:55:42.809Z,1598464542.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-08-26T17:55:42.809Z,1598464542.809 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-08-26T17:55:42.995Z,1598464542.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-08-26T17:55:42.996Z,1598464542.996 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-08-26T17:55:43.301Z,1598464543.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-08-26T17:55:43.302Z,1598464543.302 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-08-26T17:55:43.511Z,1598464543.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-08-26T17:55:43.512Z,1598464543.512 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-08-26T17:55:43.650Z,1598464543.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-08-26T17:55:43.650Z,1598464543.650 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-08-26T17:55:44.289Z,1598464544.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-08-26T17:55:44.289Z,1598464544.289 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-08-26T17:55:44.610Z,1598464544.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-08-26T17:55:44.610Z,1598464544.610 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-08-26T17:55:44.981Z,1598464544.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-08-26T17:55:44.983Z,1598464544.983 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2020-08-26T17:55:44.984Z,1598464544.984 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2020-08-26T17:55:45.398Z,1598464545.398 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2020-08-26T17:55:45.545Z,1598464545.545 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2020-08-26T17:55:45.830Z,1598464545.830 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2020-08-26T17:55:45.913Z,1598464545.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2020-08-26T17:55:46.139Z,1598464546.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-08-26T17:55:46.140Z,1598464546.140 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2020-08-26T17:55:46.242Z,1598464546.242 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2020-08-26T17:55:46.347Z,1598464546.347 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2020-08-26T17:55:46.439Z,1598464546.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2020-08-26T17:55:46.579Z,1598464546.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2020-08-26T17:55:46.674Z,1598464546.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2020-08-26T17:55:46.770Z,1598464546.770 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2020-08-26T17:55:46.771Z,1598464546.771 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-08-26T17:55:46.783Z,1598464546.783 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-08-26T17:55:46.801Z,1598464546.801 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-08-26T17:55:46.802Z,1598464546.802 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-08-26T17:55:46.878Z,1598464546.878 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-08-26T17:55:46.879Z,1598464546.879 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-08-26T17:55:46.926Z,1598464546.926 [VerticalControl](DEBUG): Construct VerticalControl.
2020-08-26T17:55:47.036Z,1598464547.036 [VerticalControl] Loaded
2020-08-26T17:55:47.036Z,1598464547.036 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-08-26T17:55:47.037Z,1598464547.037 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-08-26T17:55:47.115Z,1598464547.115 [HorizontalControl] Loaded
2020-08-26T17:55:47.115Z,1598464547.115 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-08-26T17:55:47.115Z,1598464547.115 [SpeedControl](DEBUG): Construct SpeedControl.
2020-08-26T17:55:47.121Z,1598464547.121 [SpeedControl] Loaded
2020-08-26T17:55:47.121Z,1598464547.121 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-08-26T17:55:47.122Z,1598464547.122 [LoopControl](DEBUG): Construct LoopControl.
2020-08-26T17:55:47.122Z,1598464547.122 [LoopControl] Loaded
2020-08-26T17:55:47.122Z,1598464547.122 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-08-26T17:55:47.123Z,1598464547.123 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-08-26T17:55:47.123Z,1598464547.123 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-08-26T17:55:47.266Z,1598464547.266 [BuoyancyServo] Loaded
2020-08-26T17:55:47.267Z,1598464547.267 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-08-26T17:55:47.294Z,1598464547.294 [ElevatorServo] Loaded
2020-08-26T17:55:47.295Z,1598464547.295 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-08-26T17:55:47.322Z,1598464547.322 [MassServo] Loaded
2020-08-26T17:55:47.322Z,1598464547.322 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-08-26T17:55:47.349Z,1598464547.349 [RudderServo] Loaded
2020-08-26T17:55:47.349Z,1598464547.349 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-08-26T17:55:47.375Z,1598464547.375 [ThrusterServo] Loaded
2020-08-26T17:55:47.375Z,1598464547.375 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-08-26T17:55:47.376Z,1598464547.376 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-08-26T17:55:47.376Z,1598464547.376 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-08-26T17:55:47.416Z,1598464547.416 [DepthRateCalculator] Loaded
2020-08-26T17:55:47.416Z,1598464547.416 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-08-26T17:55:47.421Z,1598464547.421 [PitchRateCalculator] Loaded
2020-08-26T17:55:47.422Z,1598464547.422 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-08-26T17:55:47.436Z,1598464547.436 [SpeedCalculator] Loaded
2020-08-26T17:55:47.436Z,1598464547.436 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-08-26T17:55:47.457Z,1598464547.457 [TempGradientCalculator] Loaded
2020-08-26T17:55:47.457Z,1598464547.457 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-08-26T17:55:47.462Z,1598464547.462 [YawRateCalculator] Loaded
2020-08-26T17:55:47.463Z,1598464547.463 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-08-26T17:55:47.491Z,1598464547.491 [ElevatorOffsetCalculator] Loaded
2020-08-26T17:55:47.492Z,1598464547.492 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-08-26T17:55:47.492Z,1598464547.492 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-08-26T17:55:47.492Z,1598464547.492 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-08-26T17:55:47.608Z,1598464547.608 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-08-26T17:55:47.608Z,1598464547.608 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-08-26T17:55:47.628Z,1598464547.628 [NavChart] Loaded
2020-08-26T17:55:47.629Z,1598464547.629 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-08-26T17:55:47.632Z,1598464547.632 [UniversalFixResidualReporter] Loaded
2020-08-26T17:55:47.633Z,1598464547.633 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-08-26T17:55:47.633Z,1598464547.633 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-08-26T17:55:47.634Z,1598464547.634 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-08-26T17:55:48.005Z,1598464548.005 [DataOverHttps] Loaded
2020-08-26T17:55:48.005Z,1598464548.005 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-08-26T17:55:48.006Z,1598464548.006 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4084F4E0
2020-08-26T17:55:48.007Z,1598464548.007 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7831
2020-08-26T17:55:48.037Z,1598464548.037 [Depth_Keller] Loaded
2020-08-26T17:55:48.037Z,1598464548.037 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-08-26T17:55:48.149Z,1598464548.149 [NAL9602] Loaded
2020-08-26T17:55:48.150Z,1598464548.150 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-08-26T17:55:48.198Z,1598464548.198 [Onboard] Loaded
2020-08-26T17:55:48.198Z,1598464548.198 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-08-26T17:55:48.199Z,1598464548.199 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4087F4E0
2020-08-26T17:55:48.199Z,1598464548.199 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7832
2020-08-26T17:55:48.219Z,1598464548.219 [Radio_Surface] Loaded
2020-08-26T17:55:48.219Z,1598464548.219 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-08-26T17:55:48.220Z,1598464548.220 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AF4E0
2020-08-26T17:55:48.220Z,1598464548.220 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7833
2020-08-26T17:55:48.372Z,1598464548.372 [DAT] Loaded
2020-08-26T17:55:48.372Z,1598464548.372 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-08-26T17:55:49.876Z,1598464549.876 [BPC1] Loaded
2020-08-26T17:55:49.876Z,1598464549.876 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-08-26T17:55:49.877Z,1598464549.877 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-08-26T17:55:49.877Z,1598464549.877 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-08-26T17:55:50.233Z,1598464550.233 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-08-26T17:55:50.233Z,1598464550.233 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-08-26T17:55:50.550Z,1598464550.550 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-08-26T17:55:50.551Z,1598464550.551 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-08-26T17:55:50.577Z,1598464550.577 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-08-26T17:55:50.577Z,1598464550.577 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-08-26T17:55:50.781Z,1598464550.781 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-08-26T17:55:50.786Z,1598464550.786 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-08-26T17:55:50.788Z,1598464550.788 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-08-26T17:55:50.793Z,1598464550.793 [CTD_Seabird](INFO): created writer for : depth
2020-08-26T17:55:50.793Z,1598464550.793 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-08-26T17:55:50.798Z,1598464550.798 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-08-26T17:55:50.799Z,1598464550.799 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-08-26T17:55:50.804Z,1598464550.804 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-08-26T17:55:50.805Z,1598464550.805 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-08-26T17:55:50.809Z,1598464550.809 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-08-26T17:55:50.810Z,1598464550.810 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-08-26T17:55:50.815Z,1598464550.815 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-08-26T17:55:50.816Z,1598464550.816 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-08-26T17:55:50.821Z,1598464550.821 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-08-26T17:55:50.848Z,1598464550.848 [CTD_Seabird] Loaded
2020-08-26T17:55:50.848Z,1598464550.848 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-08-26T17:55:50.849Z,1598464550.849 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A554E0
2020-08-26T17:55:50.849Z,1598464550.849 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7834
2020-08-26T17:55:50.899Z,1598464550.899 [ESPComponent] Loaded
2020-08-26T17:55:50.899Z,1598464550.899 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-08-26T17:55:50.929Z,1598464550.929 [PAR_Licor] Loaded
2020-08-26T17:55:50.930Z,1598464550.930 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-08-26T17:55:50.951Z,1598464550.951 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-08-26T17:55:50.952Z,1598464550.952 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-08-26T17:55:50.956Z,1598464550.956 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-08-26T17:55:50.956Z,1598464550.956 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-08-26T17:55:50.960Z,1598464550.960 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-08-26T17:55:50.960Z,1598464550.960 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-08-26T17:55:50.964Z,1598464550.964 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-08-26T17:55:50.965Z,1598464550.965 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-08-26T17:55:50.969Z,1598464550.969 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-08-26T17:55:50.969Z,1598464550.969 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-08-26T17:55:50.973Z,1598464550.973 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-08-26T17:55:50.973Z,1598464550.973 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-08-26T17:55:50.977Z,1598464550.977 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-08-26T17:55:50.978Z,1598464550.978 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-08-26T17:55:50.982Z,1598464550.982 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T17:55:50.986Z,1598464550.986 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T17:55:50.987Z,1598464550.987 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T17:55:50.987Z,1598464550.987 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T17:55:50.991Z,1598464550.991 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T17:55:50.991Z,1598464550.991 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T17:55:50.995Z,1598464550.995 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T17:55:50.995Z,1598464550.995 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T17:55:50.000Z,1598464551.000 [WetLabsBB2FL] Loaded
2020-08-26T17:55:50.000Z,1598464551.000 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-08-26T17:55:51.001Z,1598464551.001 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0
2020-08-26T17:55:51.001Z,1598464551.001 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7835
2020-08-26T17:55:51.002Z,1598464551.002 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-08-26T17:55:51.003Z,1598464551.003 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-08-26T17:55:51.288Z,1598464551.288 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-08-26T17:55:51.288Z,1598464551.288 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-08-26T17:55:51.425Z,1598464551.425 [SBIT](DEBUG): Construct Startup Built In Test.
2020-08-26T17:55:51.436Z,1598464551.436 [SBIT] Loaded
2020-08-26T17:55:51.436Z,1598464551.436 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-08-26T17:55:51.437Z,1598464551.437 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-08-26T17:55:51.450Z,1598464551.450 [IBIT] Loaded
2020-08-26T17:55:51.450Z,1598464551.450 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-08-26T17:55:51.453Z,1598464551.453 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-08-26T17:55:51.591Z,1598464551.591 [CBIT] Loaded
2020-08-26T17:55:51.591Z,1598464551.591 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-08-26T17:55:51.591Z,1598464551.591 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-08-26T17:55:51.595Z,1598464551.595 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-08-26T17:55:51.596Z,1598464551.596 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-08-26T17:55:51.602Z,1598464551.602 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-08-26T17:55:51.604Z,1598464551.604 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0
2020-08-26T17:55:51.604Z,1598464551.604 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7836
2020-08-26T17:55:51.608Z,1598464551.608 [Supervisor](INFO): Main Thread ID is 6839
2020-08-26T17:55:51.609Z,1598464551.609 [Supervisor](DEBUG): Running supervisor.
2020-08-26T17:55:51.609Z,1598464551.609 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7837
2020-08-26T17:55:51.612Z,1598464551.612 [controlThread ThreadHandler](INFO): Handler Thread ID is 7838
2020-08-26T17:55:51.612Z,1598464551.612 [controlThread](DEBUG): Initializing ControlThread
2020-08-26T17:55:51.613Z,1598464551.613 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-08-26T17:55:51.615Z,1598464551.615 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-08-26T17:55:51.615Z,1598464551.615 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-08-26T17:55:51.616Z,1598464551.616 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-08-26T17:55:51.617Z,1598464551.617 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-08-26T17:55:51.618Z,1598464551.618 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-08-26T17:55:51.618Z,1598464551.618 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-08-26T17:55:51.618Z,1598464551.618 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-08-26T17:55:51.619Z,1598464551.619 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-08-26T17:55:51.619Z,1598464551.619 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-08-26T17:55:51.620Z,1598464551.620 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-08-26T17:55:51.621Z,1598464551.621 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-08-26T17:55:51.629Z,1598464551.629 [SBIT](INFO): Initialize SBIT Component.
2020-08-26T17:55:51.629Z,1598464551.629 [SBIT](IMPORTANT): git: 2020-08-18a-30-g8cc0a1c87
2020-08-26T17:55:51.630Z,1598464551.630 [SBIT](INFO): git hash: 8cc0a1c87b994ada9a621eded1d43a5b5e56617e
2020-08-26T17:55:51.630Z,1598464551.630 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-08-26T17:55:51.631Z,1598464551.631 [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
2020-08-26T17:55:51.632Z,1598464551.632 [SBIT](INFO): Beginning SBIT in 34.000000 seconds.
2020-08-26T17:55:51.633Z,1598464551.633 [IBIT](INFO): Initialize IBIT Component.
2020-08-26T17:55:51.634Z,1598464551.634 [CBIT](DEBUG): Initialize CBIT Component.
2020-08-26T17:55:51.634Z,1598464551.634 [logger ThreadHandler](INFO): Handler Thread ID is 7839
2020-08-26T17:55:51.645Z,1598464551.645 [CBIT](DEBUG): Initialized mux pins.
2020-08-26T17:55:51.645Z,1598464551.645 [CBIT](DEBUG): Initializing the watchdog timer.
2020-08-26T17:55:51.653Z,1598464551.653 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7840
2020-08-26T17:55:51.654Z,1598464551.654 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-08-26T17:55:51.665Z,1598464551.665 [Onboard ThreadHandler](INFO): Handler Thread ID is 7841
2020-08-26T17:55:51.669Z,1598464551.669 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-08-26T17:55:51.669Z,1598464551.669 [CBIT](DEBUG): Initializing heartbeat.
2020-08-26T17:55:51.683Z,1598464551.683 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7842
2020-08-26T17:55:51.697Z,1598464551.697 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7843
2020-08-26T17:55:51.698Z,1598464551.698 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-08-26T17:55:51.702Z,1598464551.702 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7845
2020-08-26T17:55:51.703Z,1598464551.703 [WetLabsBB2FL](INFO): Powering down
2020-08-26T17:55:51.725Z,1598464551.725 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7846
2020-08-26T17:55:51.728Z,1598464551.728 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-08-26T17:55:51.728Z,1598464551.728 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-08-26T17:55:51.729Z,1598464551.729 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-08-26T17:55:51.729Z,1598464551.729 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-08-26T17:55:51.729Z,1598464551.729 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-08-26T17:55:51.729Z,1598464551.729 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-08-26T17:55:51.729Z,1598464551.729 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-08-26T17:55:51.730Z,1598464551.730 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-08-26T17:55:51.730Z,1598464551.730 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-08-26T17:55:51.730Z,1598464551.730 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-08-26T17:55:51.730Z,1598464551.730 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-08-26T17:55:51.730Z,1598464551.730 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-08-26T17:55:51.730Z,1598464551.730 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-08-26T17:55:51.731Z,1598464551.731 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-08-26T17:55:51.731Z,1598464551.731 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-08-26T17:55:51.731Z,1598464551.731 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-08-26T17:55:51.741Z,1598464551.741 [CBIT](DEBUG): Deactivating GF circuits.
2020-08-26T17:55:51.741Z,1598464551.741 [CBIT](DEBUG): Deactivating emergency mode.
2020-08-26T17:55:51.777Z,1598464551.777 [CBIT](DEBUG): Backplane powered.
2020-08-26T17:55:51.778Z,1598464551.778 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-08-26T17:55:51.787Z,1598464551.787 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-08-26T17:55:51.798Z,1598464551.798 [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-08-26T17:55:51.798Z,1598464551.798 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-08-26T17:55:51.866Z,1598464551.866 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-08-26T17:55:51.867Z,1598464551.867 [Default:A.Wait](DEBUG): Construct Wait.
2020-08-26T17:55:51.885Z,1598464551.885 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-08-26T17:55:51.912Z,1598464551.912 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-08-26T17:55:51.914Z,1598464551.914 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-08-26T17:55:51.943Z,1598464551.943 [Default:E.Execute](DEBUG): Construct Execute.
2020-08-26T17:55:51.946Z,1598464551.946 [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-08-26T17:55:51.956Z,1598464551.956 [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,
2020-08-26T17:55:51.984Z,1598464551.984 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2020-08-26T17:55:51.987Z,1598464551.987 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-08-26T17:55:51.987Z,1598464551.987 [DAT](INFO): Powering up
2020-08-26T17:55:51.987Z,1598464551.987 [DAT](DEBUG): Initializing DAT.
2020-08-26T17:55:52.009Z,1598464552.009 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-08-26T17:55:52.085Z,1598464552.085 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-08-26T17:55:52.089Z,1598464552.089 [Radio_Surface](INFO): Powering up
2020-08-26T17:55:52.129Z,1598464552.129 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-08-26T17:55:52.141Z,1598464552.141 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-08-26T17:55:52.142Z,1598464552.142 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-08-26T17:55:52.149Z,1598464552.149 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-08-26T17:55:52.150Z,1598464552.150 [MassServo](DEBUG): Initializing EZServoServo.
2020-08-26T17:55:52.157Z,1598464552.157 [MassServo](DEBUG): Initializing MassServo.
2020-08-26T17:55:52.158Z,1598464552.158 [RudderServo](DEBUG): Initializing EZServoServo.
2020-08-26T17:55:52.165Z,1598464552.165 [RudderServo](DEBUG): Initializing RudderServo.
2020-08-26T17:55:52.166Z,1598464552.166 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-08-26T17:55:52.177Z,1598464552.177 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-08-26T17:55:53.097Z,1598464553.097 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-08-26T17:55:53.097Z,1598464553.097 [RudderServo](FAULT): Rudder failed to initialize
2020-08-26T17:55:53.097Z,1598464553.097 [RudderServo] Communications Fault, FailCount= 1
2020-08-26T17:55:53.097Z,1598464553.097 [RudderServo](ERROR): Communications Fault
2020-08-26T17:55:53.208Z,1598464553.208 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-08-26T17:55:53.418Z,1598464553.418 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-08-26T17:55:53.418Z,1598464553.418 [RudderServo](INFO): Powering down
2020-08-26T17:55:54.040Z,1598464554.040 [RudderServo](DEBUG): Initializing EZServoServo.
2020-08-26T17:55:54.158Z,1598464554.158 [RudderServo](DEBUG): Initializing RudderServo.
2020-08-26T17:55:54.162Z,1598464554.162 [CBIT](INFO): Clearing failed state for component RudderServo
2020-08-26T17:55:54.162Z,1598464554.162 [RudderServo] No Fault, FailCount= 1
2020-08-26T17:55:56.897Z,1598464556.897 [CBIT](IMPORTANT): averaging humidity:17 %. Average:0.000000
2020-08-26T17:55:57.377Z,1598464557.377 [CBIT](IMPORTANT): averaging humidity:17 %. Average:17.000000
2020-08-26T17:55:57.964Z,1598464557.964 [CBIT](IMPORTANT): averaging humidity:17 %. Average:34.000000
2020-08-26T17:55:58.140Z,1598464558.140 [CBIT](IMPORTANT): averaging humidity:17 %. Average:51.000000
2020-08-26T17:55:58.528Z,1598464558.528 [CBIT](IMPORTANT): averaging humidity:17 %. Average:68.000000
2020-08-26T17:55:58.917Z,1598464558.917 [CBIT](IMPORTANT): averaging humidity:17 %. Average:85.000000
2020-08-26T17:55:59.337Z,1598464559.337 [CBIT](IMPORTANT): averaging humidity:17 %. Average:102.000000
2020-08-26T17:55:59.749Z,1598464559.749 [CBIT](IMPORTANT): averaging humidity:17 %. Average:119.000000
2020-08-26T17:56:00.189Z,1598464560.189 [CBIT](IMPORTANT): averaging humidity:17 %. Average:136.000000
2020-08-26T17:56:00.549Z,1598464560.549 [CBIT](IMPORTANT): averaging humidity:17 %. Average:153.000000
2020-08-26T17:56:00.937Z,1598464560.937 [CBIT](IMPORTANT): averaging humidity:17 %. Average:170.000000
2020-08-26T17:56:01.345Z,1598464561.345 [CBIT](IMPORTANT): averaging humidity:17 %. Average:187.000000
2020-08-26T17:56:01.749Z,1598464561.749 [CBIT](IMPORTANT): averaging humidity:17 %. Average:204.000000
2020-08-26T17:56:02.153Z,1598464562.153 [CBIT](IMPORTANT): averaging humidity:17 %. Average:221.000000
2020-08-26T17:56:02.561Z,1598464562.561 [CBIT](IMPORTANT): averaging humidity:17 %. Average:238.000000
2020-08-26T17:56:02.961Z,1598464562.961 [CBIT](IMPORTANT): averaging humidity:17 %. Average:255.000000
2020-08-26T17:56:03.365Z,1598464563.365 [CBIT](IMPORTANT): averaging humidity:17 %. Average:272.000000
2020-08-26T17:56:03.781Z,1598464563.781 [CBIT](IMPORTANT): averaging humidity:17 %. Average:289.000000
2020-08-26T17:56:04.181Z,1598464564.181 [CBIT](IMPORTANT): averaging humidity:17 %. Average:306.000000
2020-08-26T17:56:04.532Z,1598464564.532 [DAT](INFO): commRate: 800
2020-08-26T17:56:04.532Z,1598464564.532 [DAT](INFO): commRate: 800
2020-08-26T17:56:04.601Z,1598464564.601 [CBIT](IMPORTANT): averaging humidity:17 %. Average:323.000000
2020-08-26T17:56:04.934Z,1598464564.934 [DAT](INFO): entering command mode
2020-08-26T17:56:04.994Z,1598464564.994 [CBIT](IMPORTANT): averaging humidity:17 %. Average:340.000000
2020-08-26T17:56:05.338Z,1598464565.338 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:05.393Z,1598464565.393 [CBIT](IMPORTANT): averaging humidity:17 %. Average:357.000000
2020-08-26T17:56:05.742Z,1598464565.742 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:05.881Z,1598464565.881 [CBIT](IMPORTANT): averaging humidity:17 %. Average:374.000000
2020-08-26T17:56:06.146Z,1598464566.146 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:06.164Z,1598464566.164 [CBIT](IMPORTANT): averaging humidity:17 %. Average:391.000000
2020-08-26T17:56:06.550Z,1598464566.550 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:06.581Z,1598464566.581 [CBIT](IMPORTANT): averaging humidity:17 %. Average:408.000000
2020-08-26T17:56:06.954Z,1598464566.954 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:07.013Z,1598464567.013 [CBIT](IMPORTANT): averaging humidity:17 %. Average:425.000000
2020-08-26T17:56:07.358Z,1598464567.358 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:07.377Z,1598464567.377 [CBIT](IMPORTANT): averaging humidity:17 %. Average:442.000000
2020-08-26T17:56:07.763Z,1598464567.763 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:07.784Z,1598464567.784 [CBIT](IMPORTANT): averaging humidity:17 %. Average:459.000000
2020-08-26T17:56:08.193Z,1598464568.193 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:08.241Z,1598464568.241 [CBIT](IMPORTANT): averaging humidity:17 %. Average:476.000000
2020-08-26T17:56:08.566Z,1598464568.566 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:08.620Z,1598464568.620 [CBIT](IMPORTANT): averaging humidity:17 %. Average:493.000000
2020-08-26T17:56:08.974Z,1598464568.974 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:08.997Z,1598464568.997 [CBIT](IMPORTANT): averaging humidity:17 %. Average:510.000000
2020-08-26T17:56:09.378Z,1598464569.378 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:09.401Z,1598464569.401 [CBIT](IMPORTANT): averaging humidity:17 %. Average:527.000000
2020-08-26T17:56:09.782Z,1598464569.782 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:09.799Z,1598464569.799 [CBIT](IMPORTANT): averaging humidity:17 %. Average:544.000000
2020-08-26T17:56:10.186Z,1598464570.186 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:10.213Z,1598464570.213 [CBIT](IMPORTANT): averaging humidity:17 %. Average:561.000000
2020-08-26T17:56:10.590Z,1598464570.590 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:10.612Z,1598464570.612 [CBIT](IMPORTANT): averaging humidity:17 %. Average:578.000000
2020-08-26T17:56:10.994Z,1598464570.994 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:11.065Z,1598464571.065 [CBIT](IMPORTANT): averaging humidity:17 %. Average:595.000000
2020-08-26T17:56:11.398Z,1598464571.398 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:11.429Z,1598464571.429 [CBIT](IMPORTANT): averaging humidity:17 %. Average:612.000000
2020-08-26T17:56:11.798Z,1598464571.798 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:11.848Z,1598464571.848 [CBIT](IMPORTANT): averaging humidity:17 %. Average:629.000000
2020-08-26T17:56:12.202Z,1598464572.202 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:12.258Z,1598464572.258 [CBIT](IMPORTANT): averaging humidity:17 %. Average:646.000000
2020-08-26T17:56:12.610Z,1598464572.610 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:12.644Z,1598464572.644 [CBIT](IMPORTANT): averaging humidity:17 %. Average:663.000000
2020-08-26T17:56:13.015Z,1598464573.015 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:13.080Z,1598464573.080 [CBIT](IMPORTANT): averaging humidity:17 %. Average:680.000000
2020-08-26T17:56:13.414Z,1598464573.414 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:13.460Z,1598464573.460 [CBIT](IMPORTANT): averaging humidity:17 %. Average:697.000000
2020-08-26T17:56:13.822Z,1598464573.822 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:13.872Z,1598464573.872 [CBIT](IMPORTANT): averaging humidity:17 %. Average:714.000000
2020-08-26T17:56:14.222Z,1598464574.222 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:14.276Z,1598464574.276 [CBIT](IMPORTANT): averaging humidity:17 %. Average:731.000000
2020-08-26T17:56:14.630Z,1598464574.630 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:14.672Z,1598464574.672 [CBIT](IMPORTANT): averaging humidity:17 %. Average:748.000000
2020-08-26T17:56:15.030Z,1598464575.030 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:15.069Z,1598464575.069 [CBIT](IMPORTANT): averaging humidity:17 %. Average:765.000000
2020-08-26T17:56:15.438Z,1598464575.438 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:15.489Z,1598464575.489 [CBIT](IMPORTANT): averaging humidity:17 %. Average:782.000000
2020-08-26T17:56:15.842Z,1598464575.842 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:15.864Z,1598464575.864 [CBIT](IMPORTANT): averaging humidity:17 %. Average:799.000000
2020-08-26T17:56:16.246Z,1598464576.246 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:16.303Z,1598464576.303 [CBIT](IMPORTANT): averaging humidity:17 %. Average:816.000000
2020-08-26T17:56:16.650Z,1598464576.650 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:16.668Z,1598464576.668 [CBIT](IMPORTANT): averaging humidity:17 %. Average:833.000000
2020-08-26T17:56:17.050Z,1598464577.050 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:17.100Z,1598464577.100 [CBIT](IMPORTANT): averaging humidity:17 %. Average:850.000000
2020-08-26T17:56:17.454Z,1598464577.454 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:17.512Z,1598464577.512 [CBIT](IMPORTANT): averaging humidity:17 %. Average:867.000000
2020-08-26T17:56:17.862Z,1598464577.862 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:17.885Z,1598464577.885 [CBIT](IMPORTANT): averaging humidity:17 %. Average:884.000000
2020-08-26T17:56:18.267Z,1598464578.267 [NAL9602](INFO): Powering up NAL9602
2020-08-26T17:56:18.269Z,1598464578.269 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:18.306Z,1598464578.306 [CBIT](IMPORTANT): averaging humidity:17 %. Average:901.000000
2020-08-26T17:56:18.670Z,1598464578.670 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:18.701Z,1598464578.701 [CBIT](IMPORTANT): averaging humidity:17 %. Average:918.000000
2020-08-26T17:56:19.074Z,1598464579.074 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:19.096Z,1598464579.096 [CBIT](IMPORTANT): averaging humidity:17 %. Average:935.000000
2020-08-26T17:56:19.478Z,1598464579.478 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:19.527Z,1598464579.527 [CBIT](IMPORTANT): averaging humidity:17 %. Average:952.000000
2020-08-26T17:56:19.882Z,1598464579.882 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:19.917Z,1598464579.917 [CBIT](IMPORTANT): averaging humidity:17 %. Average:969.000000
2020-08-26T17:56:20.286Z,1598464580.286 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T17:56:20.286Z,1598464580.286 [DAT](FAULT): failed to enter command mode
2020-08-26T17:56:20.322Z,1598464580.322 [CBIT](IMPORTANT): averaging humidity:17 %. Average:986.000000
2020-08-26T17:56:20.690Z,1598464580.690 [DAT](INFO): entering command mode
2020-08-26T17:56:20.747Z,1598464580.747 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1003.000000
2020-08-26T17:56:21.095Z,1598464581.095 [DAT](INFO): setting verbose to 3
2020-08-26T17:56:21.159Z,1598464581.159 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1020.000000
2020-08-26T17:56:21.499Z,1598464581.499 [DAT](INFO): set verbose to 3
2020-08-26T17:56:21.499Z,1598464581.499 [DAT](INFO): setting DatVerbose to 27440
2020-08-26T17:56:21.536Z,1598464581.536 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1037.000000
2020-08-26T17:56:21.903Z,1598464581.903 [DAT](INFO): set DatVerbose to 27440
2020-08-26T17:56:21.904Z,1598464581.904 [DAT](INFO): setting transmit power to 8
2020-08-26T17:56:21.970Z,1598464581.970 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1054.000000
2020-08-26T17:56:22.303Z,1598464582.303 [DAT](INFO): set transmit power to 8
2020-08-26T17:56:22.304Z,1598464582.304 [DAT](INFO): setting local address to 5
2020-08-26T17:56:22.387Z,1598464582.387 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1071.000000
2020-08-26T17:56:22.707Z,1598464582.707 [DAT](INFO): set local address to 5
2020-08-26T17:56:22.770Z,1598464582.770 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1088.000000
2020-08-26T17:56:23.136Z,1598464583.136 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1105.000000
2020-08-26T17:56:23.564Z,1598464583.564 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1122.000000
2020-08-26T17:56:23.968Z,1598464583.968 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1139.000000
2020-08-26T17:56:24.372Z,1598464584.372 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1156.000000
2020-08-26T17:56:24.778Z,1598464584.778 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1173.000000
2020-08-26T17:56:25.164Z,1598464585.164 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1190.000000
2020-08-26T17:56:25.584Z,1598464585.584 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1207.000000
2020-08-26T17:56:25.989Z,1598464585.989 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1224.000000
2020-08-26T17:56:26.401Z,1598464586.401 [SBIT](IMPORTANT): Beginning Startup BIT
2020-08-26T17:56:26.405Z,1598464586.405 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1241.000000
2020-08-26T17:56:26.405Z,1598464586.405 [CBIT](IMPORTANT): Beginning ground fault scan
2020-08-26T17:56:26.850Z,1598464586.850 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1258.000000
2020-08-26T17:56:27.249Z,1598464587.249 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1275.000000
2020-08-26T17:56:27.635Z,1598464587.635 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1292.000000
2020-08-26T17:56:28.141Z,1598464588.141 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1309.000000
2020-08-26T17:56:28.514Z,1598464588.514 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1326.000000
2020-08-26T17:56:28.889Z,1598464588.889 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1343.000000
2020-08-26T17:56:29.170Z,1598464589.170 [NAL9602](INFO): NAL9602 initialized
2020-08-26T17:56:29.289Z,1598464589.289 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1360.000000
2020-08-26T17:56:29.730Z,1598464589.730 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1377.000000
2020-08-26T17:56:30.238Z,1598464590.238 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1394.000000
2020-08-26T17:56:30.486Z,1598464590.486 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1411.000000
2020-08-26T17:56:30.937Z,1598464590.937 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1428.000000
2020-08-26T17:56:31.385Z,1598464591.385 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1445.000000
2020-08-26T17:56:31.658Z,1598464591.658 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1462.000000
2020-08-26T17:56:32.068Z,1598464592.068 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1479.000000
2020-08-26T17:56:32.474Z,1598464592.474 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1496.000000
2020-08-26T17:56:32.877Z,1598464592.877 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1513.000000
2020-08-26T17:56:33.293Z,1598464593.293 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1530.000000
2020-08-26T17:56:33.695Z,1598464593.695 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1547.000000
2020-08-26T17:56:34.094Z,1598464594.094 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1564.000000
2020-08-26T17:56:34.493Z,1598464594.493 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1581.000000
2020-08-26T17:56:34.894Z,1598464594.894 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1598.000000
2020-08-26T17:56:35.293Z,1598464595.293 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1615.000000
2020-08-26T17:56:35.710Z,1598464595.710 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1632.000000
2020-08-26T17:56:36.122Z,1598464596.122 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1649.000000
2020-08-26T17:56:36.601Z,1598464596.601 [CBIT](IMPORTANT): averaging humidity:17 %. Average:1666.000000
2020-08-26T17:56:37.308Z,1598464597.308 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008561
CHAN A1 (24V): -0.007851
CHAN A2 (12V): -0.004643
CHAN A3 (5V): -0.002728
CHAN B0 (3.3V): -0.000796
CHAN B1 (3.15aV): -0.001409
CHAN B2 (3.15bV): -0.001423
CHAN B3 (GND): -0.000150
OPEN: 0.004465
Full Scale Calc: 4.765 mA, -1.589 mA
2020-08-26T17:57:20.519Z,1598464640.519 [SBIT](IMPORTANT): SBIT PASSED
2020-08-26T17:57:20.519Z,1598464640.519 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-08-26T17:57:20.520Z,1598464640.520 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2020-08-26T17:57:20.520Z,1598464640.520 [SBIT](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2020-08-26T17:57:20.520Z,1598464640.520 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-08-26T17:57:20.520Z,1598464640.520 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-08-26T17:57:20.520Z,1598464640.520 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2020-08-26T17:57:20.521Z,1598464640.521 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-08-26T17:57:20.521Z,1598464640.521 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2020-08-26T17:57:20.521Z,1598464640.521 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter;

2020-08-26T17:57:20.521Z,1598464640.521 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2020-08-26T17:57:20.521Z,1598464640.521 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2020-08-26T17:57:20.521Z,1598464640.521 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2020-08-26T17:57:20.521Z,1598464640.521 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=168 cubic_centimeter;

2020-08-26T17:57:20.521Z,1598464640.521 [SBIT](IMPORTANT): VerticalControl.massDefault=3.7 millimeter;

2020-08-26T17:57:20.909Z,1598464640.909 [MissionManager](IMPORTANT): Started mission Startup
2020-08-26T17:57:20.909Z,1598464640.909 [Startup] Running Loop=1
2020-08-26T17:57:20.909Z,1598464640.909 [Startup](DEBUG): Aggregate::initialize Startup
2020-08-26T17:57:20.909Z,1598464640.909 [Startup:A.GoToSurface] Running Loop=1
2020-08-26T17:57:20.909Z,1598464640.909 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-08-26T17:57:20.910Z,1598464640.910 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-08-26T17:57:20.910Z,1598464640.910 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-08-26T17:57:20.910Z,1598464640.910 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-08-26T17:57:20.911Z,1598464640.911 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-08-26T17:57:20.911Z,1598464640.911 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-08-26T17:57:20.913Z,1598464640.913 [Startup:StartupSatComms] Running Loop=1
2020-08-26T17:57:20.913Z,1598464640.913 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-08-26T17:57:20.913Z,1598464640.913 [Startup:StartupSatComms:A] Running Loop=1
2020-08-26T17:57:21.303Z,1598464641.303 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-08-26T17:57:52.021Z,1598464672.021 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2020-08-26T17:57:52.021Z,1598464672.021 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-08-26T17:57:52.021Z,1598464672.021 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-26T17:57:52.032Z,1598464672.032 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-26T17:57:52.420Z,1598464672.420 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-26T17:57:52.420Z,1598464672.420 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-08-26T17:58:19.267Z,1598464699.267 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004732
2020-08-26T17:58:21.090Z,1598464701.090 [Startup:StartupSatComms:A](INFO): Timed out from 2020-08-26T17:57:20.9Z
2020-08-26T17:58:21.090Z,1598464701.090 [Startup:StartupSatComms:A] Stopped
2020-08-26T17:58:21.090Z,1598464701.090 [Startup:StartupSatComms:B] Running Loop=1
2020-08-26T17:58:21.498Z,1598464701.498 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-08-26T17:58:28.344Z,1598464708.344 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200826T175157/Courier0004.lzma
2020-08-26T17:58:29.346Z,1598464709.346 [DataOverHttps](INFO): Moved sent file to Logs/20200826T175157/Courier0004.lzma.bak
2020-08-26T17:58:29.347Z,1598464709.347 [DataOverHttps](INFO): SBD MOMSN=12620815
2020-08-26T17:58:44.432Z,1598464724.432 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200826T175540/Courier0000.lzma
2020-08-26T17:58:45.435Z,1598464725.435 [DataOverHttps](INFO): Moved sent file to Logs/20200826T175540/Courier0000.lzma.bak
2020-08-26T17:58:45.435Z,1598464725.435 [DataOverHttps](INFO): SBD MOMSN=12620817
2020-08-26T17:59:00.702Z,1598464740.702 [DataOverHttps](INFO): Sending 1263 bytes from file Logs/20200826T174542/Express0001.lzma
2020-08-26T17:59:01.702Z,1598464741.702 [DataOverHttps](INFO): Moved sent file to Logs/20200826T174542/Express0001.lzma.bak
2020-08-26T17:59:01.702Z,1598464741.702 [DataOverHttps](INFO): SBD MOMSN=12620820
2020-08-26T17:59:16.780Z,1598464756.780 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20200826T174542/Express0005.lzma
2020-08-26T17:59:17.782Z,1598464757.782 [DataOverHttps](INFO): Moved sent file to Logs/20200826T174542/Express0005.lzma.bak
2020-08-26T17:59:17.783Z,1598464757.783 [DataOverHttps](INFO): SBD MOMSN=12620855
2020-08-26T17:59:21.310Z,1598464761.310 [Startup:StartupSatComms:B](INFO): Timed out from 2020-08-26T17:58:21.1Z
2020-08-26T17:59:21.310Z,1598464761.310 [Startup:StartupSatComms:B] Stopped
2020-08-26T17:59:21.310Z,1598464761.310 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-08-26T17:59:21.310Z,1598464761.310 [Startup:StartupSatComms] Stopped
2020-08-26T17:59:21.310Z,1598464761.310 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-08-26T17:59:21.311Z,1598464761.311 [Startup](INFO): Completed Startup
2020-08-26T17:59:21.311Z,1598464761.311 [MissionManager](INFO): Startup is completed.
2020-08-26T17:59:21.311Z,1598464761.311 [MissionManager](INFO): Uninitializing Mission Startup
2020-08-26T17:59:21.311Z,1598464761.311 [Startup] Stopped
2020-08-26T17:59:21.311Z,1598464761.311 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-08-26T17:59:21.311Z,1598464761.311 [Startup:A.GoToSurface] Stopped
2020-08-26T17:59:21.312Z,1598464761.312 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-08-26T17:59:21.703Z,1598464761.703 [MissionManager](IMPORTANT): Started mission Default
2020-08-26T17:59:21.703Z,1598464761.703 [Default] Running Loop=1
2020-08-26T17:59:21.704Z,1598464761.704 [Default](DEBUG): Aggregate::initialize Default
2020-08-26T17:59:21.704Z,1598464761.704 [Default:B.GoToSurface] Running Loop=1
2020-08-26T17:59:21.704Z,1598464761.704 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-08-26T17:59:21.704Z,1598464761.704 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-08-26T17:59:21.704Z,1598464761.704 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-08-26T17:59:21.705Z,1598464761.705 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-08-26T17:59:21.705Z,1598464761.705 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-08-26T17:59:21.705Z,1598464761.705 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-08-26T17:59:21.706Z,1598464761.705 [Default:A.Wait] Running Loop=1
2020-08-26T17:59:21.706Z,1598464761.706 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-08-26T17:59:33.055Z,1598464773.055 [DataOverHttps](INFO): Sending 1703 bytes from file Logs/20200826T174859/Express0001.lzma
2020-08-26T17:59:34.054Z,1598464774.054 [DataOverHttps](INFO): Moved sent file to Logs/20200826T174859/Express0001.lzma.bak
2020-08-26T17:59:34.054Z,1598464774.054 [DataOverHttps](INFO): SBD MOMSN=12620859
2020-08-26T17:59:35.033Z,1598464775.033 [Default:A.Wait](INFO): Done Waiting.
2020-08-26T17:59:35.033Z,1598464775.033 [Default:A.Wait] Stopped
2020-08-26T17:59:35.033Z,1598464775.033 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-08-26T17:59:35.426Z,1598464775.426 [Default:CheckIn] Running Loop=1
2020-08-26T17:59:35.426Z,1598464775.426 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-08-26T17:59:35.426Z,1598464775.426 [Default:CheckIn:Read_GPS] Running Loop=1
2020-08-26T17:59:35.847Z,1598464775.847 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-08-26T17:59:52.813Z,1598464792.813 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2020-08-26T17:59:52.813Z,1598464792.813 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-08-26T17:59:52.813Z,1598464792.813 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-26T17:59:52.824Z,1598464792.824 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-26T17:59:53.222Z,1598464793.222 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-26T17:59:53.222Z,1598464793.222 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-08-26T18:01:32.586Z,1598464892.586 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-08-26T18:01:54.007Z,1598464914.007 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2020-08-26T18:01:54.007Z,1598464914.007 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-08-26T18:01:54.007Z,1598464914.007 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-26T18:01:54.018Z,1598464914.018 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-26T18:01:54.428Z,1598464914.428 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-26T18:01:54.428Z,1598464914.428 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-08-26T18:03:55.229Z,1598465035.229 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2020-08-26T18:03:55.230Z,1598465035.230 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-08-26T18:03:55.230Z,1598465035.230 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-26T18:03:55.240Z,1598465035.240 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-26T18:03:55.653Z,1598465035.653 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-26T18:03:55.653Z,1598465035.653 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-08-26T18:04:27.857Z,1598465067.857 [CommandLine](IMPORTANT): got command restart application
2020-08-26T18:04:28.865Z,1598465068.865 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-08-26T18:04:28.865Z,1598465068.865 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T18:04:28.865Z,1598465068.865 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:28.881Z,1598465068.881 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-08-26T18:04:28.881Z,1598465068.881 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:28.881Z,1598465068.881 [CommandLine](INFO): Join timeout helper Thread ID is 7874
2020-08-26T18:04:28.891Z,1598465068.891 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-08-26T18:04:28.891Z,1598465068.891 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:28.891Z,1598465068.891 [NavChartDb](INFO): Join timeout helper Thread ID is 7875
2020-08-26T18:04:29.277Z,1598465069.277 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T18:04:29.277Z,1598465069.277 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:29.289Z,1598465069.289 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-08-26T18:04:29.289Z,1598465069.289 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:29.289Z,1598465069.289 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7876
2020-08-26T18:04:29.413Z,1598465069.413 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T18:04:29.413Z,1598465069.413 [WetLabsBB2FL](INFO): Powering down
2020-08-26T18:04:29.414Z,1598465069.414 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:29.429Z,1598465069.429 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-08-26T18:04:29.429Z,1598465069.429 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:29.429Z,1598465069.429 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7877
2020-08-26T18:04:29.861Z,1598465069.861 [CTD_Seabird](INFO): Powering down
2020-08-26T18:04:29.873Z,1598465069.873 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T18:04:29.873Z,1598465069.873 [CTD_Seabird](INFO): Powering down
2020-08-26T18:04:29.885Z,1598465069.885 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:29.889Z,1598465069.889 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-08-26T18:04:29.889Z,1598465069.889 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:29.889Z,1598465069.889 [Radio_Surface](INFO): Join timeout helper Thread ID is 7878
2020-08-26T18:04:30.065Z,1598465070.065 [Radio_Surface](INFO): Powering down
2020-08-26T18:04:30.066Z,1598465070.066 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T18:04:30.066Z,1598465070.066 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:30.070Z,1598465070.070 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-08-26T18:04:30.070Z,1598465070.070 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:30.070Z,1598465070.070 [Onboard](INFO): Join timeout helper Thread ID is 7879
2020-08-26T18:04:30.633Z,1598465070.633 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-08-26T18:04:32.089Z,1598465072.089 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T18:04:32.089Z,1598465072.089 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:32.109Z,1598465072.109 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-08-26T18:04:32.109Z,1598465072.109 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:32.110Z,1598465072.110 [DataOverHttps](INFO): Join timeout helper Thread ID is 7880
2020-08-26T18:04:32.641Z,1598465072.641 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T18:04:32.641Z,1598465072.641 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:32.653Z,1598465072.653 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-08-26T18:04:32.653Z,1598465072.653 [logger ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:32.654Z,1598465072.654 [logger](INFO): Join timeout helper Thread ID is 7881
2020-08-26T18:04:32.685Z,1598465072.685 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T18:04:32.685Z,1598465072.685 [logger ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:32.693Z,1598465072.693 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-08-26T18:04:32.693Z,1598465072.693 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:32.694Z,1598465072.694 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-08-26T18:04:32.694Z,1598465072.694 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:32.694Z,1598465072.694 [controlThread](INFO): Join timeout helper Thread ID is 7882
2020-08-26T18:04:32.769Z,1598465072.769 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T18:04:32.769Z,1598465072.769 [controlThread](DEBUG): Uninitializing ControlThread
2020-08-26T18:04:32.769Z,1598465072.769 [NAL9602](INFO): Powering down
2020-08-26T18:04:32.771Z,1598465072.771 [DAT](INFO): Powering down
2020-08-26T18:04:32.891Z,1598465072.891 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-08-26T18:04:32.892Z,1598465072.892 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-08-26T18:04:32.893Z,1598465072.893 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-08-26T18:04:32.894Z,1598465072.894 [MissionManager](INFO): Uninitializing Mission Default
2020-08-26T18:04:32.894Z,1598465072.894 [Default] Stopped
2020-08-26T18:04:32.894Z,1598465072.894 [Default](DEBUG): Aggregate::uninitialize Default
2020-08-26T18:04:32.894Z,1598465072.894 [Default:B.GoToSurface] Stopped
2020-08-26T18:04:32.894Z,1598465072.894 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-08-26T18:04:32.894Z,1598465072.894 [Default:CheckIn] Stopped
2020-08-26T18:04:32.894Z,1598465072.894 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-08-26T18:04:32.894Z,1598465072.894 [Default:CheckIn:Read_GPS] Stopped
2020-08-26T18:04:32.897Z,1598465072.897 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-08-26T18:04:32.897Z,1598465072.897 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-08-26T18:04:32.897Z,1598465072.897 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-08-26T18:04:32.898Z,1598465072.898 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-08-26T18:04:32.898Z,1598465072.898 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-08-26T18:04:32.898Z,1598465072.898 [BuoyancyServo](INFO): Powering down
2020-08-26T18:04:32.913Z,1598465072.913 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-08-26T18:04:32.913Z,1598465072.913 [ElevatorServo](INFO): Powering down
2020-08-26T18:04:32.914Z,1598465072.914 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-08-26T18:04:32.914Z,1598465072.914 [MassServo](INFO): Powering down
2020-08-26T18:04:32.915Z,1598465072.915 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-08-26T18:04:32.915Z,1598465072.915 [RudderServo](INFO): Powering down
2020-08-26T18:04:32.916Z,1598465072.916 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-08-26T18:04:32.916Z,1598465072.916 [ThrusterServo](INFO): Powering down
2020-08-26T18:04:32.916Z,1598465072.916 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-08-26T18:04:32.917Z,1598465072.917 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-08-26T18:04:32.917Z,1598465072.917 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-08-26T18:04:32.917Z,1598465072.917 [CBIT](DEBUG): Powering off loads.
2020-08-26T18:04:32.928Z,1598465072.928 [CBIT](DEBUG): Disabling WDT.
2020-08-26T18:04:32.940Z,1598465072.940 [CBIT](DEBUG): Opening all GF detection circuits.
2020-08-26T18:04:32.941Z,1598465072.941 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:33.033Z,1598465073.033 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:33.035Z,1598465073.035 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:33.041Z,1598465073.041 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:33.082Z,1598465073.082 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:33.087Z,1598465073.087 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:33.126Z,1598465073.126 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-26T18:04:33.201Z,1598465073.201 [logger ThreadHandler](INFO): Thread cancelled.