2020-12-01T15:54:58.987Z,1606838098.987 [Supervisor](DEBUG): Initializing supervisor.
2020-12-01T15:54:58.990Z,1606838098.990 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-12-01T15:54:58.990Z,1606838098.990 [SyncHandler](INFO): Protected caller Thread ID is 583
2020-12-01T15:54:58.991Z,1606838098.991 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-12-01T15:54:58.992Z,1606838098.992 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-12-01T15:54:58.992Z,1606838098.992 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 584
2020-12-01T15:54:58.995Z,1606838098.995 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-12-01T15:54:59.012Z,1606838099.012 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-12-01T15:54:59.013Z,1606838099.013 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-12-01T15:54:59.013Z,1606838099.013 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 585
2020-12-01T15:54:59.014Z,1606838099.014 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-12-01T15:54:59.015Z,1606838099.015 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-12-01T15:54:59.015Z,1606838099.015 [logger ThreadHandler](INFO): Protected caller Thread ID is 586
2020-12-01T15:54:59.017Z,1606838099.017 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-12-01T15:54:59.017Z,1606838099.017 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-12-01T15:54:59.019Z,1606838099.019 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-12-01T15:54:59.211Z,1606838099.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-12-01T15:54:59.211Z,1606838099.211 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-12-01T15:54:59.288Z,1606838099.288 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-12-01T15:54:59.740Z,1606838099.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-12-01T15:54:59.741Z,1606838099.741 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-12-01T15:55:00.039Z,1606838100.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-12-01T15:55:00.039Z,1606838100.039 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-12-01T15:55:00.131Z,1606838100.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-12-01T15:55:00.132Z,1606838100.132 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-12-01T15:55:00.408Z,1606838100.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-12-01T15:55:00.409Z,1606838100.409 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-12-01T15:55:00.591Z,1606838100.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-12-01T15:55:00.592Z,1606838100.592 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-12-01T15:55:01.050Z,1606838101.050 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-12-01T15:55:01.051Z,1606838101.051 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-12-01T15:55:01.152Z,1606838101.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-12-01T15:55:01.153Z,1606838101.153 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-12-01T15:55:01.249Z,1606838101.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-12-01T15:55:01.249Z,1606838101.249 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-12-01T15:55:01.870Z,1606838101.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-12-01T15:55:01.871Z,1606838101.871 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-12-01T15:55:02.274Z,1606838102.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-12-01T15:55:02.274Z,1606838102.274 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-12-01T15:55:02.453Z,1606838102.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-12-01T15:55:02.454Z,1606838102.454 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-12-01T15:55:02.591Z,1606838102.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-12-01T15:55:02.591Z,1606838102.591 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-12-01T15:55:02.737Z,1606838102.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-12-01T15:55:02.738Z,1606838102.738 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-12-01T15:55:02.941Z,1606838102.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-12-01T15:55:02.943Z,1606838102.943 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2020-12-01T15:55:02.943Z,1606838102.943 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2020-12-01T15:55:03.030Z,1606838103.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2020-12-01T15:55:03.112Z,1606838103.112 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2020-12-01T15:55:03.208Z,1606838103.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2020-12-01T15:55:03.287Z,1606838103.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2020-12-01T15:55:03.379Z,1606838103.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2020-12-01T15:55:03.475Z,1606838103.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2020-12-01T15:55:03.669Z,1606838103.669 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2020-12-01T15:55:03.743Z,1606838103.743 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2020-12-01T15:55:03.898Z,1606838103.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2020-12-01T15:55:04.100Z,1606838104.100 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2020-12-01T15:55:04.417Z,1606838104.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2020-12-01T15:55:04.862Z,1606838104.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-12-01T15:55:04.863Z,1606838104.863 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-12-01T15:55:04.871Z,1606838104.871 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-12-01T15:55:04.920Z,1606838104.920 [VerticalControl](DEBUG): Construct VerticalControl.
2020-12-01T15:55:04.978Z,1606838104.978 [VerticalControl] Loaded
2020-12-01T15:55:04.978Z,1606838104.978 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-12-01T15:55:04.978Z,1606838104.978 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-12-01T15:55:05.015Z,1606838105.015 [HorizontalControl] Loaded
2020-12-01T15:55:05.015Z,1606838105.015 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-12-01T15:55:05.016Z,1606838105.016 [SpeedControl](DEBUG): Construct SpeedControl.
2020-12-01T15:55:05.019Z,1606838105.019 [SpeedControl] Loaded
2020-12-01T15:55:05.019Z,1606838105.019 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-12-01T15:55:05.020Z,1606838105.020 [LoopControl](DEBUG): Construct LoopControl.
2020-12-01T15:55:05.020Z,1606838105.020 [LoopControl] Loaded
2020-12-01T15:55:05.021Z,1606838105.021 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-12-01T15:55:05.021Z,1606838105.021 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-12-01T15:55:05.022Z,1606838105.022 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-12-01T15:55:05.040Z,1606838105.040 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-12-01T15:55:05.040Z,1606838105.040 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-12-01T15:55:05.134Z,1606838105.134 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-12-01T15:55:05.134Z,1606838105.134 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-12-01T15:55:05.239Z,1606838105.239 [BuoyancyServo] Loaded
2020-12-01T15:55:05.240Z,1606838105.240 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-12-01T15:55:05.255Z,1606838105.255 [ElevatorServo] Loaded
2020-12-01T15:55:05.256Z,1606838105.256 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-12-01T15:55:05.271Z,1606838105.271 [MassServo] Loaded
2020-12-01T15:55:05.271Z,1606838105.271 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-12-01T15:55:05.286Z,1606838105.286 [RudderServo] Loaded
2020-12-01T15:55:05.286Z,1606838105.286 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-12-01T15:55:05.300Z,1606838105.300 [ThrusterServo] Loaded
2020-12-01T15:55:05.300Z,1606838105.300 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-12-01T15:55:05.301Z,1606838105.301 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-12-01T15:55:05.301Z,1606838105.301 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-12-01T15:55:05.379Z,1606838105.379 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-12-01T15:55:05.380Z,1606838105.380 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-12-01T15:55:05.390Z,1606838105.390 [NavChart] Loaded
2020-12-01T15:55:05.390Z,1606838105.390 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-12-01T15:55:05.394Z,1606838105.394 [UniversalFixResidualReporter] Loaded
2020-12-01T15:55:05.394Z,1606838105.394 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-12-01T15:55:05.395Z,1606838105.395 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-12-01T15:55:05.395Z,1606838105.395 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-12-01T15:55:05.478Z,1606838105.478 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-12-01T15:55:05.479Z,1606838105.479 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-12-01T15:55:05.774Z,1606838105.774 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-12-01T15:55:05.777Z,1606838105.777 [AHRS_M2](INFO): created writer for : platform_orientation
2020-12-01T15:55:05.779Z,1606838105.779 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-12-01T15:55:05.782Z,1606838105.782 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-12-01T15:55:05.782Z,1606838105.782 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-12-01T15:55:05.785Z,1606838105.785 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-12-01T15:55:05.785Z,1606838105.785 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-12-01T15:55:05.788Z,1606838105.788 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-12-01T15:55:05.824Z,1606838105.824 [AHRS_M2] Loaded
2020-12-01T15:55:05.824Z,1606838105.824 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-12-01T15:55:05.897Z,1606838105.897 [DataOverHttps] Loaded
2020-12-01T15:55:05.898Z,1606838105.898 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-12-01T15:55:05.899Z,1606838105.899 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408A34E0
2020-12-01T15:55:05.899Z,1606838105.899 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 671
2020-12-01T15:55:05.915Z,1606838105.915 [Depth_Keller] Loaded
2020-12-01T15:55:05.915Z,1606838105.915 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-12-01T15:55:05.918Z,1606838105.918 [DropWeight] Loaded
2020-12-01T15:55:05.918Z,1606838105.918 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-12-01T15:55:05.972Z,1606838105.972 [NAL9602] Loaded
2020-12-01T15:55:05.972Z,1606838105.972 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-12-01T15:55:05.997Z,1606838105.997 [Onboard] Loaded
2020-12-01T15:55:05.997Z,1606838105.997 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-12-01T15:55:05.998Z,1606838105.998 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408D34E0
2020-12-01T15:55:05.999Z,1606838105.999 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 672
2020-12-01T15:55:06.009Z,1606838106.009 [Radio_Surface] Loaded
2020-12-01T15:55:06.009Z,1606838106.009 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-12-01T15:55:06.010Z,1606838106.010 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409034E0
2020-12-01T15:55:06.010Z,1606838106.010 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 673
2020-12-01T15:55:06.775Z,1606838106.775 [BPC1] Loaded
2020-12-01T15:55:06.775Z,1606838106.775 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-12-01T15:55:06.793Z,1606838106.793 [AMEcho] Loaded
2020-12-01T15:55:06.794Z,1606838106.794 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2020-12-01T15:55:06.794Z,1606838106.794 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-12-01T15:55:06.795Z,1606838106.795 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-12-01T15:55:06.832Z,1606838106.832 [DepthRateCalculator] Loaded
2020-12-01T15:55:06.832Z,1606838106.832 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-12-01T15:55:06.835Z,1606838106.835 [PitchRateCalculator] Loaded
2020-12-01T15:55:06.835Z,1606838106.835 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-12-01T15:55:06.842Z,1606838106.842 [SpeedCalculator] Loaded
2020-12-01T15:55:06.842Z,1606838106.842 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-12-01T15:55:06.853Z,1606838106.853 [TempGradientCalculator] Loaded
2020-12-01T15:55:06.853Z,1606838106.853 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-12-01T15:55:06.856Z,1606838106.856 [YawRateCalculator] Loaded
2020-12-01T15:55:06.856Z,1606838106.856 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-12-01T15:55:06.870Z,1606838106.870 [ElevatorOffsetCalculator] Loaded
2020-12-01T15:55:06.871Z,1606838106.871 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-12-01T15:55:06.871Z,1606838106.871 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-12-01T15:55:06.872Z,1606838106.872 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-12-01T15:55:07.062Z,1606838107.062 [CANONSampler] Loaded
2020-12-01T15:55:07.062Z,1606838107.062 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2020-12-01T15:55:07.080Z,1606838107.080 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-12-01T15:55:07.083Z,1606838107.083 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-12-01T15:55:07.084Z,1606838107.084 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-12-01T15:55:07.086Z,1606838107.086 [CTD_Seabird](INFO): created writer for : depth
2020-12-01T15:55:07.087Z,1606838107.087 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-12-01T15:55:07.089Z,1606838107.089 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-12-01T15:55:07.090Z,1606838107.090 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-12-01T15:55:07.093Z,1606838107.093 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-12-01T15:55:07.093Z,1606838107.093 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-12-01T15:55:07.096Z,1606838107.096 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-12-01T15:55:07.096Z,1606838107.096 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-12-01T15:55:07.100Z,1606838107.100 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-12-01T15:55:07.100Z,1606838107.100 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-12-01T15:55:07.103Z,1606838107.103 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-12-01T15:55:07.103Z,1606838107.103 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2020-12-01T15:55:07.106Z,1606838107.106 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2020-12-01T15:55:07.107Z,1606838107.107 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2020-12-01T15:55:07.107Z,1606838107.107 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2020-12-01T15:55:07.123Z,1606838107.123 [CTD_Seabird] Loaded
2020-12-01T15:55:07.123Z,1606838107.123 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-12-01T15:55:07.124Z,1606838107.124 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A114E0
2020-12-01T15:55:07.124Z,1606838107.124 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 674
2020-12-01T15:55:07.141Z,1606838107.141 [PAR_Licor] Loaded
2020-12-01T15:55:07.141Z,1606838107.141 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-12-01T15:55:07.153Z,1606838107.153 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-12-01T15:55:07.154Z,1606838107.154 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-12-01T15:55:07.155Z,1606838107.155 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-12-01T15:55:07.155Z,1606838107.155 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-12-01T15:55:07.157Z,1606838107.157 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-12-01T15:55:07.158Z,1606838107.158 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-12-01T15:55:07.160Z,1606838107.160 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-12-01T15:55:07.160Z,1606838107.160 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-12-01T15:55:07.162Z,1606838107.162 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-12-01T15:55:07.162Z,1606838107.162 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-12-01T15:55:07.164Z,1606838107.164 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-12-01T15:55:07.164Z,1606838107.164 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-12-01T15:55:07.166Z,1606838107.166 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-12-01T15:55:07.166Z,1606838107.166 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-12-01T15:55:07.168Z,1606838107.168 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T15:55:07.170Z,1606838107.170 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T15:55:07.170Z,1606838107.170 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T15:55:07.171Z,1606838107.171 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T15:55:07.172Z,1606838107.172 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T15:55:07.173Z,1606838107.173 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T15:55:07.174Z,1606838107.174 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T15:55:07.175Z,1606838107.175 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-12-01T15:55:07.177Z,1606838107.177 [WetLabsBB2FL] Loaded
2020-12-01T15:55:07.177Z,1606838107.177 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-12-01T15:55:07.178Z,1606838107.178 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A414E0
2020-12-01T15:55:07.178Z,1606838107.178 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 675
2020-12-01T15:55:07.179Z,1606838107.179 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-12-01T15:55:07.179Z,1606838107.179 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-12-01T15:55:07.206Z,1606838107.206 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-12-01T15:55:07.207Z,1606838107.207 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-12-01T15:55:07.487Z,1606838107.487 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-12-01T15:55:07.487Z,1606838107.487 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-12-01T15:55:07.616Z,1606838107.616 [SBIT](DEBUG): Construct Startup Built In Test.
2020-12-01T15:55:07.624Z,1606838107.624 [SBIT] Loaded
2020-12-01T15:55:07.625Z,1606838107.625 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-12-01T15:55:07.625Z,1606838107.625 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-12-01T15:55:07.637Z,1606838107.637 [IBIT] Loaded
2020-12-01T15:55:07.637Z,1606838107.637 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-12-01T15:55:07.640Z,1606838107.640 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-12-01T15:55:07.736Z,1606838107.736 [CBIT] Loaded
2020-12-01T15:55:07.736Z,1606838107.736 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-12-01T15:55:07.736Z,1606838107.736 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-12-01T15:55:07.737Z,1606838107.737 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-12-01T15:55:07.813Z,1606838107.813 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-12-01T15:55:07.817Z,1606838107.817 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-12-01T15:55:07.818Z,1606838107.818 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-12-01T15:55:07.826Z,1606838107.826 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-12-01T15:55:07.827Z,1606838107.827 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B684E0
2020-12-01T15:55:07.827Z,1606838107.827 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 676
2020-12-01T15:55:07.831Z,1606838107.831 [Supervisor](INFO): Main Thread ID is 582
2020-12-01T15:55:07.831Z,1606838107.831 [Supervisor](DEBUG): Running supervisor.
2020-12-01T15:55:07.832Z,1606838107.832 [CommandLine ThreadHandler](INFO): Handler Thread ID is 677
2020-12-01T15:55:07.834Z,1606838107.834 [controlThread ThreadHandler](INFO): Handler Thread ID is 678
2020-12-01T15:55:07.835Z,1606838107.835 [controlThread](DEBUG): Initializing ControlThread
2020-12-01T15:55:07.836Z,1606838107.836 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-12-01T15:55:07.837Z,1606838107.837 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-12-01T15:55:07.838Z,1606838107.838 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-12-01T15:55:07.838Z,1606838107.838 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-12-01T15:55:07.840Z,1606838107.840 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-12-01T15:55:07.841Z,1606838107.841 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-12-01T15:55:07.847Z,1606838107.847 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-12-01T15:55:07.847Z,1606838107.847 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-12-01T15:55:07.847Z,1606838107.847 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-12-01T15:55:07.848Z,1606838107.848 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-12-01T15:55:07.848Z,1606838107.848 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-12-01T15:55:07.849Z,1606838107.849 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-12-01T15:55:07.853Z,1606838107.853 [SBIT](INFO): Initialize SBIT Component.
2020-12-01T15:55:07.854Z,1606838107.854 [SBIT](IMPORTANT): git: 2020-11-25
2020-12-01T15:55:07.854Z,1606838107.854 [SBIT](INFO): git hash: 5e13bf84858840c906f3eccd44caf880a68d7500
2020-12-01T15:55:07.854Z,1606838107.854 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-12-01T15:55:07.855Z,1606838107.855 [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-12-01T15:55:07.856Z,1606838107.856 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2020-12-01T15:55:07.857Z,1606838107.857 [IBIT](INFO): Initialize IBIT Component.
2020-12-01T15:55:07.858Z,1606838107.858 [CBIT](DEBUG): Initialize CBIT Component.
2020-12-01T15:55:07.859Z,1606838107.859 [logger ThreadHandler](INFO): Handler Thread ID is 679
2020-12-01T15:55:07.869Z,1606838107.869 [CBIT](DEBUG): Initialized mux pins.
2020-12-01T15:55:07.869Z,1606838107.869 [CBIT](DEBUG): Initializing the watchdog timer.
2020-12-01T15:55:07.877Z,1606838107.877 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 680
2020-12-01T15:55:07.878Z,1606838107.878 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-12-01T15:55:07.889Z,1606838107.889 [Onboard ThreadHandler](INFO): Handler Thread ID is 681
2020-12-01T15:55:07.894Z,1606838107.894 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-12-01T15:55:07.894Z,1606838107.894 [CBIT](DEBUG): Initializing heartbeat.
2020-12-01T15:55:07.921Z,1606838107.921 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 682
2020-12-01T15:55:07.929Z,1606838107.929 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 683
2020-12-01T15:55:07.930Z,1606838107.930 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-12-01T15:55:07.934Z,1606838107.934 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 685
2020-12-01T15:55:07.935Z,1606838107.935 [WetLabsBB2FL](INFO): Powering down
2020-12-01T15:55:07.957Z,1606838107.957 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 686
2020-12-01T15:55:07.960Z,1606838107.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-12-01T15:55:07.960Z,1606838107.960 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-12-01T15:55:07.960Z,1606838107.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-12-01T15:55:07.961Z,1606838107.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-12-01T15:55:07.961Z,1606838107.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-12-01T15:55:07.961Z,1606838107.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-12-01T15:55:07.961Z,1606838107.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-12-01T15:55:07.961Z,1606838107.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-12-01T15:55:07.961Z,1606838107.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-12-01T15:55:07.962Z,1606838107.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-12-01T15:55:07.962Z,1606838107.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-12-01T15:55:07.962Z,1606838107.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-12-01T15:55:07.962Z,1606838107.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-12-01T15:55:07.962Z,1606838107.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-12-01T15:55:07.962Z,1606838107.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-12-01T15:55:07.963Z,1606838107.963 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-12-01T15:55:07.965Z,1606838107.965 [CBIT](DEBUG): Deactivating GF circuits.
2020-12-01T15:55:07.965Z,1606838107.965 [CBIT](DEBUG): Deactivating emergency mode.
2020-12-01T15:55:08.001Z,1606838108.001 [CBIT](DEBUG): Backplane powered.
2020-12-01T15:55:08.002Z,1606838108.002 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-12-01T15:55:08.011Z,1606838108.011 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-01T15:55:08.022Z,1606838108.022 [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-12-01T15:55:08.023Z,1606838108.023 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-12-01T15:55:08.102Z,1606838108.102 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-12-01T15:55:08.103Z,1606838108.103 [Default:A.Wait](DEBUG): Construct Wait.
2020-12-01T15:55:08.121Z,1606838108.121 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-01T15:55:08.147Z,1606838108.147 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-12-01T15:55:08.150Z,1606838108.150 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-12-01T15:55:08.179Z,1606838108.179 [Default:E.Execute](DEBUG): Construct Execute.
2020-12-01T15:55:08.182Z,1606838108.182 [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-12-01T15:55:08.187Z,1606838108.187 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,BPC1,AMEcho,CANONSampler,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-12-01T15:55:08.200Z,1606838108.200 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-12-01T15:55:08.230Z,1606838108.230 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2020-12-01T15:55:08.265Z,1606838108.265 [AMEcho](INFO): Powering up
2020-12-01T15:55:08.267Z,1606838108.267 [AMEcho](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-12-01T15:55:08.285Z,1606838108.285 [CANONSampler](INFO): Powering down
2020-12-01T15:55:08.325Z,1606838108.325 [Radio_Surface](INFO): Powering up
2020-12-01T15:55:08.333Z,1606838108.333 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-12-01T15:55:08.411Z,1606838108.411 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-12-01T15:55:08.463Z,1606838108.463 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-12-01T15:55:08.469Z,1606838108.469 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-12-01T15:55:08.470Z,1606838108.470 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-12-01T15:55:08.477Z,1606838108.477 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-12-01T15:55:08.478Z,1606838108.478 [MassServo](DEBUG): Initializing EZServoServo.
2020-12-01T15:55:08.485Z,1606838108.485 [MassServo](DEBUG): Initializing MassServo.
2020-12-01T15:55:08.486Z,1606838108.486 [RudderServo](DEBUG): Initializing EZServoServo.
2020-12-01T15:55:08.493Z,1606838108.493 [RudderServo](DEBUG): Initializing RudderServo.
2020-12-01T15:55:08.494Z,1606838108.494 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-12-01T15:55:08.501Z,1606838108.501 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-12-01T15:55:08.727Z,1606838108.727 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-12-01T15:55:08.727Z,1606838108.727 [DropWeight] Hardware Fault, FailCount= 1
2020-12-01T15:55:08.727Z,1606838108.727 [DropWeight](ERROR): Hardware Fault
2020-12-01T15:55:08.897Z,1606838108.897 [AMEcho](INFO): Powering down
2020-12-01T15:55:08.928Z,1606838108.928 [CommandLine](FAULT): Scheduling is paused
2020-12-01T15:55:08.928Z,1606838108.928 [CBIT](INFO): Critical error at 20201201T155508
2020-12-01T15:55:08.929Z,1606838108.929 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-12-01T15:55:08.931Z,1606838108.931 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-12-01T15:55:08.932Z,1606838108.932 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-12-01T15:55:09.369Z,1606838109.369 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-12-01T15:55:09.369Z,1606838109.369 [RudderServo](FAULT): Rudder failed to initialize
2020-12-01T15:55:09.369Z,1606838109.369 [RudderServo] Communications Fault, FailCount= 1
2020-12-01T15:55:09.369Z,1606838109.369 [RudderServo](ERROR): Communications Fault
2020-12-01T15:55:09.466Z,1606838109.466 [CBIT](INFO): Critical error at 20201201T155508
2020-12-01T15:55:09.469Z,1606838109.469 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-12-01T15:55:09.478Z,1606838109.478 [AMEcho](IMPORTANT): Setting 'depthThreshold' to 20.000000
2020-12-01T15:55:09.670Z,1606838109.670 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-12-01T15:55:09.670Z,1606838109.670 [RudderServo](INFO): Powering down
2020-12-01T15:55:10.327Z,1606838110.327 [RudderServo](DEBUG): Initializing EZServoServo.
2020-12-01T15:55:10.446Z,1606838110.446 [RudderServo](DEBUG): Initializing RudderServo.
2020-12-01T15:55:10.450Z,1606838110.450 [CBIT](INFO): Clearing failed state for component RudderServo
2020-12-01T15:55:10.450Z,1606838110.450 [RudderServo] No Fault, FailCount= 1
2020-12-01T15:55:21.163Z,1606838121.163 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004816
2020-12-01T15:55:36.146Z,1606838136.146 [NAL9602](INFO): Powering up NAL9602
2020-12-01T15:55:47.054Z,1606838147.054 [NAL9602](INFO): NAL9602 initialized
2020-12-01T15:56:07.270Z,1606838167.270 [SBIT](IMPORTANT): Beginning Startup BIT
2020-12-01T15:56:07.274Z,1606838167.274 [CBIT](IMPORTANT): Beginning ground fault scan
2020-12-01T15:56:18.181Z,1606838178.181 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.000996
CHAN A1 (24V): 0.000533
CHAN A2 (12V): 4.697929
CHAN A3 (5V): -0.001495
CHAN B0 (3.3V): 4.766929
CHAN B1 (3.15aV): -0.000070
CHAN B2 (3.15bV): -0.000217
CHAN B3 (GND): -0.000045
OPEN: -0.000353
Full Scale Calc: 4.765 mA, -1.589 mA
2020-12-01T15:57:01.404Z,1606838221.404 [SBIT](IMPORTANT): SBIT PASSED
2020-12-01T15:57:01.404Z,1606838221.404 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-12-01T15:57:01.405Z,1606838221.405 [SBIT](IMPORTANT): AMEcho.enabled=1 bool;

2020-12-01T15:57:01.405Z,1606838221.405 [SBIT](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup=1 bool;

2020-12-01T15:57:01.405Z,1606838221.405 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter;

2020-12-01T15:57:01.405Z,1606838221.405 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 1.000000 meter;

2020-12-01T15:57:01.405Z,1606838221.405 [SBIT](IMPORTANT): PAR_Licor.parCal=1.0914 none;

2020-12-01T15:57:01.405Z,1606838221.405 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=352.865169 cubic_centimeter;

2020-12-01T15:57:01.405Z,1606838221.405 [SBIT](IMPORTANT): VerticalControl.massDefault=-9.903388 millimeter;

2020-12-01T15:57:01.819Z,1606838221.819 [MissionManager](IMPORTANT): Started mission Startup
2020-12-01T15:57:01.819Z,1606838221.819 [Startup] Running Loop=1
2020-12-01T15:57:01.819Z,1606838221.819 [Startup](DEBUG): Aggregate::initialize Startup
2020-12-01T15:57:01.819Z,1606838221.819 [Startup:A.GoToSurface] Running Loop=1
2020-12-01T15:57:01.819Z,1606838221.819 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-01T15:57:01.820Z,1606838221.820 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-01T15:57:01.820Z,1606838221.820 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-01T15:57:01.821Z,1606838221.821 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-01T15:57:01.821Z,1606838221.821 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-01T15:57:01.822Z,1606838221.822 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-01T15:57:01.823Z,1606838221.823 [Startup:StartupSatComms] Running Loop=1
2020-12-01T15:57:01.824Z,1606838221.824 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-12-01T15:57:01.824Z,1606838221.824 [Startup:StartupSatComms:A] Running Loop=1
2020-12-01T15:57:02.220Z,1606838222.220 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-12-01T15:58:02.042Z,1606838282.042 [Startup:StartupSatComms:A](INFO): Timed out from 2020-12-01T15:57:01.8Z
2020-12-01T15:58:02.042Z,1606838282.042 [Startup:StartupSatComms:A] Stopped
2020-12-01T15:58:02.042Z,1606838282.042 [Startup:StartupSatComms:B] Running Loop=1
2020-12-01T15:58:02.413Z,1606838282.413 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-01T15:58:08.116Z,1606838288.116 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-12-01T15:58:08.127Z,1606838288.127 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T15:58:08.149Z,1606838288.149 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T15:58:08.510Z,1606838288.510 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T15:58:08.510Z,1606838288.510 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-12-01T15:58:09.572Z,1606838289.572 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201201T005444/Courier0007.lzma
2020-12-01T15:58:10.575Z,1606838290.575 [DataOverHttps](INFO): Moved sent file to Logs/20201201T005444/Courier0007.lzma.bak
2020-12-01T15:58:10.575Z,1606838290.575 [DataOverHttps](INFO): SBD MOMSN=13371887
2020-12-01T15:58:25.977Z,1606838305.977 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20201201T155458/Courier0000.lzma
2020-12-01T15:58:26.979Z,1606838306.979 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Courier0000.lzma.bak
2020-12-01T15:58:26.979Z,1606838306.979 [DataOverHttps](INFO): SBD MOMSN=13371889
2020-12-01T15:58:42.433Z,1606838322.433 [DataOverHttps](INFO): Sending 684 bytes from file Logs/20201201T005444/Express0008.lzma
2020-12-01T15:58:43.434Z,1606838323.434 [DataOverHttps](INFO): Moved sent file to Logs/20201201T005444/Express0008.lzma.bak
2020-12-01T15:58:43.435Z,1606838323.435 [DataOverHttps](INFO): SBD MOMSN=13371894
2020-12-01T15:58:57.846Z,1606838337.846 [DataOverHttps](INFO): Sending 928 bytes from file Logs/20201201T155458/Express0001.lzma
2020-12-01T15:58:58.847Z,1606838338.847 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Express0001.lzma.bak
2020-12-01T15:58:58.847Z,1606838338.847 [DataOverHttps](INFO): SBD MOMSN=13371916
2020-12-01T15:59:00.178Z,1606838340.178 [Startup:StartupSatComms:B] Stopped
2020-12-01T15:59:00.178Z,1606838340.178 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-12-01T15:59:00.178Z,1606838340.178 [Startup:StartupSatComms] Stopped
2020-12-01T15:59:00.178Z,1606838340.178 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-12-01T15:59:00.179Z,1606838340.179 [Startup](INFO): Completed Startup
2020-12-01T15:59:00.179Z,1606838340.179 [MissionManager](INFO): Startup is completed.
2020-12-01T15:59:00.179Z,1606838340.179 [MissionManager](INFO): Uninitializing Mission Startup
2020-12-01T15:59:00.179Z,1606838340.179 [Startup] Stopped
2020-12-01T15:59:00.179Z,1606838340.179 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-12-01T15:59:00.179Z,1606838340.179 [Startup:A.GoToSurface] Stopped
2020-12-01T15:59:00.179Z,1606838340.179 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-01T15:59:00.590Z,1606838340.590 [MissionManager](IMPORTANT): Started mission Default
2020-12-01T15:59:00.590Z,1606838340.590 [Default] Running Loop=1
2020-12-01T15:59:00.590Z,1606838340.590 [Default](DEBUG): Aggregate::initialize Default
2020-12-01T15:59:00.590Z,1606838340.590 [Default:B.GoToSurface] Running Loop=1
2020-12-01T15:59:00.590Z,1606838340.590 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-01T15:59:00.591Z,1606838340.591 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-01T15:59:00.591Z,1606838340.591 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-01T15:59:00.591Z,1606838340.591 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-01T15:59:00.591Z,1606838340.591 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-01T15:59:00.592Z,1606838340.592 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-01T15:59:00.592Z,1606838340.592 [Default:A.Wait] Running Loop=1
2020-12-01T15:59:00.592Z,1606838340.592 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-12-01T15:59:13.926Z,1606838353.926 [Default:A.Wait](INFO): Done Waiting.
2020-12-01T15:59:13.926Z,1606838353.926 [Default:A.Wait] Stopped
2020-12-01T15:59:13.926Z,1606838353.926 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T15:59:14.339Z,1606838354.339 [Default:CheckIn] Running Loop=1
2020-12-01T15:59:14.339Z,1606838354.339 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T15:59:14.339Z,1606838354.339 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T15:59:14.731Z,1606838354.731 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-12-01T16:00:50.054Z,1606838450.054 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-01T16:01:09.086Z,1606838469.086 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-12-01T16:01:09.086Z,1606838469.086 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:01:09.116Z,1606838469.116 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:01:09.487Z,1606838469.487 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:01:09.487Z,1606838469.487 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-12-01T16:02:35.102Z,1606838555.102 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-12-01T16:02:35.104Z,1606838555.104 [BPC1](INFO): Received data from all battery sticks.
2020-12-01T16:04:10.066Z,1606838650.066 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-12-01T16:04:10.066Z,1606838650.066 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:04:10.076Z,1606838650.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:04:10.454Z,1606838650.454 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:04:10.454Z,1606838650.454 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-12-01T16:04:14.507Z,1606838654.507 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-01T15:59:14.3Z
2020-12-01T16:04:14.507Z,1606838654.507 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T16:04:14.507Z,1606838654.507 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T16:04:14.911Z,1606838654.911 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-01T16:04:21.204Z,1606838661.204 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20201201T155458/Courier0004.lzma
2020-12-01T16:04:22.207Z,1606838662.207 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Courier0004.lzma.bak
2020-12-01T16:04:22.207Z,1606838662.207 [DataOverHttps](INFO): SBD MOMSN=13372055
2020-12-01T16:04:37.517Z,1606838677.517 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20201201T155458/Express0005.lzma
2020-12-01T16:04:38.518Z,1606838678.518 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Express0005.lzma.bak
2020-12-01T16:04:38.519Z,1606838678.519 [DataOverHttps](INFO): SBD MOMSN=13372057
2020-12-01T16:04:39.551Z,1606838679.551 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T16:04:39.551Z,1606838679.551 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T16:04:39.551Z,1606838679.551 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T16:05:48.206Z,1606838748.206 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-01T16:05:48.206Z,1606838748.206 [NAL9602] Data Fault, FailCount= 1
2020-12-01T16:05:48.206Z,1606838748.206 [NAL9602](ERROR): Data Fault
2020-12-01T16:05:48.223Z,1606838748.223 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-01T16:05:48.614Z,1606838748.614 [NAL9602](INFO): Powering down
2020-12-01T16:05:49.434Z,1606838749.434 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-01T16:05:49.434Z,1606838749.434 [NAL9602] No Fault, FailCount= 1
2020-12-01T16:06:18.906Z,1606838778.906 [NAL9602](INFO): Powering up NAL9602
2020-12-01T16:06:29.826Z,1606838789.826 [NAL9602](INFO): NAL9602 initialized
2020-12-01T16:07:11.034Z,1606838831.034 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-12-01T16:07:11.034Z,1606838831.034 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:07:11.044Z,1606838831.044 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:07:11.450Z,1606838831.450 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:07:11.450Z,1606838831.450 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-12-01T16:09:40.111Z,1606838980.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T16:09:40.111Z,1606838980.111 [Default:CheckIn:C.Wait] Stopped
2020-12-01T16:09:40.111Z,1606838980.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T16:09:40.111Z,1606838980.111 [Default:CheckIn:D] Running Loop=1
2020-12-01T16:09:40.519Z,1606838980.519 [Default:CheckIn:D] Stopped
2020-12-01T16:09:40.519Z,1606838980.519 [Default:CheckIn:E] Running Loop=1
2020-12-01T16:09:40.933Z,1606838980.933 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.665484 min 
2020-12-01T16:09:40.933Z,1606838980.933 [Default:CheckIn:E] Stopped
2020-12-01T16:09:40.933Z,1606838980.933 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T16:09:40.933Z,1606838980.933 [Default:CheckIn] Stopped
2020-12-01T16:09:40.933Z,1606838980.933 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T16:09:40.933Z,1606838980.933 [Default:CheckIn](INFO): Running loop #2
2020-12-01T16:09:40.933Z,1606838980.933 [Default:CheckIn] Running Loop=2
2020-12-01T16:09:40.933Z,1606838980.933 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T16:09:40.934Z,1606838980.934 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T16:10:08.814Z,1606839008.814 [CBIT](INFO): Clearing failed state for component DropWeight
2020-12-01T16:10:08.814Z,1606839008.814 [DropWeight] No Fault, FailCount= 1
2020-12-01T16:10:12.024Z,1606839012.024 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-12-01T16:10:12.024Z,1606839012.024 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:10:12.034Z,1606839012.034 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:10:12.456Z,1606839012.456 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:10:12.456Z,1606839012.456 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-12-01T16:13:13.082Z,1606839193.082 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-12-01T16:13:13.082Z,1606839193.082 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:13:13.092Z,1606839193.092 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:13:13.474Z,1606839193.474 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:13:13.474Z,1606839193.474 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-12-01T16:14:41.159Z,1606839281.159 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-01T16:09:40.9Z
2020-12-01T16:14:41.159Z,1606839281.159 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T16:14:41.159Z,1606839281.159 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T16:14:47.720Z,1606839287.720 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201201T155458/Courier0007.lzma
2020-12-01T16:14:48.723Z,1606839288.723 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Courier0007.lzma.bak
2020-12-01T16:14:48.723Z,1606839288.723 [DataOverHttps](INFO): SBD MOMSN=13372117
2020-12-01T16:15:03.052Z,1606839303.052 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20201201T155458/Express0008.lzma
2020-12-01T16:15:04.055Z,1606839304.055 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Express0008.lzma.bak
2020-12-01T16:15:04.055Z,1606839304.055 [DataOverHttps](INFO): SBD MOMSN=13372119
2020-12-01T16:15:05.384Z,1606839305.384 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T16:15:05.384Z,1606839305.384 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T16:15:05.384Z,1606839305.384 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T16:16:14.077Z,1606839374.077 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-12-01T16:16:14.077Z,1606839374.077 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:16:14.087Z,1606839374.087 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:16:14.478Z,1606839374.478 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:16:14.478Z,1606839374.478 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-12-01T16:16:33.434Z,1606839393.434 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-01T16:16:33.434Z,1606839393.434 [NAL9602] Data Fault, FailCount= 2
2020-12-01T16:16:33.434Z,1606839393.434 [NAL9602](ERROR): Data Fault
2020-12-01T16:16:33.451Z,1606839393.451 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-01T16:16:33.838Z,1606839393.838 [NAL9602](INFO): Powering down
2020-12-01T16:16:34.677Z,1606839394.677 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-01T16:16:34.677Z,1606839394.677 [NAL9602] No Fault, FailCount= 2
2020-12-01T16:17:04.136Z,1606839424.136 [NAL9602](INFO): Powering up NAL9602
2020-12-01T16:17:15.046Z,1606839435.046 [NAL9602](INFO): NAL9602 initialized
2020-12-01T16:19:15.050Z,1606839555.050 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-12-01T16:19:15.050Z,1606839555.050 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:19:15.060Z,1606839555.060 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:19:15.475Z,1606839555.475 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:19:15.475Z,1606839555.475 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-12-01T16:20:05.975Z,1606839605.975 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T16:20:05.975Z,1606839605.975 [Default:CheckIn:C.Wait] Stopped
2020-12-01T16:20:05.975Z,1606839605.975 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T16:20:05.976Z,1606839605.976 [Default:CheckIn:D] Running Loop=1
2020-12-01T16:20:06.367Z,1606839606.367 [Default:CheckIn:D] Stopped
2020-12-01T16:20:06.367Z,1606839606.367 [Default:CheckIn:E] Running Loop=1
2020-12-01T16:20:06.759Z,1606839606.759 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.096283 min 
2020-12-01T16:20:06.759Z,1606839606.759 [Default:CheckIn:E] Stopped
2020-12-01T16:20:06.760Z,1606839606.760 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T16:20:06.760Z,1606839606.760 [Default:CheckIn] Stopped
2020-12-01T16:20:06.760Z,1606839606.760 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T16:20:06.760Z,1606839606.760 [Default:CheckIn](INFO): Running loop #3
2020-12-01T16:20:06.760Z,1606839606.760 [Default:CheckIn] Running Loop=3
2020-12-01T16:20:06.760Z,1606839606.760 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T16:20:06.760Z,1606839606.760 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T16:22:16.074Z,1606839736.074 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-12-01T16:22:16.074Z,1606839736.074 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:22:16.104Z,1606839736.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:22:16.472Z,1606839736.472 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:22:16.472Z,1606839736.472 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-12-01T16:25:06.979Z,1606839906.979 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-01T16:20:06.8Z
2020-12-01T16:25:06.979Z,1606839906.979 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T16:25:06.979Z,1606839906.979 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T16:25:14.240Z,1606839914.240 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201201T155458/Courier0010.lzma
2020-12-01T16:25:15.243Z,1606839915.243 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Courier0010.lzma.bak
2020-12-01T16:25:15.243Z,1606839915.243 [DataOverHttps](INFO): SBD MOMSN=13372182
2020-12-01T16:25:17.066Z,1606839917.066 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-12-01T16:25:17.066Z,1606839917.066 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:25:17.076Z,1606839917.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:25:17.475Z,1606839917.475 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:25:17.475Z,1606839917.475 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-12-01T16:25:30.492Z,1606839930.492 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20201201T155458/Express0011.lzma
2020-12-01T16:25:31.494Z,1606839931.494 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Express0011.lzma.bak
2020-12-01T16:25:31.495Z,1606839931.495 [DataOverHttps](INFO): SBD MOMSN=13372184
2020-12-01T16:25:32.806Z,1606839932.806 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T16:25:32.806Z,1606839932.806 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T16:25:32.806Z,1606839932.806 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T16:27:18.242Z,1606840038.242 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-01T16:27:18.242Z,1606840038.242 [NAL9602] Data Fault, FailCount= 3
2020-12-01T16:27:18.242Z,1606840038.242 [NAL9602](ERROR): Data Fault
2020-12-01T16:27:18.282Z,1606840038.282 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-01T16:27:18.646Z,1606840038.646 [NAL9602](INFO): Powering down
2020-12-01T16:27:19.479Z,1606840039.479 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-01T16:27:19.479Z,1606840039.479 [NAL9602] No Fault, FailCount= 3
2020-12-01T16:27:48.942Z,1606840068.942 [NAL9602](INFO): Powering up NAL9602
2020-12-01T16:27:59.855Z,1606840079.855 [NAL9602](INFO): NAL9602 initialized
2020-12-01T16:28:18.062Z,1606840098.062 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-12-01T16:28:18.062Z,1606840098.062 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:28:18.072Z,1606840098.072 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:28:18.478Z,1606840098.478 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:28:18.478Z,1606840098.478 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-12-01T16:30:33.382Z,1606840233.382 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T16:30:33.383Z,1606840233.383 [Default:CheckIn:C.Wait] Stopped
2020-12-01T16:30:33.383Z,1606840233.383 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T16:30:33.383Z,1606840233.383 [Default:CheckIn:D] Running Loop=1
2020-12-01T16:30:33.782Z,1606840233.782 [Default:CheckIn:D] Stopped
2020-12-01T16:30:33.782Z,1606840233.782 [Default:CheckIn:E] Running Loop=1
2020-12-01T16:30:34.207Z,1606840234.207 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.553200 min 
2020-12-01T16:30:34.207Z,1606840234.207 [Default:CheckIn:E] Stopped
2020-12-01T16:30:34.207Z,1606840234.207 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T16:30:34.207Z,1606840234.207 [Default:CheckIn] Stopped
2020-12-01T16:30:34.208Z,1606840234.208 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T16:30:34.208Z,1606840234.208 [Default:CheckIn](INFO): Running loop #4
2020-12-01T16:30:34.208Z,1606840234.208 [Default:CheckIn] Running Loop=4
2020-12-01T16:30:34.208Z,1606840234.208 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T16:30:34.208Z,1606840234.208 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T16:31:19.057Z,1606840279.057 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-12-01T16:31:19.057Z,1606840279.057 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:31:19.067Z,1606840279.067 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:31:19.446Z,1606840279.446 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:31:19.446Z,1606840279.446 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-12-01T16:34:20.047Z,1606840460.047 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-12-01T16:34:20.047Z,1606840460.047 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:34:20.084Z,1606840460.084 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:34:20.470Z,1606840460.470 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:34:20.470Z,1606840460.470 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-12-01T16:35:34.376Z,1606840534.376 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-01T16:30:34.2Z
2020-12-01T16:35:34.376Z,1606840534.376 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T16:35:34.376Z,1606840534.376 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T16:36:38.588Z,1606840598.588 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201201T155458/Courier0013.lzma
2020-12-01T16:36:39.590Z,1606840599.590 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Courier0013.lzma.bak
2020-12-01T16:36:39.591Z,1606840599.591 [DataOverHttps](INFO): SBD MOMSN=13372240
2020-12-01T16:36:54.864Z,1606840614.864 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20201201T155458/Express0014.lzma
2020-12-01T16:37:03.116Z,1606840623.116 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20201201T155458/Express0014.lzma
2020-12-01T16:37:04.119Z,1606840624.119 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Express0014.lzma.bak
2020-12-01T16:37:04.119Z,1606840624.119 [DataOverHttps](INFO): SBD MOMSN=13372248
2020-12-01T16:37:05.291Z,1606840625.291 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T16:37:05.291Z,1606840625.291 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T16:37:05.291Z,1606840625.291 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T16:37:21.038Z,1606840641.038 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-12-01T16:37:21.038Z,1606840641.038 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:37:21.064Z,1606840641.064 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:37:21.438Z,1606840641.438 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:37:21.438Z,1606840641.438 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-12-01T16:38:03.435Z,1606840683.435 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-01T16:38:03.435Z,1606840683.435 [NAL9602] Data Fault, FailCount= 4
2020-12-01T16:38:03.435Z,1606840683.435 [NAL9602](ERROR): Data Fault
2020-12-01T16:38:03.450Z,1606840683.450 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-01T16:38:03.838Z,1606840683.838 [NAL9602](INFO): Powering down
2020-12-01T16:38:04.678Z,1606840684.678 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-01T16:38:04.678Z,1606840684.678 [NAL9602] No Fault, FailCount= 4
2020-12-01T16:38:34.134Z,1606840714.134 [NAL9602](INFO): Powering up NAL9602
2020-12-01T16:38:45.048Z,1606840725.048 [NAL9602](INFO): NAL9602 initialized
2020-12-01T16:40:10.286Z,1606840810.286 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-12-01T16:40:10.286Z,1606840810.286 [DropWeight] Hardware Fault, FailCount= 1
2020-12-01T16:40:10.286Z,1606840810.286 [DropWeight](ERROR): Hardware Fault
2020-12-01T16:40:10.380Z,1606840810.380 [CBIT](INFO): Critical error at 20201201T164010
2020-12-01T16:40:10.383Z,1606840810.383 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-12-01T16:40:10.383Z,1606840810.383 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-12-01T16:40:10.764Z,1606840810.764 [CBIT](INFO): Critical error at 20201201T164010
2020-12-01T16:40:22.089Z,1606840822.089 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-12-01T16:40:22.089Z,1606840822.089 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:40:22.099Z,1606840822.099 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:40:22.551Z,1606840822.551 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:40:22.551Z,1606840822.551 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-12-01T16:42:05.922Z,1606840925.922 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-01T16:42:05.922Z,1606840925.922 [Default:CheckIn:C.Wait] Stopped
2020-12-01T16:42:05.922Z,1606840925.922 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T16:42:05.922Z,1606840925.922 [Default:CheckIn:D] Running Loop=1
2020-12-01T16:42:06.335Z,1606840926.335 [Default:CheckIn:D] Stopped
2020-12-01T16:42:06.335Z,1606840926.335 [Default:CheckIn:E] Running Loop=1
2020-12-01T16:42:06.730Z,1606840926.730 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.095744 min 
2020-12-01T16:42:06.730Z,1606840926.730 [Default:CheckIn:E] Stopped
2020-12-01T16:42:06.730Z,1606840926.730 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-01T16:42:06.730Z,1606840926.730 [Default:CheckIn] Stopped
2020-12-01T16:42:06.730Z,1606840926.730 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T16:42:06.731Z,1606840926.731 [Default:CheckIn](INFO): Running loop #5
2020-12-01T16:42:06.731Z,1606840926.731 [Default:CheckIn] Running Loop=5
2020-12-01T16:42:06.731Z,1606840926.731 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-01T16:42:06.731Z,1606840926.731 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-01T16:43:23.081Z,1606841003.081 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-12-01T16:43:23.081Z,1606841003.081 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:43:23.090Z,1606841003.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:43:23.498Z,1606841003.498 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:43:23.498Z,1606841003.498 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-12-01T16:46:24.094Z,1606841184.094 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-12-01T16:46:24.094Z,1606841184.094 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:46:24.106Z,1606841184.106 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:46:24.523Z,1606841184.523 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:46:24.524Z,1606841184.524 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-12-01T16:47:06.923Z,1606841226.923 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-01T16:42:06.7Z
2020-12-01T16:47:06.923Z,1606841226.923 [Default:CheckIn:Read_GPS] Stopped
2020-12-01T16:47:06.923Z,1606841226.923 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-01T16:47:14.080Z,1606841234.080 [DataOverHttps](INFO): Sending 114 bytes from file Logs/20201201T155458/Courier0016.lzma
2020-12-01T16:47:15.082Z,1606841235.082 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Courier0016.lzma.bak
2020-12-01T16:47:15.083Z,1606841235.083 [DataOverHttps](INFO): SBD MOMSN=13372275
2020-12-01T16:48:46.382Z,1606841326.382 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-01T16:48:46.382Z,1606841326.382 [NAL9602] Data Fault, FailCount= 5
2020-12-01T16:48:46.382Z,1606841326.382 [NAL9602](ERROR): Data Fault
2020-12-01T16:48:46.451Z,1606841326.451 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-01T16:48:46.451Z,1606841326.451 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-12-01T16:48:46.786Z,1606841326.786 [NAL9602](INFO): Powering down
2020-12-01T16:48:46.848Z,1606841326.848 [CBIT](INFO): Critical error at 20201201T164846
2020-12-01T16:49:25.217Z,1606841365.217 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-12-01T16:49:25.217Z,1606841365.217 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:49:25.235Z,1606841365.235 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:49:25.613Z,1606841365.613 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:49:25.613Z,1606841365.613 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-12-01T16:49:29.344Z,1606841369.344 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20201201T155458/Express0017.lzma
2020-12-01T16:49:30.346Z,1606841370.346 [DataOverHttps](INFO): Moved sent file to Logs/20201201T155458/Express0017.lzma.bak
2020-12-01T16:49:30.347Z,1606841370.347 [DataOverHttps](INFO): SBD MOMSN=13372322
2020-12-01T16:49:31.642Z,1606841371.642 [Default:CheckIn:Read_Iridium] Stopped
2020-12-01T16:49:31.642Z,1606841371.642 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-01T16:49:31.643Z,1606841371.643 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-01T16:50:46.392Z,1606841446.392 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-01T16:50:46.392Z,1606841446.392 [NAL9602] No Fault, FailCount= 5
2020-12-01T16:50:46.778Z,1606841446.778 [NAL9602](INFO): Powering up NAL9602
2020-12-01T16:50:57.686Z,1606841457.686 [NAL9602](INFO): NAL9602 initialized
2020-12-01T16:52:26.193Z,1606841546.193 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-12-01T16:52:26.193Z,1606841546.193 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-01T16:52:26.227Z,1606841546.227 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-01T16:52:26.582Z,1606841546.582 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-01T16:52:26.582Z,1606841546.582 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-12-01T16:53:40.490Z,1606841620.490 [DataOverHttps](IMPORTANT): SBD MTMSN=20201201T165339
2020-12-01T16:53:47.669Z,1606841627.669 [DataOverHttps](INFO): Received command:restart app
2020-12-01T16:53:47.769Z,1606841627.769 [CommandLine](IMPORTANT): got command restart application
2020-12-01T16:53:48.773Z,1606841628.773 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T16:53:48.773Z,1606841628.773 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:48.793Z,1606841628.793 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-12-01T16:53:48.793Z,1606841628.793 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:48.793Z,1606841628.793 [CommandLine](INFO): Join timeout helper Thread ID is 792
2020-12-01T16:53:48.794Z,1606841628.794 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-12-01T16:53:48.794Z,1606841628.794 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:48.794Z,1606841628.794 [NavChartDb](INFO): Join timeout helper Thread ID is 794
2020-12-01T16:53:49.129Z,1606841629.129 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T16:53:49.129Z,1606841629.129 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:49.133Z,1606841629.133 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-12-01T16:53:49.133Z,1606841629.133 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:49.133Z,1606841629.133 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 795
2020-12-01T16:53:49.633Z,1606841629.633 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T16:53:49.633Z,1606841629.633 [WetLabsBB2FL](INFO): Powering down
2020-12-01T16:53:49.634Z,1606841629.634 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:49.653Z,1606841629.653 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-12-01T16:53:49.653Z,1606841629.653 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:49.653Z,1606841629.653 [CTD_Seabird](INFO): Join timeout helper Thread ID is 796
2020-12-01T16:53:49.677Z,1606841629.677 [CTD_Seabird](INFO): Powering down
2020-12-01T16:53:49.689Z,1606841629.689 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T16:53:49.689Z,1606841629.689 [CTD_Seabird](INFO): Powering down
2020-12-01T16:53:49.701Z,1606841629.701 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:49.717Z,1606841629.717 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-12-01T16:53:49.717Z,1606841629.717 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:49.717Z,1606841629.717 [Radio_Surface](INFO): Join timeout helper Thread ID is 797
2020-12-01T16:53:49.925Z,1606841629.925 [Radio_Surface](INFO): Powering down
2020-12-01T16:53:49.926Z,1606841629.926 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T16:53:49.926Z,1606841629.926 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:49.942Z,1606841629.942 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-12-01T16:53:49.942Z,1606841629.942 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:49.942Z,1606841629.942 [Onboard](INFO): Join timeout helper Thread ID is 798
2020-12-01T16:53:50.677Z,1606841630.677 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-12-01T16:53:50.885Z,1606841630.885 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T16:53:50.885Z,1606841630.885 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:50.905Z,1606841630.905 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-12-01T16:53:50.906Z,1606841630.906 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:50.906Z,1606841630.906 [DataOverHttps](INFO): Join timeout helper Thread ID is 799
2020-12-01T16:53:51.681Z,1606841631.681 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T16:53:51.681Z,1606841631.681 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:51.685Z,1606841631.685 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-12-01T16:53:51.686Z,1606841631.686 [logger ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:51.686Z,1606841631.686 [logger](INFO): Join timeout helper Thread ID is 800
2020-12-01T16:53:51.729Z,1606841631.729 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T16:53:51.729Z,1606841631.729 [logger ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:51.745Z,1606841631.745 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-12-01T16:53:51.746Z,1606841631.746 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:51.746Z,1606841631.746 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-12-01T16:53:51.746Z,1606841631.746 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:51.746Z,1606841631.746 [controlThread](INFO): Join timeout helper Thread ID is 801
2020-12-01T16:53:51.797Z,1606841631.797 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-01T16:53:51.797Z,1606841631.797 [controlThread](DEBUG): Uninitializing ControlThread
2020-12-01T16:53:51.797Z,1606841631.797 [AHRS_M2](INFO): Powering down
2020-12-01T16:53:51.869Z,1606841631.869 [NAL9602](INFO): Powering down
2020-12-01T16:53:51.871Z,1606841631.871 [AMEcho](INFO): Powering down
2020-12-01T16:53:51.872Z,1606841631.872 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-12-01T16:53:51.874Z,1606841631.874 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-12-01T16:53:51.874Z,1606841631.874 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-12-01T16:53:51.874Z,1606841631.874 [MissionManager](INFO): Uninitializing Mission Default
2020-12-01T16:53:51.875Z,1606841631.875 [Default] Stopped
2020-12-01T16:53:51.875Z,1606841631.875 [Default](DEBUG): Aggregate::uninitialize Default
2020-12-01T16:53:51.875Z,1606841631.875 [Default:B.GoToSurface] Stopped
2020-12-01T16:53:51.875Z,1606841631.875 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-01T16:53:51.875Z,1606841631.875 [Default:CheckIn] Stopped
2020-12-01T16:53:51.875Z,1606841631.875 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-01T16:53:51.875Z,1606841631.875 [Default:CheckIn:C.Wait] Stopped
2020-12-01T16:53:51.875Z,1606841631.875 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-01T16:53:51.877Z,1606841631.877 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-12-01T16:53:51.878Z,1606841631.878 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-12-01T16:53:51.878Z,1606841631.878 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-12-01T16:53:51.878Z,1606841631.878 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-12-01T16:53:51.879Z,1606841631.879 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-12-01T16:53:51.879Z,1606841631.879 [BuoyancyServo](INFO): Powering down
2020-12-01T16:53:51.893Z,1606841631.893 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-12-01T16:53:51.893Z,1606841631.893 [ElevatorServo](INFO): Powering down
2020-12-01T16:53:51.894Z,1606841631.894 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-12-01T16:53:51.894Z,1606841631.894 [MassServo](INFO): Powering down
2020-12-01T16:53:51.895Z,1606841631.895 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-12-01T16:53:51.895Z,1606841631.895 [RudderServo](INFO): Powering down
2020-12-01T16:53:51.895Z,1606841631.895 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-12-01T16:53:51.896Z,1606841631.896 [ThrusterServo](INFO): Powering down
2020-12-01T16:53:51.896Z,1606841631.896 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-12-01T16:53:51.897Z,1606841631.897 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-12-01T16:53:51.897Z,1606841631.897 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-12-01T16:53:51.897Z,1606841631.897 [CBIT](DEBUG): Powering off loads.
2020-12-01T16:53:51.909Z,1606841631.909 [CBIT](DEBUG): Disabling WDT.
2020-12-01T16:53:51.921Z,1606841631.921 [CBIT](DEBUG): Opening all GF detection circuits.
2020-12-01T16:53:51.921Z,1606841631.921 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:51.997Z,1606841631.997 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:51.999Z,1606841631.999 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:52.005Z,1606841632.005 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:52.037Z,1606841632.037 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:52.042Z,1606841632.042 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:52.084Z,1606841632.084 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-01T16:53:52.143Z,1606841632.143 [logger ThreadHandler](INFO): Thread cancelled.