2020-10-28T04:25:45.779Z,1603859145.779 [Supervisor](DEBUG): Initializing supervisor.
2020-10-28T04:25:45.782Z,1603859145.782 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-10-28T04:25:45.782Z,1603859145.782 [SyncHandler](INFO): Protected caller Thread ID is 820
2020-10-28T04:25:45.783Z,1603859145.783 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-10-28T04:25:45.784Z,1603859145.784 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-10-28T04:25:45.784Z,1603859145.784 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 821
2020-10-28T04:25:45.787Z,1603859145.787 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-10-28T04:25:45.803Z,1603859145.803 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-10-28T04:25:45.804Z,1603859145.804 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-10-28T04:25:45.805Z,1603859145.805 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 822
2020-10-28T04:25:45.805Z,1603859145.805 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-10-28T04:25:45.806Z,1603859145.806 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-10-28T04:25:45.807Z,1603859145.807 [logger ThreadHandler](INFO): Protected caller Thread ID is 823
2020-10-28T04:25:45.809Z,1603859145.809 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-10-28T04:25:45.809Z,1603859145.809 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-10-28T04:25:45.813Z,1603859145.813 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-10-28T04:25:45.907Z,1603859145.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-10-28T04:25:45.909Z,1603859145.909 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-10-28T04:25:46.092Z,1603859146.092 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-10-28T04:25:46.093Z,1603859146.093 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-10-28T04:25:46.295Z,1603859146.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-10-28T04:25:46.297Z,1603859146.297 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-10-28T04:25:46.433Z,1603859146.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-10-28T04:25:46.434Z,1603859146.434 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-10-28T04:25:47.014Z,1603859147.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-10-28T04:25:47.016Z,1603859147.016 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-10-28T04:25:47.471Z,1603859147.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-10-28T04:25:47.473Z,1603859147.473 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-10-28T04:25:47.966Z,1603859147.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-10-28T04:25:47.967Z,1603859147.967 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-10-28T04:25:48.256Z,1603859148.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-10-28T04:25:48.257Z,1603859148.257 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-10-28T04:25:48.564Z,1603859148.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-10-28T04:25:48.565Z,1603859148.565 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-10-28T04:25:48.968Z,1603859148.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-10-28T04:25:48.969Z,1603859148.969 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-10-28T04:25:49.115Z,1603859149.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-10-28T04:25:49.116Z,1603859149.116 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-10-28T04:25:49.716Z,1603859149.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-10-28T04:25:49.718Z,1603859149.718 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-10-28T04:25:49.804Z,1603859149.804 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-10-28T04:25:49.904Z,1603859149.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-10-28T04:25:49.904Z,1603859149.904 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-10-28T04:25:50.093Z,1603859150.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-10-28T04:25:50.093Z,1603859150.093 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-10-28T04:25:50.296Z,1603859150.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-10-28T04:25:50.298Z,1603859150.298 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2020-10-28T04:25:50.300Z,1603859150.300 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2020-10-28T04:25:50.383Z,1603859150.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2020-10-28T04:25:50.628Z,1603859150.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-10-28T04:25:50.629Z,1603859150.629 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2020-10-28T04:25:50.722Z,1603859150.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2020-10-28T04:25:50.882Z,1603859150.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2020-10-28T04:25:51.081Z,1603859151.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2020-10-28T04:25:51.165Z,1603859151.165 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2020-10-28T04:25:51.263Z,1603859151.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2020-10-28T04:25:51.354Z,1603859151.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2020-10-28T04:25:51.511Z,1603859151.511 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2020-10-28T04:25:51.612Z,1603859151.612 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2020-10-28T04:25:51.709Z,1603859151.709 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-10-28T04:25:51.726Z,1603859151.726 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-10-28T04:25:51.847Z,1603859151.847 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-10-28T04:25:51.849Z,1603859151.849 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-10-28T04:25:52.006Z,1603859152.006 [BuoyancyServo] Loaded
2020-10-28T04:25:52.006Z,1603859152.006 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-10-28T04:25:52.023Z,1603859152.023 [ElevatorServo] Loaded
2020-10-28T04:25:52.023Z,1603859152.023 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-10-28T04:25:52.039Z,1603859152.039 [MassServo] Loaded
2020-10-28T04:25:52.040Z,1603859152.040 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-10-28T04:25:52.056Z,1603859152.056 [RudderServo] Loaded
2020-10-28T04:25:52.056Z,1603859152.056 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-10-28T04:25:52.072Z,1603859152.072 [ThrusterServo] Loaded
2020-10-28T04:25:52.072Z,1603859152.072 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-10-28T04:25:52.072Z,1603859152.072 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-10-28T04:25:52.074Z,1603859152.074 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-10-28T04:25:52.273Z,1603859152.273 [SBIT](DEBUG): Construct Startup Built In Test.
2020-10-28T04:25:52.286Z,1603859152.286 [SBIT] Loaded
2020-10-28T04:25:52.287Z,1603859152.287 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-10-28T04:25:52.287Z,1603859152.287 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-10-28T04:25:52.306Z,1603859152.306 [IBIT] Loaded
2020-10-28T04:25:52.307Z,1603859152.307 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-10-28T04:25:52.310Z,1603859152.310 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-10-28T04:25:52.503Z,1603859152.503 [CBIT] Loaded
2020-10-28T04:25:52.503Z,1603859152.503 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-10-28T04:25:52.504Z,1603859152.504 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-10-28T04:25:52.506Z,1603859152.506 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-10-28T04:25:52.775Z,1603859152.775 [CANONSampler] Loaded
2020-10-28T04:25:52.775Z,1603859152.775 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2020-10-28T04:25:52.793Z,1603859152.793 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-10-28T04:25:52.795Z,1603859152.795 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-10-28T04:25:52.797Z,1603859152.797 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-10-28T04:25:52.799Z,1603859152.799 [CTD_Seabird](INFO): created writer for : depth
2020-10-28T04:25:52.800Z,1603859152.800 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-10-28T04:25:52.803Z,1603859152.803 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-10-28T04:25:52.803Z,1603859152.803 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-10-28T04:25:52.806Z,1603859152.806 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-10-28T04:25:52.806Z,1603859152.806 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-10-28T04:25:52.809Z,1603859152.809 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-10-28T04:25:52.810Z,1603859152.810 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-10-28T04:25:52.813Z,1603859152.813 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-10-28T04:25:52.814Z,1603859152.814 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-10-28T04:25:52.816Z,1603859152.816 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-10-28T04:25:52.817Z,1603859152.817 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2020-10-28T04:25:52.819Z,1603859152.819 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2020-10-28T04:25:52.820Z,1603859152.820 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2020-10-28T04:25:52.820Z,1603859152.820 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2020-10-28T04:25:52.835Z,1603859152.835 [CTD_Seabird] Loaded
2020-10-28T04:25:52.835Z,1603859152.835 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-10-28T04:25:52.836Z,1603859152.836 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406784E0
2020-10-28T04:25:52.837Z,1603859152.837 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 906
2020-10-28T04:25:52.864Z,1603859152.864 [ESPComponent] Loaded
2020-10-28T04:25:52.864Z,1603859152.864 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-10-28T04:25:52.880Z,1603859152.880 [PAR_Licor] Loaded
2020-10-28T04:25:52.880Z,1603859152.880 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-10-28T04:25:52.892Z,1603859152.892 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-10-28T04:25:52.893Z,1603859152.893 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-10-28T04:25:52.894Z,1603859152.894 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-10-28T04:25:52.895Z,1603859152.895 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-10-28T04:25:52.896Z,1603859152.896 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-10-28T04:25:52.896Z,1603859152.896 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-10-28T04:25:52.898Z,1603859152.898 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-10-28T04:25:52.899Z,1603859152.899 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-10-28T04:25:52.900Z,1603859152.900 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-10-28T04:25:52.900Z,1603859152.900 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-10-28T04:25:52.903Z,1603859152.903 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-10-28T04:25:52.903Z,1603859152.903 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-10-28T04:25:52.904Z,1603859152.904 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-10-28T04:25:52.905Z,1603859152.905 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-10-28T04:25:52.906Z,1603859152.906 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-10-28T04:25:52.909Z,1603859152.909 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-10-28T04:25:52.909Z,1603859152.909 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-10-28T04:25:52.909Z,1603859152.909 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-10-28T04:25:52.911Z,1603859152.911 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-10-28T04:25:52.911Z,1603859152.911 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-10-28T04:25:52.913Z,1603859152.913 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-10-28T04:25:52.913Z,1603859152.913 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-10-28T04:25:52.915Z,1603859152.915 [WetLabsBB2FL] Loaded
2020-10-28T04:25:52.915Z,1603859152.915 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-10-28T04:25:52.916Z,1603859152.916 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A84E0
2020-10-28T04:25:52.917Z,1603859152.917 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 907
2020-10-28T04:25:52.918Z,1603859152.918 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-10-28T04:25:52.919Z,1603859152.919 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-10-28T04:25:52.970Z,1603859152.970 [DepthRateCalculator] Loaded
2020-10-28T04:25:52.970Z,1603859152.970 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-10-28T04:25:52.973Z,1603859152.973 [PitchRateCalculator] Loaded
2020-10-28T04:25:52.973Z,1603859152.973 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-10-28T04:25:52.981Z,1603859152.981 [SpeedCalculator] Loaded
2020-10-28T04:25:52.981Z,1603859152.981 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-10-28T04:25:52.992Z,1603859152.992 [TempGradientCalculator] Loaded
2020-10-28T04:25:52.992Z,1603859152.992 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-10-28T04:25:52.995Z,1603859152.995 [YawRateCalculator] Loaded
2020-10-28T04:25:52.995Z,1603859152.995 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-10-28T04:25:53.010Z,1603859153.010 [ElevatorOffsetCalculator] Loaded
2020-10-28T04:25:53.011Z,1603859153.011 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-10-28T04:25:53.011Z,1603859153.011 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-10-28T04:25:53.012Z,1603859153.012 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-10-28T04:25:53.106Z,1603859153.106 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-10-28T04:25:53.107Z,1603859153.107 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-10-28T04:25:53.147Z,1603859153.147 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-10-28T04:25:53.147Z,1603859153.147 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-10-28T04:25:53.243Z,1603859153.243 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-10-28T04:25:53.244Z,1603859153.244 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-10-28T04:25:53.254Z,1603859153.254 [NavChart] Loaded
2020-10-28T04:25:53.255Z,1603859153.255 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-10-28T04:25:53.258Z,1603859153.258 [UniversalFixResidualReporter] Loaded
2020-10-28T04:25:53.259Z,1603859153.259 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-10-28T04:25:53.259Z,1603859153.259 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-10-28T04:25:53.261Z,1603859153.261 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-10-28T04:25:53.788Z,1603859153.788 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-10-28T04:25:53.791Z,1603859153.791 [AHRS_M2](INFO): created writer for : platform_orientation
2020-10-28T04:25:53.792Z,1603859153.792 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-10-28T04:25:53.795Z,1603859153.795 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-10-28T04:25:53.795Z,1603859153.795 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-10-28T04:25:53.798Z,1603859153.798 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-10-28T04:25:53.798Z,1603859153.798 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-10-28T04:25:53.801Z,1603859153.801 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-10-28T04:25:53.945Z,1603859153.945 [AHRS_M2] Loaded
2020-10-28T04:25:53.945Z,1603859153.945 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-10-28T04:25:54.083Z,1603859154.083 [DataOverHttps] Loaded
2020-10-28T04:25:54.083Z,1603859154.083 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-10-28T04:25:54.084Z,1603859154.084 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409CC4E0
2020-10-28T04:25:54.084Z,1603859154.084 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 908
2020-10-28T04:25:54.101Z,1603859154.101 [Depth_Keller] Loaded
2020-10-28T04:25:54.101Z,1603859154.101 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-10-28T04:25:54.104Z,1603859154.104 [DropWeight] Loaded
2020-10-28T04:25:54.104Z,1603859154.104 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-10-28T04:25:54.154Z,1603859154.154 [NAL9602] Loaded
2020-10-28T04:25:54.155Z,1603859154.155 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-10-28T04:25:54.176Z,1603859154.176 [Onboard] Loaded
2020-10-28T04:25:54.176Z,1603859154.176 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-10-28T04:25:54.177Z,1603859154.177 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409FC4E0
2020-10-28T04:25:54.177Z,1603859154.177 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 909
2020-10-28T04:25:54.188Z,1603859154.188 [Radio_Surface] Loaded
2020-10-28T04:25:54.188Z,1603859154.188 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-10-28T04:25:54.189Z,1603859154.189 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A2C4E0
2020-10-28T04:25:54.189Z,1603859154.189 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 910
2020-10-28T04:25:54.260Z,1603859154.260 [DAT] Loaded
2020-10-28T04:25:54.261Z,1603859154.261 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-10-28T04:25:55.610Z,1603859155.610 [BPC1] Loaded
2020-10-28T04:25:55.610Z,1603859155.610 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-10-28T04:25:55.628Z,1603859155.628 [AMEcho] Loaded
2020-10-28T04:25:55.628Z,1603859155.628 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2020-10-28T04:25:55.629Z,1603859155.629 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-10-28T04:25:55.629Z,1603859155.629 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-10-28T04:25:55.755Z,1603859155.755 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-10-28T04:25:55.756Z,1603859155.756 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-10-28T04:25:55.817Z,1603859155.817 [VerticalControl](DEBUG): Construct VerticalControl.
2020-10-28T04:25:55.867Z,1603859155.867 [VerticalControl] Loaded
2020-10-28T04:25:55.867Z,1603859155.867 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-10-28T04:25:55.868Z,1603859155.868 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-10-28T04:25:55.900Z,1603859155.900 [HorizontalControl] Loaded
2020-10-28T04:25:55.901Z,1603859155.901 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-10-28T04:25:55.902Z,1603859155.902 [SpeedControl](DEBUG): Construct SpeedControl.
2020-10-28T04:25:55.903Z,1603859155.903 [SpeedControl] Loaded
2020-10-28T04:25:55.903Z,1603859155.903 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-10-28T04:25:55.904Z,1603859155.904 [LoopControl](DEBUG): Construct LoopControl.
2020-10-28T04:25:55.904Z,1603859155.904 [LoopControl] Loaded
2020-10-28T04:25:55.905Z,1603859155.905 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-10-28T04:25:55.905Z,1603859155.905 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-10-28T04:25:55.906Z,1603859155.906 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-10-28T04:25:55.935Z,1603859155.935 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-10-28T04:25:55.935Z,1603859155.935 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-10-28T04:25:56.260Z,1603859156.260 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-10-28T04:25:56.264Z,1603859156.264 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-10-28T04:25:56.265Z,1603859156.265 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-10-28T04:25:56.273Z,1603859156.273 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-10-28T04:25:56.274Z,1603859156.274 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B664E0
2020-10-28T04:25:56.275Z,1603859156.275 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 911
2020-10-28T04:25:56.279Z,1603859156.279 [Supervisor](INFO): Main Thread ID is 816
2020-10-28T04:25:56.279Z,1603859156.279 [Supervisor](DEBUG): Running supervisor.
2020-10-28T04:25:56.280Z,1603859156.280 [CommandLine ThreadHandler](INFO): Handler Thread ID is 912
2020-10-28T04:25:56.282Z,1603859156.282 [controlThread ThreadHandler](INFO): Handler Thread ID is 913
2020-10-28T04:25:56.282Z,1603859156.282 [controlThread](DEBUG): Initializing ControlThread
2020-10-28T04:25:56.284Z,1603859156.284 [SBIT](INFO): Initialize SBIT Component.
2020-10-28T04:25:56.285Z,1603859156.285 [SBIT](IMPORTANT): git: 2020-10-07_A
2020-10-28T04:25:56.285Z,1603859156.285 [SBIT](INFO): git hash: c52f40a9277e8d17c6912ac1d266a8bed1742f02
2020-10-28T04:25:56.285Z,1603859156.285 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-10-28T04:25:56.287Z,1603859156.287 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019
2020-10-28T04:25:56.288Z,1603859156.288 [SBIT](INFO): Beginning SBIT in 76.000000 seconds.
2020-10-28T04:25:56.288Z,1603859156.288 [IBIT](INFO): Initialize IBIT Component.
2020-10-28T04:25:56.289Z,1603859156.289 [CBIT](DEBUG): Initialize CBIT Component.
2020-10-28T04:25:56.290Z,1603859156.290 [logger ThreadHandler](INFO): Handler Thread ID is 914
2020-10-28T04:25:56.301Z,1603859156.301 [CBIT](DEBUG): Initialized mux pins.
2020-10-28T04:25:56.301Z,1603859156.301 [CBIT](DEBUG): Initializing the watchdog timer.
2020-10-28T04:25:56.309Z,1603859156.309 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 915
2020-10-28T04:25:56.310Z,1603859156.310 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-28T04:25:56.315Z,1603859156.315 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 917
2020-10-28T04:25:56.315Z,1603859156.315 [WetLabsBB2FL](INFO): Powering down
2020-10-28T04:25:56.325Z,1603859156.325 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-10-28T04:25:56.325Z,1603859156.325 [CBIT](DEBUG): Initializing heartbeat.
2020-10-28T04:25:56.397Z,1603859156.397 [CBIT](DEBUG): Deactivating GF circuits.
2020-10-28T04:25:56.397Z,1603859156.397 [CBIT](DEBUG): Deactivating emergency mode.
2020-10-28T04:25:56.433Z,1603859156.433 [CBIT](DEBUG): Backplane powered.
2020-10-28T04:25:56.437Z,1603859156.437 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-10-28T04:25:56.438Z,1603859156.438 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-10-28T04:25:56.438Z,1603859156.438 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-10-28T04:25:56.438Z,1603859156.438 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-10-28T04:25:56.439Z,1603859156.439 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-10-28T04:25:56.439Z,1603859156.439 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-10-28T04:25:56.440Z,1603859156.440 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-10-28T04:25:56.441Z,1603859156.441 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-10-28T04:25:56.450Z,1603859156.450 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-10-28T04:25:56.452Z,1603859156.452 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-10-28T04:25:56.452Z,1603859156.452 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-10-28T04:25:56.453Z,1603859156.453 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-10-28T04:25:56.458Z,1603859156.458 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 918
2020-10-28T04:25:56.459Z,1603859156.459 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-10-28T04:25:56.460Z,1603859156.460 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-10-28T04:25:56.562Z,1603859156.562 [Onboard ThreadHandler](INFO): Handler Thread ID is 919
2020-10-28T04:25:56.660Z,1603859156.660 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 920
2020-10-28T04:25:56.662Z,1603859156.662 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-28T04:25:56.682Z,1603859156.682 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 921
2020-10-28T04:25:56.724Z,1603859156.724 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-10-28T04:25:56.724Z,1603859156.724 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-10-28T04:25:56.724Z,1603859156.724 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-10-28T04:25:56.724Z,1603859156.724 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-10-28T04:25:56.725Z,1603859156.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-10-28T04:25:56.725Z,1603859156.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-10-28T04:25:56.725Z,1603859156.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-10-28T04:25:56.725Z,1603859156.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-10-28T04:25:56.725Z,1603859156.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-10-28T04:25:56.725Z,1603859156.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-10-28T04:25:56.726Z,1603859156.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-10-28T04:25:56.726Z,1603859156.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-10-28T04:25:56.726Z,1603859156.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-10-28T04:25:56.726Z,1603859156.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-10-28T04:25:56.726Z,1603859156.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-10-28T04:25:56.726Z,1603859156.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-10-28T04:25:56.722Z,1603859156.722 [MissionManager](DEBUG):
2020-10-28T04:25:56.728Z,1603859156.728 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-10-28T04:25:56.813Z,1603859156.813 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-10-28T04:25:56.814Z,1603859156.814 [Default:A.Wait](DEBUG): Construct Wait.
2020-10-28T04:25:56.815Z,1603859156.815 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-28T04:25:56.859Z,1603859156.859 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-10-28T04:25:56.861Z,1603859156.861 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-10-28T04:25:56.883Z,1603859156.883 [Default:E.Execute](DEBUG): Construct Execute.
2020-10-28T04:25:56.886Z,1603859156.886 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2020-10-28T04:25:56.893Z,1603859156.893 [controlThread](DEBUG): Component order: CycleStarter,CANONSampler,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,NAL9602,DAT,BPC1,AMEcho,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-10-28T04:25:56.909Z,1603859156.909 [CANONSampler](INFO): Powering down
2020-10-28T04:25:56.912Z,1603859156.912 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-28T04:25:57.029Z,1603859157.029 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2020-10-28T04:25:57.032Z,1603859157.032 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-10-28T04:25:57.033Z,1603859157.033 [DAT](INFO): Powering up
2020-10-28T04:25:57.033Z,1603859157.033 [DAT](DEBUG): Initializing DAT.
2020-10-28T04:25:57.069Z,1603859157.069 [Radio_Surface](INFO): Powering up
2020-10-28T04:25:57.081Z,1603859157.081 [AMEcho](INFO): Powering up
2020-10-28T04:25:57.083Z,1603859157.083 [AMEcho](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-10-28T04:25:57.090Z,1603859157.090 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-10-28T04:25:57.129Z,1603859157.129 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-10-28T04:25:57.191Z,1603859157.191 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-10-28T04:25:57.197Z,1603859157.197 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-10-28T04:25:57.198Z,1603859157.198 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-10-28T04:25:57.209Z,1603859157.209 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-10-28T04:25:57.210Z,1603859157.210 [MassServo](DEBUG): Initializing EZServoServo.
2020-10-28T04:25:57.221Z,1603859157.221 [MassServo](DEBUG): Initializing MassServo.
2020-10-28T04:25:57.222Z,1603859157.222 [RudderServo](DEBUG): Initializing EZServoServo.
2020-10-28T04:25:57.233Z,1603859157.233 [RudderServo](DEBUG): Initializing RudderServo.
2020-10-28T04:25:57.234Z,1603859157.234 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-28T04:25:57.245Z,1603859157.245 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-28T04:25:57.511Z,1603859157.511 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2020-10-28T04:25:57.511Z,1603859157.511 [DropWeight] Hardware Fault, FailCount= 1
2020-10-28T04:25:57.511Z,1603859157.511 [DropWeight](ERROR): Hardware Fault
2020-10-28T04:25:57.681Z,1603859157.681 [AMEcho](INFO): Powering down
2020-10-28T04:25:57.716Z,1603859157.716 [CommandLine](FAULT): Scheduling is paused
2020-10-28T04:25:57.716Z,1603859157.716 [CBIT](INFO): Critical error at 20201028T042557
2020-10-28T04:25:57.717Z,1603859157.717 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-10-28T04:25:57.719Z,1603859157.719 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-10-28T04:25:57.720Z,1603859157.720 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-10-28T04:25:58.165Z,1603859158.165 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-10-28T04:25:58.165Z,1603859158.165 [RudderServo](FAULT): Rudder failed to initialize
2020-10-28T04:25:58.165Z,1603859158.165 [RudderServo] Communications Fault, FailCount= 1
2020-10-28T04:25:58.165Z,1603859158.165 [RudderServo](ERROR): Communications Fault
2020-10-28T04:25:58.274Z,1603859158.274 [CBIT](INFO): Critical error at 20201028T042557
2020-10-28T04:25:58.276Z,1603859158.276 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-10-28T04:25:58.311Z,1603859158.311 [AMEcho](IMPORTANT): Setting 'enabled' to 0
2020-10-28T04:25:58.462Z,1603859158.462 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-10-28T04:25:58.462Z,1603859158.462 [RudderServo](INFO): Powering down
2020-10-28T04:25:59.122Z,1603859159.122 [RudderServo](DEBUG): Initializing EZServoServo.
2020-10-28T04:25:59.242Z,1603859159.242 [RudderServo](DEBUG): Initializing RudderServo.
2020-10-28T04:25:59.246Z,1603859159.246 [CBIT](INFO): Clearing failed state for component RudderServo
2020-10-28T04:25:59.246Z,1603859159.246 [RudderServo] No Fault, FailCount= 1
2020-10-28T04:26:00.413Z,1603859160.413 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:00.805Z,1603859160.805 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:01.209Z,1603859161.209 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:01.841Z,1603859161.841 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:02.049Z,1603859162.049 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:02.681Z,1603859162.681 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:02.917Z,1603859162.917 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:03.601Z,1603859163.601 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:03.797Z,1603859163.797 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:04.433Z,1603859164.433 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:04.653Z,1603859164.653 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:05.021Z,1603859165.021 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:05.441Z,1603859165.441 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:05.829Z,1603859165.829 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:06.233Z,1603859166.233 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:06.649Z,1603859166.649 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:07.041Z,1603859167.041 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:07.649Z,1603859167.649 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:07.837Z,1603859167.837 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:08.253Z,1603859168.253 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:08.861Z,1603859168.861 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:09.049Z,1603859169.049 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:09.361Z,1603859169.361 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-28T04:26:09.361Z,1603859169.361 [CTD_Seabird] Communications Fault, FailCount= 1
2020-10-28T04:26:09.361Z,1603859169.361 [CTD_Seabird](ERROR): Communications Fault
2020-10-28T04:26:09.465Z,1603859169.465 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:09.541Z,1603859169.541 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-28T04:26:09.765Z,1603859169.765 [CTD_Seabird](INFO): Powering down
2020-10-28T04:26:09.869Z,1603859169.869 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:10.501Z,1603859170.501 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:10.501Z,1603859170.501 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-10-28T04:26:10.501Z,1603859170.501 [AHRS_M2] Communications Fault, FailCount= 1
2020-10-28T04:26:10.501Z,1603859170.501 [AHRS_M2](ERROR): Communications Fault
2020-10-28T04:26:10.593Z,1603859170.593 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-10-28T04:26:10.601Z,1603859170.601 [AHRS_M2](INFO): Powering down
2020-10-28T04:26:10.735Z,1603859170.736 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-10-28T04:26:10.736Z,1603859170.736 [CTD_Seabird] No Fault, FailCount= 1
2020-10-28T04:26:11.109Z,1603859171.109 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-28T04:26:11.416Z,1603859171.416 [DAT](INFO): commRate: 800
2020-10-28T04:26:11.465Z,1603859171.465 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-10-28T04:26:11.465Z,1603859171.465 [AHRS_M2] No Fault, FailCount= 1
2020-10-28T04:26:11.813Z,1603859171.813 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-28T04:26:13.434Z,1603859173.434 [DAT](INFO): entering command mode
2020-10-28T04:26:13.839Z,1603859173.839 [DAT](INFO): setting verbose to 3
2020-10-28T04:26:14.243Z,1603859174.243 [DAT](INFO): set verbose to 3
2020-10-28T04:26:14.244Z,1603859174.244 [DAT](INFO): setting DatVerbose to 27440
2020-10-28T04:26:14.647Z,1603859174.647 [DAT](INFO): set DatVerbose to 27440
2020-10-28T04:26:14.648Z,1603859174.648 [DAT](INFO): setting transmit power to 8
2020-10-28T04:26:15.153Z,1603859175.153 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:15.159Z,1603859175.159 [DAT](INFO): set transmit power to 8
2020-10-28T04:26:15.160Z,1603859175.160 [DAT](INFO): setting local address to 4
2020-10-28T04:26:15.557Z,1603859175.557 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:15.563Z,1603859175.563 [DAT](INFO): set local address to 4
2020-10-28T04:26:15.961Z,1603859175.961 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:16.353Z,1603859176.353 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:16.769Z,1603859176.769 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:17.161Z,1603859177.161 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:17.569Z,1603859177.569 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:17.969Z,1603859177.969 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:18.385Z,1603859178.385 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:19.005Z,1603859179.005 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:19.209Z,1603859179.209 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:19.613Z,1603859179.613 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:20.001Z,1603859180.001 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:20.393Z,1603859180.393 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:20.809Z,1603859180.809 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:21.213Z,1603859181.213 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:21.610Z,1603859181.610 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:22.021Z,1603859182.021 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:22.413Z,1603859182.413 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:22.829Z,1603859182.829 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:23.237Z,1603859183.237 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:23.637Z,1603859183.637 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:24.029Z,1603859184.029 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:24.161Z,1603859184.161 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-28T04:26:24.161Z,1603859184.161 [CTD_Seabird] Communications Fault, FailCount= 2
2020-10-28T04:26:24.161Z,1603859184.161 [CTD_Seabird](ERROR): Communications Fault
2020-10-28T04:26:24.445Z,1603859184.445 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:24.450Z,1603859184.450 [NAL9602](INFO): Powering up NAL9602
2020-10-28T04:26:24.511Z,1603859184.511 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-28T04:26:24.565Z,1603859184.565 [CTD_Seabird](INFO): Powering down
2020-10-28T04:26:24.849Z,1603859184.849 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:24.849Z,1603859184.849 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-10-28T04:26:24.849Z,1603859184.849 [AHRS_M2] Communications Fault, FailCount= 2
2020-10-28T04:26:24.849Z,1603859184.849 [AHRS_M2](ERROR): Communications Fault
2020-10-28T04:26:24.933Z,1603859184.933 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-10-28T04:26:25.145Z,1603859185.145 [AHRS_M2](INFO): Powering down
2020-10-28T04:26:25.621Z,1603859185.621 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-10-28T04:26:25.621Z,1603859185.621 [CTD_Seabird] No Fault, FailCount= 2
2020-10-28T04:26:25.910Z,1603859185.910 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-28T04:26:26.029Z,1603859186.029 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-10-28T04:26:26.029Z,1603859186.029 [AHRS_M2] No Fault, FailCount= 2
2020-10-28T04:26:26.357Z,1603859186.357 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-28T04:26:29.701Z,1603859189.701 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:30.090Z,1603859190.090 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:30.505Z,1603859190.505 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:30.909Z,1603859190.909 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:31.313Z,1603859191.313 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:31.749Z,1603859191.749 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:32.129Z,1603859192.129 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:32.513Z,1603859192.513 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:32.929Z,1603859192.929 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:33.337Z,1603859193.337 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:33.737Z,1603859193.737 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:34.141Z,1603859194.141 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:34.541Z,1603859194.541 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:34.949Z,1603859194.949 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:35.353Z,1603859195.353 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:35.358Z,1603859195.358 [NAL9602](INFO): NAL9602 initialized
2020-10-28T04:26:35.769Z,1603859195.769 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:36.165Z,1603859196.165 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:36.565Z,1603859196.565 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:36.969Z,1603859196.969 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:37.373Z,1603859197.373 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:37.777Z,1603859197.777 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:38.185Z,1603859198.185 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:38.589Z,1603859198.589 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:38.961Z,1603859198.961 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-28T04:26:38.961Z,1603859198.961 [CTD_Seabird] Communications Fault, FailCount= 3
2020-10-28T04:26:38.961Z,1603859198.961 [CTD_Seabird](ERROR): Communications Fault
2020-10-28T04:26:38.985Z,1603859198.985 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:39.067Z,1603859199.067 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-28T04:26:39.068Z,1603859199.068 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2020-10-28T04:26:39.365Z,1603859199.365 [CTD_Seabird](INFO): Powering down
2020-10-28T04:26:39.394Z,1603859199.394 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:39.395Z,1603859199.395 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-10-28T04:26:39.395Z,1603859199.395 [AHRS_M2] Communications Fault, FailCount= 3
2020-10-28T04:26:39.395Z,1603859199.395 [AHRS_M2](ERROR): Communications Fault
2020-10-28T04:26:39.437Z,1603859199.437 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-10-28T04:26:39.689Z,1603859199.689 [AHRS_M2](INFO): Powering down
2020-10-28T04:26:40.520Z,1603859200.520 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-10-28T04:26:40.520Z,1603859200.520 [AHRS_M2] No Fault, FailCount= 3
2020-10-28T04:26:40.901Z,1603859200.901 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-28T04:26:44.225Z,1603859204.225 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:44.645Z,1603859204.645 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:45.041Z,1603859205.041 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:45.437Z,1603859205.437 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:45.861Z,1603859205.861 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:46.253Z,1603859206.253 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:46.657Z,1603859206.657 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:47.069Z,1603859207.069 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:47.477Z,1603859207.477 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:47.861Z,1603859207.861 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:48.289Z,1603859208.289 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:48.693Z,1603859208.693 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:49.081Z,1603859209.081 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:49.501Z,1603859209.501 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:49.909Z,1603859209.909 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:50.281Z,1603859210.281 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:50.709Z,1603859210.709 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:51.105Z,1603859211.105 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:51.497Z,1603859211.497 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:51.921Z,1603859211.921 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:52.317Z,1603859212.317 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:52.705Z,1603859212.705 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:53.121Z,1603859213.121 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:53.541Z,1603859213.541 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:53.921Z,1603859213.921 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:53.921Z,1603859213.921 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-10-28T04:26:53.921Z,1603859213.921 [AHRS_M2] Communications Fault, FailCount= 4
2020-10-28T04:26:53.921Z,1603859213.921 [AHRS_M2](ERROR): Communications Fault
2020-10-28T04:26:53.979Z,1603859213.979 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-10-28T04:26:54.237Z,1603859214.237 [AHRS_M2](INFO): Powering down
2020-10-28T04:26:55.074Z,1603859215.074 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-10-28T04:26:55.074Z,1603859215.074 [AHRS_M2] No Fault, FailCount= 4
2020-10-28T04:26:55.450Z,1603859215.450 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-28T04:26:58.769Z,1603859218.769 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:59.197Z,1603859219.197 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:59.601Z,1603859219.601 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:26:59.981Z,1603859219.981 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:00.385Z,1603859220.385 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:00.789Z,1603859220.789 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:01.193Z,1603859221.193 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:01.597Z,1603859221.597 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:02.029Z,1603859222.029 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:02.401Z,1603859222.401 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:02.809Z,1603859222.809 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:03.237Z,1603859223.237 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:03.248Z,1603859223.248 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2020-10-28T04:27:03.617Z,1603859223.617 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:04.045Z,1603859224.045 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:04.437Z,1603859224.437 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:04.829Z,1603859224.829 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:05.245Z,1603859225.245 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:05.661Z,1603859225.661 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:06.041Z,1603859226.041 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:06.445Z,1603859226.445 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:06.861Z,1603859226.861 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:07.253Z,1603859227.253 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:07.657Z,1603859227.657 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:08.069Z,1603859228.069 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:08.465Z,1603859228.465 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-28T04:27:08.465Z,1603859228.465 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-10-28T04:27:08.465Z,1603859228.465 [AHRS_M2] Communications Fault, FailCount= 5
2020-10-28T04:27:08.465Z,1603859228.465 [AHRS_M2](ERROR): Communications Fault
2020-10-28T04:27:08.500Z,1603859228.500 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-10-28T04:27:08.501Z,1603859228.501 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2020-10-28T04:27:08.774Z,1603859228.774 [AHRS_M2](INFO): Powering down
2020-10-28T04:27:08.863Z,1603859228.863 [CBIT](INFO): Critical error at 20201028T042708
2020-10-28T04:27:12.835Z,1603859232.835 [SBIT](IMPORTANT): Beginning Startup BIT
2020-10-28T04:27:12.839Z,1603859232.839 [CBIT](IMPORTANT): Beginning ground fault scan
2020-10-28T04:27:23.786Z,1603859243.786 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.018833
CHAN A1 (24V): 0.000150
CHAN A2 (12V): -0.005181
CHAN A3 (5V): -0.002407
CHAN B0 (3.3V): -0.001608
CHAN B1 (3.15aV): -0.001390
CHAN B2 (3.15bV): -0.001399
CHAN B3 (GND): -0.000179
OPEN: 0.006734
Full Scale Calc: 4.765 mA, -1.589 mA
2020-10-28T04:27:56.469Z,1603859276.469 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2020-10-28T04:27:56.469Z,1603859276.469 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-10-28T04:27:56.469Z,1603859276.469 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-28T04:27:56.562Z,1603859276.562 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-28T04:27:56.993Z,1603859276.993 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-28T04:27:56.993Z,1603859276.993 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-10-28T04:28:06.576Z,1603859286.576 [SBIT](IMPORTANT): SBIT PASSED
2020-10-28T04:28:06.576Z,1603859286.576 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-10-28T04:28:06.577Z,1603859286.577 [SBIT](IMPORTANT): AMEcho.enabled=0 bool;
2020-10-28T04:28:06.577Z,1603859286.577 [SBIT](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;
2020-10-28T04:28:06.577Z,1603859286.577 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;
2020-10-28T04:28:06.578Z,1603859286.578 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;
2020-10-28T04:28:06.578Z,1603859286.578 [SBIT](IMPORTANT): CANONSampler.rotateOnly=0 bool;
2020-10-28T04:28:06.578Z,1603859286.578 [SBIT](IMPORTANT): CANONSampler.simulateHardware=0 bool;
2020-10-28T04:28:06.578Z,1603859286.578 [SBIT](IMPORTANT): CBIT.gf24Offset=148.5 microampere;
2020-10-28T04:28:06.578Z,1603859286.578 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;
2020-10-28T04:28:06.578Z,1603859286.578 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;
2020-10-28T04:28:06.578Z,1603859286.578 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2020-10-28T04:28:06.578Z,1603859286.578 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=25 minute;
2020-10-28T04:28:06.578Z,1603859286.578 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;
2020-10-28T04:28:06.578Z,1603859286.578 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;
2020-10-28T04:28:06.579Z,1603859286.579 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;
2020-10-28T04:28:06.579Z,1603859286.579 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=280 cubic_centimeter;
2020-10-28T04:28:06.579Z,1603859286.579 [SBIT](IMPORTANT): VerticalControl.massDefault=14 millimeter;
2020-10-28T04:28:06.977Z,1603859286.977 [MissionManager](IMPORTANT): Started mission Startup
2020-10-28T04:28:06.978Z,1603859286.978 [Startup] Running Loop=1
2020-10-28T04:28:06.978Z,1603859286.978 [Startup](DEBUG): Aggregate::initialize Startup
2020-10-28T04:28:06.978Z,1603859286.978 [Startup:A.GoToSurface] Running Loop=1
2020-10-28T04:28:06.978Z,1603859286.978 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-10-28T04:28:06.979Z,1603859286.979 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-10-28T04:28:06.979Z,1603859286.979 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-10-28T04:28:06.979Z,1603859286.979 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-10-28T04:28:06.980Z,1603859286.980 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-10-28T04:28:06.980Z,1603859286.980 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-10-28T04:28:06.982Z,1603859286.982 [Startup:StartupSatComms] Running Loop=1
2020-10-28T04:28:06.982Z,1603859286.982 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-10-28T04:28:06.982Z,1603859286.982 [Startup:StartupSatComms:A] Running Loop=1
2020-10-28T04:28:07.398Z,1603859287.398 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-10-28T04:28:50.184Z,1603859330.184 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004987
2020-10-28T04:29:07.162Z,1603859347.162 [Startup:StartupSatComms:A](INFO): Timed out from 2020-10-28T04:28:06.0Z
2020-10-28T04:29:07.162Z,1603859347.162 [Startup:StartupSatComms:A] Stopped
2020-10-28T04:29:07.162Z,1603859347.162 [Startup:StartupSatComms:B] Running Loop=1
2020-10-28T04:29:07.567Z,1603859347.567 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-10-28T04:29:09.200Z,1603859349.200 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-10-28T04:29:09.201Z,1603859349.201 [CTD_Seabird] No Fault, FailCount= 3
2020-10-28T04:29:09.397Z,1603859349.397 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-28T04:29:14.342Z,1603859354.342 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20201027T233056/Courier0088.lzma
2020-10-28T04:29:15.343Z,1603859355.343 [DataOverHttps](INFO): Moved sent file to Logs/20201027T233056/Courier0088.lzma.bak
2020-10-28T04:29:15.343Z,1603859355.343 [DataOverHttps](INFO): SBD MOMSN=12994235
2020-10-28T04:29:22.449Z,1603859362.449 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-28T04:29:22.449Z,1603859362.449 [CTD_Seabird] Communications Fault, FailCount= 1
2020-10-28T04:29:22.449Z,1603859362.449 [CTD_Seabird](ERROR): Communications Fault
2020-10-28T04:29:22.532Z,1603859362.532 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-28T04:29:22.853Z,1603859362.853 [CTD_Seabird](INFO): Powering down
2020-10-28T04:29:24.141Z,1603859364.141 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-10-28T04:29:24.141Z,1603859364.141 [CTD_Seabird] No Fault, FailCount= 1
2020-10-28T04:29:24.197Z,1603859364.197 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-28T04:29:30.468Z,1603859370.468 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20201028T042545/Courier0000.lzma
2020-10-28T04:29:31.471Z,1603859371.471 [DataOverHttps](INFO): Moved sent file to Logs/20201028T042545/Courier0000.lzma.bak
2020-10-28T04:29:31.471Z,1603859371.471 [DataOverHttps](INFO): SBD MOMSN=12994238
2020-10-28T04:29:37.257Z,1603859377.257 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-28T04:29:37.257Z,1603859377.257 [CTD_Seabird] Communications Fault, FailCount= 2
2020-10-28T04:29:37.257Z,1603859377.257 [CTD_Seabird](ERROR): Communications Fault
2020-10-28T04:29:37.513Z,1603859377.513 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-28T04:29:37.661Z,1603859377.661 [CTD_Seabird](INFO): Powering down
2020-10-28T04:29:38.683Z,1603859378.683 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-10-28T04:29:38.683Z,1603859378.683 [CTD_Seabird] No Fault, FailCount= 2
2020-10-28T04:29:39.005Z,1603859379.005 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-28T04:29:39.530Z,1603859379.530 [DataOverHttps](IMPORTANT): SBD MTMSN=20201028T042729
2020-10-28T04:29:46.760Z,1603859386.760 [DataOverHttps](INFO): Sending 405 bytes from file Logs/20201027T233056/Express0089.lzma
2020-10-28T04:29:46.777Z,1603859386.777 [DataOverHttps](INFO): Received command:onESPclient brent -- slot 40=>:dry
2020-10-28T04:29:46.861Z,1603859386.861 [CommandLine](IMPORTANT): got command exit
2020-10-28T04:29:47.865Z,1603859387.865 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-28T04:29:47.865Z,1603859387.865 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:47.881Z,1603859387.881 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2020-10-28T04:29:47.881Z,1603859387.881 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:47.882Z,1603859387.882 [CommandLine](INFO): Join timeout helper Thread ID is 962
2020-10-28T04:29:47.899Z,1603859387.899 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-10-28T04:29:47.899Z,1603859387.899 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:47.900Z,1603859387.900 [NavChartDb](INFO): Join timeout helper Thread ID is 963
2020-10-28T04:29:47.927Z,1603859387.927 [DataOverHttps](INFO): Moved sent file to Logs/20201027T233056/Express0089.lzma.bak
2020-10-28T04:29:47.928Z,1603859387.928 [DataOverHttps](INFO): SBD MOMSN=12994245
2020-10-28T04:29:48.181Z,1603859388.181 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-28T04:29:48.181Z,1603859388.181 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:48.189Z,1603859388.189 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-10-28T04:29:48.189Z,1603859388.189 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:48.189Z,1603859388.189 [Radio_Surface](INFO): Join timeout helper Thread ID is 964
2020-10-28T04:29:48.569Z,1603859388.569 [Radio_Surface](INFO): Powering down
2020-10-28T04:29:48.573Z,1603859388.573 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-28T04:29:48.574Z,1603859388.574 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:48.589Z,1603859388.589 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-10-28T04:29:48.589Z,1603859388.589 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:48.589Z,1603859388.589 [Onboard](INFO): Join timeout helper Thread ID is 965
2020-10-28T04:29:49.884Z,1603859389.884 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-10-28T04:29:51.753Z,1603859391.753 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-28T04:29:51.753Z,1603859391.753 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:51.757Z,1603859391.757 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-10-28T04:29:51.757Z,1603859391.757 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:51.757Z,1603859391.757 [DataOverHttps](INFO): Join timeout helper Thread ID is 966
2020-10-28T04:29:51.889Z,1603859391.889 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-28T04:29:51.889Z,1603859391.889 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:51.898Z,1603859391.898 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-10-28T04:29:51.898Z,1603859391.898 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:51.898Z,1603859391.898 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 967
2020-10-28T04:29:52.057Z,1603859392.057 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-28T04:29:52.057Z,1603859392.057 [CTD_Seabird] Communications Fault, FailCount= 3
2020-10-28T04:29:52.057Z,1603859392.057 [CTD_Seabird](ERROR): Communications Fault
2020-10-28T04:29:52.277Z,1603859392.277 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-28T04:29:52.277Z,1603859392.277 [WetLabsBB2FL](INFO): Powering down
2020-10-28T04:29:52.278Z,1603859392.278 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:52.286Z,1603859392.286 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-10-28T04:29:52.286Z,1603859392.286 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:52.286Z,1603859392.286 [CTD_Seabird](INFO): Join timeout helper Thread ID is 968
2020-10-28T04:29:52.440Z,1603859392.440 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-28T04:29:52.440Z,1603859392.440 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2020-10-28T04:29:52.461Z,1603859392.461 [CTD_Seabird](INFO): Powering down
2020-10-28T04:29:52.593Z,1603859392.593 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-28T04:29:52.593Z,1603859392.593 [CTD_Seabird](INFO): Powering down
2020-10-28T04:29:52.605Z,1603859392.605 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:52.610Z,1603859392.610 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-10-28T04:29:52.610Z,1603859392.610 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:52.610Z,1603859392.610 [logger](INFO): Join timeout helper Thread ID is 969
2020-10-28T04:29:52.625Z,1603859392.625 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-28T04:29:52.625Z,1603859392.625 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:52.630Z,1603859392.630 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-10-28T04:29:52.630Z,1603859392.630 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:52.630Z,1603859392.630 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-10-28T04:29:52.630Z,1603859392.630 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:52.630Z,1603859392.630 [controlThread](INFO): Join timeout helper Thread ID is 970
2020-10-28T04:29:52.797Z,1603859392.797 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-28T04:29:52.797Z,1603859392.797 [controlThread](DEBUG): Uninitializing ControlThread
2020-10-28T04:29:52.799Z,1603859392.799 [AHRS_M2](INFO): Powering down
2020-10-28T04:29:52.800Z,1603859392.800 [NAL9602](INFO): Powering down
2020-10-28T04:29:52.801Z,1603859392.801 [DAT](INFO): Powering down
2020-10-28T04:29:52.922Z,1603859392.922 [AMEcho](INFO): Powering down
2020-10-28T04:29:52.923Z,1603859392.923 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-10-28T04:29:52.924Z,1603859392.924 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-10-28T04:29:52.924Z,1603859392.924 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-10-28T04:29:52.925Z,1603859392.925 [MissionManager](INFO): Uninitializing Mission Default
2020-10-28T04:29:52.927Z,1603859392.927 [MissionManager](INFO): Uninitializing Mission Startup
2020-10-28T04:29:52.927Z,1603859392.927 [Startup] Stopped
2020-10-28T04:29:52.927Z,1603859392.927 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-10-28T04:29:52.928Z,1603859392.928 [Startup:A.GoToSurface] Stopped
2020-10-28T04:29:52.928Z,1603859392.928 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-10-28T04:29:52.928Z,1603859392.928 [Startup:StartupSatComms] Stopped
2020-10-28T04:29:52.928Z,1603859392.928 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-10-28T04:29:52.928Z,1603859392.928 [Startup:StartupSatComms:B] Stopped
2020-10-28T04:29:52.929Z,1603859392.929 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-10-28T04:29:52.930Z,1603859392.930 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-10-28T04:29:52.930Z,1603859392.930 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-10-28T04:29:52.930Z,1603859392.930 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-10-28T04:29:52.930Z,1603859392.930 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-10-28T04:29:52.930Z,1603859392.930 [BuoyancyServo](INFO): Powering down
2020-10-28T04:29:52.945Z,1603859392.945 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-10-28T04:29:52.945Z,1603859392.945 [ElevatorServo](INFO): Powering down
2020-10-28T04:29:52.946Z,1603859392.946 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-10-28T04:29:52.946Z,1603859392.946 [MassServo](INFO): Powering down
2020-10-28T04:29:52.947Z,1603859392.947 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-10-28T04:29:52.947Z,1603859392.947 [RudderServo](INFO): Powering down
2020-10-28T04:29:52.947Z,1603859392.947 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-28T04:29:52.947Z,1603859392.947 [ThrusterServo](INFO): Powering down
2020-10-28T04:29:52.948Z,1603859392.948 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-10-28T04:29:52.948Z,1603859392.948 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-10-28T04:29:52.949Z,1603859392.949 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-10-28T04:29:52.949Z,1603859392.949 [CBIT](DEBUG): Powering off loads.
2020-10-28T04:29:52.961Z,1603859392.961 [CBIT](DEBUG): Disabling WDT.
2020-10-28T04:29:52.973Z,1603859392.973 [CBIT](DEBUG): Opening all GF detection circuits.
2020-10-28T04:29:52.974Z,1603859392.974 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:53.017Z,1603859393.017 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:53.023Z,1603859393.023 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:53.092Z,1603859393.092 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:53.093Z,1603859393.093 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:53.098Z,1603859393.098 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:53.150Z,1603859393.150 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-28T04:29:53.209Z,1603859393.209 [logger ThreadHandler](INFO): Thread cancelled.