2020-07-17T20:01:38.686Z,1595016098.686 [Supervisor](DEBUG): Initializing supervisor.
2020-07-17T20:01:38.689Z,1595016098.689 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-07-17T20:01:38.690Z,1595016098.690 [SyncHandler](INFO): Protected caller Thread ID is 820
2020-07-17T20:01:38.690Z,1595016098.690 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-07-17T20:01:38.691Z,1595016098.691 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-07-17T20:01:38.691Z,1595016098.691 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 821
2020-07-17T20:01:38.694Z,1595016098.694 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-07-17T20:01:38.707Z,1595016098.707 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-07-17T20:01:38.708Z,1595016098.708 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-07-17T20:01:38.708Z,1595016098.708 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 822
2020-07-17T20:01:38.709Z,1595016098.709 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-07-17T20:01:38.710Z,1595016098.710 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-07-17T20:01:38.710Z,1595016098.710 [logger ThreadHandler](INFO): Protected caller Thread ID is 823
2020-07-17T20:01:38.712Z,1595016098.712 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-07-17T20:01:38.712Z,1595016098.712 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-07-17T20:01:38.716Z,1595016098.716 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-07-17T20:01:39.018Z,1595016099.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-07-17T20:01:39.020Z,1595016099.020 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-07-17T20:01:39.594Z,1595016099.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-07-17T20:01:39.596Z,1595016099.596 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-07-17T20:01:39.749Z,1595016099.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-07-17T20:01:39.751Z,1595016099.751 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-07-17T20:01:40.190Z,1595016100.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-07-17T20:01:40.192Z,1595016100.192 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-07-17T20:01:40.286Z,1595016100.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-07-17T20:01:40.288Z,1595016100.288 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-07-17T20:01:40.368Z,1595016100.368 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-07-17T20:01:40.655Z,1595016100.655 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-07-17T20:01:40.656Z,1595016100.656 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-07-17T20:01:40.798Z,1595016100.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-07-17T20:01:40.799Z,1595016100.799 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-07-17T20:01:40.900Z,1595016100.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-07-17T20:01:40.901Z,1595016100.901 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-07-17T20:01:41.095Z,1595016101.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-07-17T20:01:41.097Z,1595016101.097 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-07-17T20:01:41.608Z,1595016101.608 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-07-17T20:01:41.609Z,1595016101.609 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-07-17T20:01:42.018Z,1595016102.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-07-17T20:01:42.020Z,1595016102.020 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-07-17T20:01:42.138Z,1595016102.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-07-17T20:01:42.139Z,1595016102.139 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-07-17T20:01:43.102Z,1595016103.102 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-07-17T20:01:43.104Z,1595016103.104 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-07-17T20:01:43.304Z,1595016103.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-07-17T20:01:43.306Z,1595016103.306 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-07-17T20:01:43.525Z,1595016103.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-07-17T20:01:43.526Z,1595016103.526 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2020-07-17T20:01:43.531Z,1595016103.531 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2020-07-17T20:01:43.623Z,1595016103.623 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2020-07-17T20:01:43.761Z,1595016103.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2020-07-17T20:01:43.847Z,1595016103.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2020-07-17T20:01:43.930Z,1595016103.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2020-07-17T20:01:44.036Z,1595016104.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2020-07-17T20:01:44.214Z,1595016104.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2020-07-17T20:01:44.440Z,1595016104.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-07-17T20:01:44.440Z,1595016104.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2020-07-17T20:01:44.550Z,1595016104.550 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2020-07-17T20:01:44.643Z,1595016104.643 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2020-07-17T20:01:44.747Z,1595016104.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2020-07-17T20:01:44.840Z,1595016104.840 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-07-17T20:01:44.851Z,1595016104.851 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-07-17T20:01:44.945Z,1595016104.945 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-07-17T20:01:44.947Z,1595016104.947 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-07-17T20:01:44.977Z,1595016104.977 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-07-17T20:01:44.977Z,1595016104.977 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-07-17T20:01:45.032Z,1595016105.032 [DepthRateCalculator] Loaded
2020-07-17T20:01:45.032Z,1595016105.032 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-07-17T20:01:45.038Z,1595016105.038 [PitchRateCalculator] Loaded
2020-07-17T20:01:45.038Z,1595016105.038 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-07-17T20:01:45.053Z,1595016105.053 [SpeedCalculator] Loaded
2020-07-17T20:01:45.053Z,1595016105.053 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-07-17T20:01:45.074Z,1595016105.074 [TempGradientCalculator] Loaded
2020-07-17T20:01:45.074Z,1595016105.074 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-07-17T20:01:45.080Z,1595016105.080 [YawRateCalculator] Loaded
2020-07-17T20:01:45.080Z,1595016105.080 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-07-17T20:01:45.118Z,1595016105.118 [ElevatorOffsetCalculator] Loaded
2020-07-17T20:01:45.118Z,1595016105.118 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-07-17T20:01:45.119Z,1595016105.119 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-07-17T20:01:45.119Z,1595016105.119 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-07-17T20:01:45.181Z,1595016105.181 [VerticalControl](DEBUG): Construct VerticalControl.
2020-07-17T20:01:45.277Z,1595016105.277 [VerticalControl] Loaded
2020-07-17T20:01:45.278Z,1595016105.278 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-07-17T20:01:45.278Z,1595016105.278 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-07-17T20:01:45.355Z,1595016105.355 [HorizontalControl] Loaded
2020-07-17T20:01:45.355Z,1595016105.355 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-07-17T20:01:45.356Z,1595016105.356 [SpeedControl](DEBUG): Construct SpeedControl.
2020-07-17T20:01:45.361Z,1595016105.361 [SpeedControl] Loaded
2020-07-17T20:01:45.361Z,1595016105.361 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-07-17T20:01:45.362Z,1595016105.362 [LoopControl](DEBUG): Construct LoopControl.
2020-07-17T20:01:45.362Z,1595016105.362 [LoopControl] Loaded
2020-07-17T20:01:45.363Z,1595016105.363 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-07-17T20:01:45.363Z,1595016105.363 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-07-17T20:01:45.364Z,1595016105.364 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-07-17T20:01:45.495Z,1595016105.495 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-07-17T20:01:45.495Z,1595016105.495 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-07-17T20:01:45.515Z,1595016105.515 [NavChart] Loaded
2020-07-17T20:01:45.515Z,1595016105.515 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-07-17T20:01:45.519Z,1595016105.519 [UniversalFixResidualReporter] Loaded
2020-07-17T20:01:45.519Z,1595016105.519 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-07-17T20:01:45.520Z,1595016105.520 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-07-17T20:01:45.520Z,1595016105.520 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-07-17T20:01:45.644Z,1595016105.644 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-07-17T20:01:45.645Z,1595016105.645 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-07-17T20:01:46.203Z,1595016106.203 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-07-17T20:01:46.207Z,1595016106.207 [AHRS_M2](INFO): created writer for : platform_orientation
2020-07-17T20:01:46.209Z,1595016106.209 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-07-17T20:01:46.214Z,1595016106.214 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-07-17T20:01:46.215Z,1595016106.215 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-07-17T20:01:46.220Z,1595016106.220 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-07-17T20:01:46.220Z,1595016106.220 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-07-17T20:01:46.225Z,1595016106.225 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-07-17T20:01:46.400Z,1595016106.400 [AHRS_M2] Loaded
2020-07-17T20:01:46.401Z,1595016106.401 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-07-17T20:01:46.547Z,1595016106.547 [DataOverHttps] Loaded
2020-07-17T20:01:46.547Z,1595016106.547 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-07-17T20:01:46.548Z,1595016106.548 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408564E0
2020-07-17T20:01:46.548Z,1595016106.548 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 906
2020-07-17T20:01:46.577Z,1595016106.577 [Depth_Keller] Loaded
2020-07-17T20:01:46.578Z,1595016106.578 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-07-17T20:01:46.582Z,1595016106.582 [DropWeight] Loaded
2020-07-17T20:01:46.583Z,1595016106.583 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-07-17T20:01:46.643Z,1595016106.643 [DVL_micro] Loaded
2020-07-17T20:01:46.644Z,1595016106.644 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2020-07-17T20:01:46.753Z,1595016106.753 [NAL9602] Loaded
2020-07-17T20:01:46.753Z,1595016106.753 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-07-17T20:01:46.800Z,1595016106.800 [Onboard] Loaded
2020-07-17T20:01:46.801Z,1595016106.801 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-07-17T20:01:46.802Z,1595016106.802 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408864E0
2020-07-17T20:01:46.802Z,1595016106.802 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 907
2020-07-17T20:01:46.821Z,1595016106.821 [Radio_Surface] Loaded
2020-07-17T20:01:46.821Z,1595016106.821 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-07-17T20:01:46.822Z,1595016106.822 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2020-07-17T20:01:46.822Z,1595016106.822 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 908
2020-07-17T20:01:48.531Z,1595016108.531 [BPC1] Loaded
2020-07-17T20:01:48.531Z,1595016108.531 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-07-17T20:01:48.531Z,1595016108.531 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-07-17T20:01:48.532Z,1595016108.532 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-07-17T20:01:49.203Z,1595016109.203 [BuoyancyServo] Loaded
2020-07-17T20:01:49.204Z,1595016109.204 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-07-17T20:01:49.232Z,1595016109.232 [ElevatorServo] Loaded
2020-07-17T20:01:49.232Z,1595016109.232 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-07-17T20:01:49.259Z,1595016109.259 [MassServo] Loaded
2020-07-17T20:01:49.260Z,1595016109.260 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-07-17T20:01:49.286Z,1595016109.286 [RudderServo] Loaded
2020-07-17T20:01:49.286Z,1595016109.286 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-07-17T20:01:49.312Z,1595016109.312 [ThrusterServo] Loaded
2020-07-17T20:01:49.312Z,1595016109.312 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-07-17T20:01:49.313Z,1595016109.313 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-07-17T20:01:49.313Z,1595016109.313 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-07-17T20:01:49.435Z,1595016109.435 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-07-17T20:01:49.436Z,1595016109.436 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-07-17T20:01:49.476Z,1595016109.476 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-07-17T20:01:49.477Z,1595016109.477 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-07-17T20:01:49.804Z,1595016109.804 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-07-17T20:01:49.805Z,1595016109.805 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-07-17T20:01:50.087Z,1595016110.087 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-07-17T20:01:50.092Z,1595016110.092 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-07-17T20:01:50.093Z,1595016110.093 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-07-17T20:01:50.097Z,1595016110.097 [CTD_Seabird](INFO): created writer for : depth
2020-07-17T20:01:50.098Z,1595016110.098 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-07-17T20:01:50.103Z,1595016110.103 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-07-17T20:01:50.103Z,1595016110.103 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-07-17T20:01:50.109Z,1595016110.109 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-07-17T20:01:50.109Z,1595016110.109 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-07-17T20:01:50.114Z,1595016110.114 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-07-17T20:01:50.115Z,1595016110.115 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-07-17T20:01:50.120Z,1595016110.120 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-07-17T20:01:50.120Z,1595016110.120 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-07-17T20:01:50.125Z,1595016110.125 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-07-17T20:01:50.152Z,1595016110.152 [CTD_Seabird] Loaded
2020-07-17T20:01:50.152Z,1595016110.152 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-07-17T20:01:50.153Z,1595016110.153 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AC24E0
2020-07-17T20:01:50.153Z,1595016110.153 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 909
2020-07-17T20:01:50.202Z,1595016110.202 [ESPComponent] Loaded
2020-07-17T20:01:50.202Z,1595016110.202 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-07-17T20:01:50.232Z,1595016110.232 [PAR_Licor] Loaded
2020-07-17T20:01:50.232Z,1595016110.232 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-07-17T20:01:50.254Z,1595016110.254 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-07-17T20:01:50.254Z,1595016110.254 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-07-17T20:01:50.258Z,1595016110.258 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-07-17T20:01:50.258Z,1595016110.258 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-07-17T20:01:50.262Z,1595016110.262 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-07-17T20:01:50.262Z,1595016110.262 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-07-17T20:01:50.266Z,1595016110.266 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-07-17T20:01:50.267Z,1595016110.267 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-07-17T20:01:50.271Z,1595016110.271 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-07-17T20:01:50.271Z,1595016110.271 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-07-17T20:01:50.275Z,1595016110.275 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-07-17T20:01:50.275Z,1595016110.275 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-07-17T20:01:50.279Z,1595016110.279 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-07-17T20:01:50.279Z,1595016110.279 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-07-17T20:01:50.283Z,1595016110.283 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-07-17T20:01:50.288Z,1595016110.288 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-07-17T20:01:50.288Z,1595016110.288 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-17T20:01:50.289Z,1595016110.289 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-17T20:01:50.293Z,1595016110.293 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-17T20:01:50.293Z,1595016110.293 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-17T20:01:50.297Z,1595016110.297 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-17T20:01:50.297Z,1595016110.297 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-17T20:01:50.301Z,1595016110.301 [WetLabsBB2FL] Loaded
2020-07-17T20:01:50.302Z,1595016110.302 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-07-17T20:01:50.303Z,1595016110.303 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AF24E0
2020-07-17T20:01:50.303Z,1595016110.303 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 910
2020-07-17T20:01:50.304Z,1595016110.304 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-07-17T20:01:50.304Z,1595016110.304 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-07-17T20:01:50.459Z,1595016110.459 [SBIT](DEBUG): Construct Startup Built In Test.
2020-07-17T20:01:50.470Z,1595016110.470 [SBIT] Loaded
2020-07-17T20:01:50.470Z,1595016110.470 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-07-17T20:01:50.471Z,1595016110.471 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-07-17T20:01:50.483Z,1595016110.483 [IBIT] Loaded
2020-07-17T20:01:50.483Z,1595016110.483 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-07-17T20:01:50.486Z,1595016110.486 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-07-17T20:01:50.623Z,1595016110.623 [CBIT] Loaded
2020-07-17T20:01:50.623Z,1595016110.623 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-07-17T20:01:50.623Z,1595016110.623 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-07-17T20:01:50.627Z,1595016110.627 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-07-17T20:01:50.628Z,1595016110.628 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-07-17T20:01:50.634Z,1595016110.634 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-07-17T20:01:50.635Z,1595016110.635 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B5D4E0
2020-07-17T20:01:50.636Z,1595016110.636 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 911
2020-07-17T20:01:50.640Z,1595016110.640 [Supervisor](INFO): Main Thread ID is 816
2020-07-17T20:01:50.640Z,1595016110.640 [Supervisor](DEBUG): Running supervisor.
2020-07-17T20:01:50.641Z,1595016110.641 [CommandLine ThreadHandler](INFO): Handler Thread ID is 912
2020-07-17T20:01:50.643Z,1595016110.643 [controlThread ThreadHandler](INFO): Handler Thread ID is 913
2020-07-17T20:01:50.644Z,1595016110.644 [controlThread](DEBUG): Initializing ControlThread
2020-07-17T20:01:50.645Z,1595016110.645 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-07-17T20:01:50.645Z,1595016110.645 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-07-17T20:01:50.645Z,1595016110.645 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-07-17T20:01:50.646Z,1595016110.646 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-07-17T20:01:50.646Z,1595016110.646 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-07-17T20:01:50.647Z,1595016110.647 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-07-17T20:01:50.647Z,1595016110.647 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-07-17T20:01:50.649Z,1595016110.649 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-07-17T20:01:50.649Z,1595016110.649 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-07-17T20:01:50.650Z,1595016110.650 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-07-17T20:01:50.651Z,1595016110.651 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-07-17T20:01:50.651Z,1595016110.651 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-07-17T20:01:50.657Z,1595016110.657 [SBIT](INFO): Initialize SBIT Component.
2020-07-17T20:01:50.658Z,1595016110.658 [SBIT](IMPORTANT): git: 2020-07-17
2020-07-17T20:01:50.658Z,1595016110.658 [SBIT](INFO): git hash: e190d94ed20392517662b2bcc897c2f9e7dd6614
2020-07-17T20:01:50.658Z,1595016110.658 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-07-17T20:01:50.658Z,1595016110.658 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
2020-07-17T20:01:50.659Z,1595016110.659 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-07-17T20:01:50.660Z,1595016110.660 [IBIT](INFO): Initialize IBIT Component.
2020-07-17T20:01:50.661Z,1595016110.661 [CBIT](DEBUG): Initialize CBIT Component.
2020-07-17T20:01:50.662Z,1595016110.662 [logger ThreadHandler](INFO): Handler Thread ID is 914
2020-07-17T20:01:50.673Z,1595016110.673 [CBIT](DEBUG): Initialized mux pins.
2020-07-17T20:01:50.673Z,1595016110.673 [CBIT](DEBUG): Initializing the watchdog timer.
2020-07-17T20:01:50.681Z,1595016110.681 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 915
2020-07-17T20:01:50.682Z,1595016110.682 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-07-17T20:01:50.693Z,1595016110.693 [Onboard ThreadHandler](INFO): Handler Thread ID is 916
2020-07-17T20:01:50.697Z,1595016110.697 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-07-17T20:01:50.697Z,1595016110.697 [CBIT](DEBUG): Initializing heartbeat.
2020-07-17T20:01:50.723Z,1595016110.723 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 917
2020-07-17T20:01:50.729Z,1595016110.729 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 918
2020-07-17T20:01:50.730Z,1595016110.730 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-07-17T20:01:50.734Z,1595016110.734 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 920
2020-07-17T20:01:50.735Z,1595016110.735 [WetLabsBB2FL](INFO): Powering down
2020-07-17T20:01:50.769Z,1595016110.769 [CBIT](DEBUG): Deactivating GF circuits.
2020-07-17T20:01:50.769Z,1595016110.769 [CBIT](DEBUG): Deactivating emergency mode.
2020-07-17T20:01:50.805Z,1595016110.805 [CBIT](DEBUG): Backplane powered.
2020-07-17T20:01:50.806Z,1595016110.806 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-07-17T20:01:50.875Z,1595016110.875 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 921
2020-07-17T20:01:50.962Z,1595016110.962 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-17T20:01:50.967Z,1595016110.967 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-07-17T20:01:50.967Z,1595016110.967 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-07-17T20:01:50.967Z,1595016110.967 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-07-17T20:01:50.967Z,1595016110.967 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-07-17T20:01:50.968Z,1595016110.968 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-07-17T20:01:50.968Z,1595016110.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-07-17T20:01:50.968Z,1595016110.968 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-07-17T20:01:50.968Z,1595016110.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-07-17T20:01:50.968Z,1595016110.968 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-07-17T20:01:50.968Z,1595016110.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-07-17T20:01:50.969Z,1595016110.969 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-07-17T20:01:50.969Z,1595016110.969 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-07-17T20:01:50.969Z,1595016110.969 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-07-17T20:01:50.969Z,1595016110.969 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-07-17T20:01:50.969Z,1595016110.969 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-07-17T20:01:50.969Z,1595016110.969 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-07-17T20:01:51.004Z,1595016111.004 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2020-07-17T20:01:51.035Z,1595016111.035 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-07-17T20:01:51.125Z,1595016111.125 [Radio_Surface](INFO): Powering up
2020-07-17T20:01:51.174Z,1595016111.174 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-07-17T20:01:51.175Z,1595016111.175 [Default:A.Wait](DEBUG): Construct Wait.
2020-07-17T20:01:51.197Z,1595016111.197 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-17T20:01:51.220Z,1595016111.220 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-07-17T20:01:51.222Z,1595016111.222 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-07-17T20:01:51.259Z,1595016111.259 [Default:E.Execute](DEBUG): Construct Execute.
2020-07-17T20:01:51.262Z,1595016111.262 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2020-07-17T20:01:51.270Z,1595016111.270 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,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-07-17T20:01:51.293Z,1595016111.293 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-07-17T20:01:51.511Z,1595016111.511 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-07-17T20:01:51.517Z,1595016111.517 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-07-17T20:01:51.518Z,1595016111.518 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-07-17T20:01:51.525Z,1595016111.525 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-07-17T20:01:51.526Z,1595016111.526 [MassServo](DEBUG): Initializing EZServoServo.
2020-07-17T20:01:51.533Z,1595016111.533 [MassServo](DEBUG): Initializing MassServo.
2020-07-17T20:01:51.534Z,1595016111.534 [RudderServo](DEBUG): Initializing EZServoServo.
2020-07-17T20:01:51.541Z,1595016111.541 [RudderServo](DEBUG): Initializing RudderServo.
2020-07-17T20:01:51.542Z,1595016111.542 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-07-17T20:01:51.549Z,1595016111.549 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-07-17T20:01:51.697Z,1595016111.697 [Depth_Keller](ERROR): Pressure reading out of range: 1829.799438 decibar
2020-07-17T20:01:52.401Z,1595016112.401 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-07-17T20:01:52.401Z,1595016112.401 [RudderServo](FAULT): Rudder failed to initialize
2020-07-17T20:01:52.401Z,1595016112.401 [RudderServo] Communications Fault, FailCount= 1
2020-07-17T20:01:52.401Z,1595016112.401 [RudderServo](ERROR): Communications Fault
2020-07-17T20:01:52.572Z,1595016112.572 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-07-17T20:01:52.766Z,1595016112.766 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-07-17T20:01:52.766Z,1595016112.766 [RudderServo](INFO): Powering down
2020-07-17T20:01:53.409Z,1595016113.409 [RudderServo](DEBUG): Initializing EZServoServo.
2020-07-17T20:01:53.530Z,1595016113.530 [RudderServo](DEBUG): Initializing RudderServo.
2020-07-17T20:01:53.534Z,1595016113.534 [CBIT](INFO): Clearing failed state for component RudderServo
2020-07-17T20:01:53.534Z,1595016113.534 [RudderServo] No Fault, FailCount= 1
2020-07-17T20:02:08.746Z,1595016128.746 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-17T20:02:08.746Z,1595016128.746 [DVL_micro] Communications Fault, FailCount= 1
2020-07-17T20:02:08.746Z,1595016128.746 [DVL_micro](ERROR): Communications Fault
2020-07-17T20:02:08.802Z,1595016128.802 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-17T20:02:09.221Z,1595016129.221 [DVL_micro](INFO): Powering down
2020-07-17T20:02:10.021Z,1595016130.021 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-17T20:02:10.021Z,1595016130.021 [DVL_micro] No Fault, FailCount= 1
2020-07-17T20:02:16.823Z,1595016136.823 [NAL9602](INFO): Powering up NAL9602
2020-07-17T20:02:27.734Z,1595016147.734 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-17T20:02:27.734Z,1595016147.734 [DVL_micro] Communications Fault, FailCount= 2
2020-07-17T20:02:27.734Z,1595016147.734 [DVL_micro](ERROR): Communications Fault
2020-07-17T20:02:27.735Z,1595016147.735 [NAL9602](INFO): NAL9602 initialized
2020-07-17T20:02:27.751Z,1595016147.751 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-17T20:02:28.209Z,1595016148.209 [DVL_micro](INFO): Powering down
2020-07-17T20:02:29.001Z,1595016149.001 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-17T20:02:29.001Z,1595016149.001 [DVL_micro] No Fault, FailCount= 2
2020-07-17T20:02:30.194Z,1595016150.194 [SBIT](IMPORTANT): Beginning Startup BIT
2020-07-17T20:02:30.202Z,1595016150.202 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-17T20:02:41.141Z,1595016161.141 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.008614
CHAN A1 (24V): -0.158923
CHAN A2 (12V): 0.005342
CHAN A3 (5V): -0.000154
CHAN B0 (3.3V): -0.000088
CHAN B1 (3.15aV): -0.000606
CHAN B2 (3.15bV): -0.000438
CHAN B3 (GND): -0.002593
OPEN: -0.004070
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-17T20:02:46.730Z,1595016166.730 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-17T20:02:46.730Z,1595016166.730 [DVL_micro] Communications Fault, FailCount= 3
2020-07-17T20:02:46.730Z,1595016166.730 [DVL_micro](ERROR): Communications Fault
2020-07-17T20:02:46.849Z,1595016166.849 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-17T20:02:46.850Z,1595016166.850 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2020-07-17T20:02:47.193Z,1595016167.193 [DVL_micro](INFO): Powering down
2020-07-17T20:02:47.303Z,1595016167.303 [CommandLine](FAULT): Scheduling is paused
2020-07-17T20:02:47.303Z,1595016167.303 [CBIT](INFO): Critical error at 20200717T200246
2020-07-17T20:02:47.303Z,1595016167.303 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-07-17T20:03:03.358Z,1595016183.358 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:03:14.603Z,1595016194.603 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2020-07-17T20:03:14.603Z,1595016194.603 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2020-07-17T20:03:24.311Z,1595016204.311 [SBIT](IMPORTANT): SBIT PASSED
2020-07-17T20:03:24.312Z,1595016204.312 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-07-17T20:03:24.312Z,1595016204.312 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-07-17T20:03:24.313Z,1595016204.313 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-07-17T20:03:24.313Z,1595016204.313 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2020-07-17T20:03:24.313Z,1595016204.313 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-07-17T20:03:24.313Z,1595016204.313 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=236.2 cubic_centimeter;

2020-07-17T20:03:24.313Z,1595016204.313 [SBIT](IMPORTANT): VerticalControl.massDefault=4.77 millimeter;

2020-07-17T20:03:24.717Z,1595016204.717 [MissionManager](IMPORTANT): Started mission Startup
2020-07-17T20:03:24.717Z,1595016204.717 [Startup] Running Loop=1
2020-07-17T20:03:24.717Z,1595016204.717 [Startup](DEBUG): Aggregate::initialize Startup
2020-07-17T20:03:24.717Z,1595016204.717 [Startup:A.GoToSurface] Running Loop=1
2020-07-17T20:03:24.718Z,1595016204.718 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-17T20:03:24.718Z,1595016204.718 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-17T20:03:24.718Z,1595016204.718 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-17T20:03:24.719Z,1595016204.719 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-17T20:03:24.719Z,1595016204.719 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-17T20:03:24.719Z,1595016204.719 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-17T20:03:24.721Z,1595016204.721 [Startup:StartupSatComms] Running Loop=1
2020-07-17T20:03:24.721Z,1595016204.721 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-07-17T20:03:24.721Z,1595016204.721 [Startup:StartupSatComms:A] Running Loop=1
2020-07-17T20:03:25.118Z,1595016205.118 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-07-17T20:03:26.385Z,1595016206.385 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:03:36.815Z,1595016216.815 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2020-07-17T20:03:36.816Z,1595016216.816 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2020-07-17T20:03:48.620Z,1595016228.620 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:04:00.791Z,1595016240.791 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:04:05.463Z,1595016245.463 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004951
2020-07-17T20:04:12.448Z,1595016252.448 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:04:19.304Z,1595016259.304 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-17T20:04:24.167Z,1595016264.167 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:04:24.921Z,1595016264.921 [Startup:StartupSatComms:A](INFO): Timed out from 2020-07-17T20:03:24.7Z
2020-07-17T20:04:24.921Z,1595016264.921 [Startup:StartupSatComms:A] Stopped
2020-07-17T20:04:24.921Z,1595016264.921 [Startup:StartupSatComms:B] Running Loop=1
2020-07-17T20:04:25.315Z,1595016265.315 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-07-17T20:04:31.654Z,1595016271.654 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200717T174759/Courier0052.lzma
2020-07-17T20:04:32.655Z,1595016272.655 [DataOverHttps](INFO): Moved sent file to Logs/20200717T174759/Courier0052.lzma.bak
2020-07-17T20:04:32.655Z,1595016272.655 [DataOverHttps](INFO): SBD MOMSN=12446107
2020-07-17T20:04:33.794Z,1595016273.794 [BPC1](ERROR): BPC1B: No match for serial number FFFF in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2020-07-17T20:04:35.465Z,1595016275.465 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:04:47.591Z,1595016287.591 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-17T20:04:48.013Z,1595016288.013 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20200717T200138/Courier0000.lzma
2020-07-17T20:04:49.002Z,1595016289.002 [DataOverHttps](INFO): Moved sent file to Logs/20200717T200138/Courier0000.lzma.bak
2020-07-17T20:04:49.003Z,1595016289.003 [DataOverHttps](INFO): SBD MOMSN=12446114
2020-07-17T20:04:50.785Z,1595016290.785 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-07-17T20:04:50.785Z,1595016290.785 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-17T20:04:50.795Z,1595016290.795 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-17T20:04:51.201Z,1595016291.201 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-17T20:04:51.201Z,1595016291.201 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-07-17T20:04:59.314Z,1595016299.314 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-17T20:05:04.410Z,1595016304.410 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20200717T174759/Express0053.lzma
2020-07-17T20:05:05.411Z,1595016305.411 [DataOverHttps](INFO): Moved sent file to Logs/20200717T174759/Express0053.lzma.bak
2020-07-17T20:05:05.411Z,1595016305.411 [DataOverHttps](INFO): SBD MOMSN=12446118
2020-07-17T20:05:19.146Z,1595016319.146 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:05:20.750Z,1595016320.750 [DataOverHttps](INFO): Sending 952 bytes from file Logs/20200717T200138/Express0001.lzma
2020-07-17T20:05:21.750Z,1595016321.750 [DataOverHttps](INFO): Moved sent file to Logs/20200717T200138/Express0001.lzma.bak
2020-07-17T20:05:21.751Z,1595016321.751 [DataOverHttps](INFO): SBD MOMSN=12446125
2020-07-17T20:05:23.099Z,1595016323.099 [Startup:StartupSatComms:B] Stopped
2020-07-17T20:05:23.099Z,1595016323.099 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-07-17T20:05:23.099Z,1595016323.099 [Startup:StartupSatComms] Stopped
2020-07-17T20:05:23.099Z,1595016323.099 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-07-17T20:05:23.100Z,1595016323.100 [Startup](INFO): Completed Startup
2020-07-17T20:05:23.100Z,1595016323.100 [MissionManager](INFO): Startup is completed.
2020-07-17T20:05:23.100Z,1595016323.100 [MissionManager](INFO): Uninitializing Mission Startup
2020-07-17T20:05:23.100Z,1595016323.100 [Startup] Stopped
2020-07-17T20:05:23.100Z,1595016323.100 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-07-17T20:05:23.125Z,1595016323.125 [Startup:A.GoToSurface] Stopped
2020-07-17T20:05:23.125Z,1595016323.125 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-17T20:05:23.507Z,1595016323.507 [MissionManager](IMPORTANT): Started mission Default
2020-07-17T20:05:23.507Z,1595016323.507 [Default] Running Loop=1
2020-07-17T20:05:23.507Z,1595016323.507 [Default](DEBUG): Aggregate::initialize Default
2020-07-17T20:05:23.507Z,1595016323.507 [Default:B.GoToSurface] Running Loop=1
2020-07-17T20:05:23.507Z,1595016323.507 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-17T20:05:23.508Z,1595016323.508 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-17T20:05:23.508Z,1595016323.508 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-17T20:05:23.508Z,1595016323.508 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-17T20:05:23.509Z,1595016323.509 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-17T20:05:23.509Z,1595016323.509 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-17T20:05:23.509Z,1595016323.509 [Default:A.Wait] Running Loop=1
2020-07-17T20:05:23.509Z,1595016323.509 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-07-17T20:05:27.664Z,1595016327.664 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-17T20:05:36.845Z,1595016336.845 [Default:A.Wait](INFO): Done Waiting.
2020-07-17T20:05:36.845Z,1595016336.845 [Default:A.Wait] Stopped
2020-07-17T20:05:36.845Z,1595016336.845 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-07-17T20:05:37.256Z,1595016337.256 [Default:CheckIn] Running Loop=1
2020-07-17T20:05:37.256Z,1595016337.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-17T20:05:37.256Z,1595016337.256 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-17T20:05:37.662Z,1595016337.662 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-07-17T20:05:46.943Z,1595016346.943 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-17T20:05:46.943Z,1595016346.943 [DVL_micro] No Fault, FailCount= 3
2020-07-17T20:06:02.685Z,1595016362.685 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2020-07-17T20:06:04.703Z,1595016364.703 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-17T20:06:04.703Z,1595016364.703 [DVL_micro] Communications Fault, FailCount= 1
2020-07-17T20:06:04.703Z,1595016364.703 [DVL_micro](ERROR): Communications Fault
2020-07-17T20:06:04.721Z,1595016364.721 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-17T20:06:05.169Z,1595016365.169 [DVL_micro](INFO): Powering down
2020-07-17T20:06:05.968Z,1595016365.968 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-17T20:06:05.969Z,1595016365.969 [DVL_micro] No Fault, FailCount= 1
2020-07-17T20:06:16.895Z,1595016376.895 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:06:23.686Z,1595016383.686 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-17T20:06:23.686Z,1595016383.686 [DVL_micro] Communications Fault, FailCount= 2
2020-07-17T20:06:23.686Z,1595016383.686 [DVL_micro](ERROR): Communications Fault
2020-07-17T20:06:23.704Z,1595016383.704 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-17T20:06:24.153Z,1595016384.153 [DVL_micro](INFO): Powering down
2020-07-17T20:06:24.215Z,1595016384.215 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-17T20:06:24.929Z,1595016384.929 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-17T20:06:24.929Z,1595016384.929 [DVL_micro] No Fault, FailCount= 2
2020-07-17T20:06:42.674Z,1595016402.674 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-17T20:06:42.674Z,1595016402.674 [DVL_micro] Communications Fault, FailCount= 3
2020-07-17T20:06:42.674Z,1595016402.674 [DVL_micro](ERROR): Communications Fault
2020-07-17T20:06:42.712Z,1595016402.712 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-17T20:06:42.733Z,1595016402.733 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2020-07-17T20:06:43.145Z,1595016403.145 [DVL_micro](INFO): Powering down
2020-07-17T20:06:43.178Z,1595016403.178 [CBIT](INFO): Critical error at 20200717T200642
2020-07-17T20:06:58.901Z,1595016418.901 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:07:07.378Z,1595016427.378 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-17T20:07:10.626Z,1595016430.626 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:07:21.926Z,1595016441.926 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:07:30.751Z,1595016450.751 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-07-17T20:07:33.646Z,1595016453.646 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:07:45.765Z,1595016465.765 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:07:51.775Z,1595016471.775 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-07-17T20:07:51.775Z,1595016471.775 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-17T20:07:51.808Z,1595016471.808 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-17T20:07:52.199Z,1595016472.199 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-17T20:07:52.199Z,1595016472.199 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-07-17T20:07:57.493Z,1595016477.493 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:08:09.601Z,1595016489.601 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:08:21.326Z,1595016501.326 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:08:32.169Z,1595016512.169 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2020-07-17T20:08:32.189Z,1595016512.189 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2020-07-17T20:08:43.957Z,1595016523.957 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:08:56.109Z,1595016536.109 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:08:58.845Z,1595016538.845 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:09:10.596Z,1595016550.596 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:09:21.942Z,1595016561.942 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:09:42.892Z,1595016582.892 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-17T20:09:42.892Z,1595016582.892 [DVL_micro] No Fault, FailCount= 3
2020-07-17T20:10:00.634Z,1595016600.634 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-17T20:10:00.634Z,1595016600.634 [DVL_micro] Communications Fault, FailCount= 1
2020-07-17T20:10:00.634Z,1595016600.634 [DVL_micro](ERROR): Communications Fault
2020-07-17T20:10:00.689Z,1595016600.689 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-17T20:10:01.112Z,1595016601.112 [DVL_micro](INFO): Powering down
2020-07-17T20:10:01.896Z,1595016601.896 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-17T20:10:01.897Z,1595016601.897 [DVL_micro] No Fault, FailCount= 1
2020-07-17T20:10:19.618Z,1595016619.618 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-17T20:10:19.618Z,1595016619.618 [DVL_micro] Communications Fault, FailCount= 2
2020-07-17T20:10:19.618Z,1595016619.618 [DVL_micro](ERROR): Communications Fault
2020-07-17T20:10:19.667Z,1595016619.667 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-17T20:10:20.093Z,1595016620.093 [DVL_micro](INFO): Powering down
2020-07-17T20:10:20.927Z,1595016620.927 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-17T20:10:20.927Z,1595016620.927 [DVL_micro] No Fault, FailCount= 2
2020-07-17T20:10:31.784Z,1595016631.784 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:10:37.403Z,1595016637.403 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-17T20:05:37.3Z
2020-07-17T20:10:37.403Z,1595016637.403 [Default:CheckIn:Read_GPS] Stopped
2020-07-17T20:10:37.403Z,1595016637.403 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-17T20:10:37.827Z,1595016637.827 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-07-17T20:10:38.606Z,1595016638.606 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-07-17T20:10:38.606Z,1595016638.606 [DVL_micro] Communications Fault, FailCount= 3
2020-07-17T20:10:38.606Z,1595016638.606 [DVL_micro](ERROR): Communications Fault
2020-07-17T20:10:38.647Z,1595016638.647 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-17T20:10:38.647Z,1595016638.647 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2020-07-17T20:10:39.077Z,1595016639.077 [DVL_micro](INFO): Powering down
2020-07-17T20:10:39.111Z,1595016639.111 [CBIT](INFO): Critical error at 20200717T201038
2020-07-17T20:10:43.148Z,1595016643.148 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:10:45.020Z,1595016645.020 [DataOverHttps](INFO): Sending 91 bytes from file Logs/20200717T200138/Courier0004.lzma
2020-07-17T20:10:46.022Z,1595016646.022 [DataOverHttps](INFO): Moved sent file to Logs/20200717T200138/Courier0004.lzma.bak
2020-07-17T20:10:46.023Z,1595016646.023 [DataOverHttps](INFO): SBD MOMSN=12446148
2020-07-17T20:10:52.768Z,1595016652.768 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-07-17T20:10:52.768Z,1595016652.768 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-17T20:10:52.778Z,1595016652.778 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-17T20:10:53.190Z,1595016653.190 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-17T20:10:53.190Z,1595016653.190 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-07-17T20:10:54.817Z,1595016654.817 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:10:59.338Z,1595016659.338 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-17T20:11:01.365Z,1595016661.365 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20200717T200138/Express0005.lzma
2020-07-17T20:11:02.366Z,1595016662.366 [DataOverHttps](INFO): Moved sent file to Logs/20200717T200138/Express0005.lzma.bak
2020-07-17T20:11:02.367Z,1595016662.367 [DataOverHttps](INFO): SBD MOMSN=12446151
2020-07-17T20:11:03.680Z,1595016663.680 [Default:CheckIn:Read_Iridium] Stopped
2020-07-17T20:11:03.680Z,1595016663.680 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-17T20:11:03.680Z,1595016663.680 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-17T20:11:06.196Z,1595016666.196 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:11:17.446Z,1595016677.446 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:11:54.167Z,1595016714.167 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2020-07-17T20:11:54.171Z,1595016714.171 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2020-07-17T20:12:05.962Z,1595016725.962 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:12:18.142Z,1595016738.142 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:12:29.792Z,1595016749.792 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:12:30.930Z,1595016750.930 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-17T20:12:30.930Z,1595016750.930 [NAL9602] Data Fault, FailCount= 1
2020-07-17T20:12:30.930Z,1595016750.930 [NAL9602](ERROR): Data Fault
2020-07-17T20:12:30.947Z,1595016750.947 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-17T20:12:31.330Z,1595016751.330 [NAL9602](INFO): Powering down
2020-07-17T20:12:32.169Z,1595016752.169 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-17T20:12:32.169Z,1595016752.169 [NAL9602] No Fault, FailCount= 1
2020-07-17T20:12:41.908Z,1595016761.908 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:12:52.742Z,1595016772.742 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2020-07-17T20:12:52.744Z,1595016772.744 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2020-07-17T20:13:01.631Z,1595016781.631 [NAL9602](INFO): Powering up NAL9602
2020-07-17T20:13:04.529Z,1595016784.529 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:13:05.328Z,1595016785.328 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-07-17T20:13:12.542Z,1595016792.542 [NAL9602](INFO): NAL9602 initialized
2020-07-17T20:13:16.675Z,1595016796.675 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:13:28.449Z,1595016808.449 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:13:38.844Z,1595016818.844 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-17T20:13:38.844Z,1595016818.844 [DVL_micro] No Fault, FailCount= 3
2020-07-17T20:13:40.491Z,1595016820.491 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:13:48.014Z,1595016828.014 [CommandLine](IMPORTANT): got command quit
2020-07-17T20:13:49.021Z,1595016829.021 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-17T20:13:49.021Z,1595016829.021 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:49.077Z,1595016829.077 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-07-17T20:13:49.077Z,1595016829.077 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:49.077Z,1595016829.077 [CommandLine](INFO): Join timeout helper Thread ID is 969
2020-07-17T20:13:49.078Z,1595016829.078 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-07-17T20:13:49.078Z,1595016829.078 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:49.079Z,1595016829.079 [NavChartDb](INFO): Join timeout helper Thread ID is 970
2020-07-17T20:13:49.153Z,1595016829.153 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-17T20:13:49.153Z,1595016829.153 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:49.161Z,1595016829.161 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-07-17T20:13:49.161Z,1595016829.161 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:49.161Z,1595016829.161 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 971
2020-07-17T20:13:49.509Z,1595016829.509 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-17T20:13:49.509Z,1595016829.509 [WetLabsBB2FL](INFO): Powering down
2020-07-17T20:13:49.510Z,1595016829.510 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:49.521Z,1595016829.521 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-07-17T20:13:49.521Z,1595016829.521 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:49.521Z,1595016829.521 [CTD_Seabird](INFO): Join timeout helper Thread ID is 972
2020-07-17T20:13:49.705Z,1595016829.705 [CTD_Seabird](INFO): Powering down
2020-07-17T20:13:49.717Z,1595016829.717 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-17T20:13:49.717Z,1595016829.717 [CTD_Seabird](INFO): Powering down
2020-07-17T20:13:49.729Z,1595016829.729 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:49.745Z,1595016829.745 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-07-17T20:13:49.745Z,1595016829.745 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:49.745Z,1595016829.745 [Radio_Surface](INFO): Join timeout helper Thread ID is 973
2020-07-17T20:13:49.869Z,1595016829.869 [Radio_Surface](INFO): Powering down
2020-07-17T20:13:49.870Z,1595016829.870 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-17T20:13:49.870Z,1595016829.870 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:49.874Z,1595016829.874 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-07-17T20:13:49.874Z,1595016829.874 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:49.874Z,1595016829.874 [Onboard](INFO): Join timeout helper Thread ID is 974
2020-07-17T20:13:50.085Z,1595016830.085 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-07-17T20:13:51.293Z,1595016831.293 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-17T20:13:51.293Z,1595016831.293 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:51.314Z,1595016831.314 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-07-17T20:13:51.314Z,1595016831.314 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:51.314Z,1595016831.314 [DataOverHttps](INFO): Join timeout helper Thread ID is 975
2020-07-17T20:13:51.380Z,1595016831.380 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:2285304 %. Average:282808.093750
2020-07-17T20:13:52.093Z,1595016832.093 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-17T20:13:52.093Z,1595016832.093 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.109Z,1595016832.109 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-07-17T20:13:52.110Z,1595016832.110 [logger ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.110Z,1595016832.110 [logger](INFO): Join timeout helper Thread ID is 976
2020-07-17T20:13:52.141Z,1595016832.141 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-17T20:13:52.141Z,1595016832.141 [logger ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.149Z,1595016832.149 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-07-17T20:13:52.150Z,1595016832.150 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.150Z,1595016832.150 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-07-17T20:13:52.150Z,1595016832.150 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.150Z,1595016832.150 [controlThread](INFO): Join timeout helper Thread ID is 977
2020-07-17T20:13:52.198Z,1595016832.198 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-07-17T20:13:52.214Z,1595016832.214 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-17T20:13:52.215Z,1595016832.215 [controlThread](DEBUG): Uninitializing ControlThread
2020-07-17T20:13:52.215Z,1595016832.215 [AHRS_M2](INFO): Powering down
2020-07-17T20:13:52.357Z,1595016832.357 [DVL_micro](INFO): Powering down
2020-07-17T20:13:52.358Z,1595016832.358 [NAL9602](INFO): Powering down
2020-07-17T20:13:52.361Z,1595016832.361 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-07-17T20:13:52.362Z,1595016832.362 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-07-17T20:13:52.363Z,1595016832.363 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-07-17T20:13:52.363Z,1595016832.363 [MissionManager](INFO): Uninitializing Mission Default
2020-07-17T20:13:52.363Z,1595016832.363 [Default] Stopped
2020-07-17T20:13:52.363Z,1595016832.363 [Default](DEBUG): Aggregate::uninitialize Default
2020-07-17T20:13:52.363Z,1595016832.363 [Default:B.GoToSurface] Stopped
2020-07-17T20:13:52.363Z,1595016832.363 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-17T20:13:52.363Z,1595016832.363 [Default:CheckIn] Stopped
2020-07-17T20:13:52.364Z,1595016832.364 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-17T20:13:52.364Z,1595016832.364 [Default:CheckIn:C.Wait] Stopped
2020-07-17T20:13:52.364Z,1595016832.364 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-17T20:13:52.366Z,1595016832.366 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-07-17T20:13:52.366Z,1595016832.366 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-07-17T20:13:52.367Z,1595016832.367 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-07-17T20:13:52.367Z,1595016832.367 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-07-17T20:13:52.367Z,1595016832.367 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-07-17T20:13:52.367Z,1595016832.367 [BuoyancyServo](INFO): Powering down
2020-07-17T20:13:52.381Z,1595016832.381 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-07-17T20:13:52.381Z,1595016832.381 [ElevatorServo](INFO): Powering down
2020-07-17T20:13:52.382Z,1595016832.382 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-07-17T20:13:52.382Z,1595016832.382 [MassServo](INFO): Powering down
2020-07-17T20:13:52.383Z,1595016832.383 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-07-17T20:13:52.383Z,1595016832.383 [RudderServo](INFO): Powering down
2020-07-17T20:13:52.384Z,1595016832.384 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-07-17T20:13:52.384Z,1595016832.384 [ThrusterServo](INFO): Powering down
2020-07-17T20:13:52.385Z,1595016832.385 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-07-17T20:13:52.385Z,1595016832.385 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-07-17T20:13:52.385Z,1595016832.385 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-07-17T20:13:52.385Z,1595016832.385 [CBIT](DEBUG): Powering off loads.
2020-07-17T20:13:52.397Z,1595016832.397 [CBIT](DEBUG): Disabling WDT.
2020-07-17T20:13:52.409Z,1595016832.409 [CBIT](DEBUG): Opening all GF detection circuits.
2020-07-17T20:13:52.410Z,1595016832.410 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.490Z,1595016832.490 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.493Z,1595016832.493 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.500Z,1595016832.500 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.553Z,1595016832.553 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.559Z,1595016832.559 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.582Z,1595016832.582 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-17T20:13:52.642Z,1595016832.642 [logger ThreadHandler](INFO): Thread cancelled.