2020-06-05T20:37:12.648Z,1591389432.648 [Supervisor](DEBUG): Initializing supervisor.
2020-06-05T20:37:12.650Z,1591389432.650 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-06-05T20:37:12.651Z,1591389432.651 [SyncHandler](INFO): Protected caller Thread ID is 9858
2020-06-05T20:37:12.651Z,1591389432.651 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-06-05T20:37:12.652Z,1591389432.652 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-06-05T20:37:12.653Z,1591389432.653 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9859
2020-06-05T20:37:12.655Z,1591389432.655 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-06-05T20:37:12.668Z,1591389432.668 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-06-05T20:37:12.669Z,1591389432.669 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-06-05T20:37:12.670Z,1591389432.670 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9860
2020-06-05T20:37:12.670Z,1591389432.670 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-06-05T20:37:12.671Z,1591389432.671 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-06-05T20:37:12.672Z,1591389432.672 [logger ThreadHandler](INFO): Protected caller Thread ID is 9861
2020-06-05T20:37:12.674Z,1591389432.674 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-06-05T20:37:12.674Z,1591389432.674 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-06-05T20:37:12.675Z,1591389432.675 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-06-05T20:37:12.986Z,1591389432.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-06-05T20:37:12.986Z,1591389432.986 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-06-05T20:37:13.578Z,1591389433.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-06-05T20:37:13.578Z,1591389433.578 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-06-05T20:37:13.719Z,1591389433.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-06-05T20:37:13.720Z,1591389433.720 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-06-05T20:37:14.185Z,1591389434.185 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-06-05T20:37:14.186Z,1591389434.186 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-06-05T20:37:14.282Z,1591389434.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-06-05T20:37:14.283Z,1591389434.283 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-06-05T20:37:14.366Z,1591389434.366 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-06-05T20:37:14.664Z,1591389434.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-06-05T20:37:14.665Z,1591389434.665 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-06-05T20:37:14.813Z,1591389434.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-06-05T20:37:14.813Z,1591389434.813 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-06-05T20:37:14.920Z,1591389434.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-06-05T20:37:14.921Z,1591389434.921 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-06-05T20:37:15.120Z,1591389435.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-06-05T20:37:15.120Z,1591389435.120 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-06-05T20:37:15.676Z,1591389435.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-06-05T20:37:15.677Z,1591389435.677 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-06-05T20:37:16.094Z,1591389436.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-06-05T20:37:16.095Z,1591389436.095 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-06-05T20:37:16.197Z,1591389436.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-06-05T20:37:16.198Z,1591389436.198 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-06-05T20:37:16.388Z,1591389436.388 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-06-05T20:37:16.389Z,1591389436.389 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-06-05T20:37:16.594Z,1591389436.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-06-05T20:37:16.594Z,1591389436.594 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-06-05T20:37:16.818Z,1591389436.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-06-05T20:37:16.820Z,1591389436.820 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2020-06-05T20:37:16.821Z,1591389436.821 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2020-06-05T20:37:16.918Z,1591389436.918 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2020-06-05T20:37:17.058Z,1591389437.058 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2020-06-05T20:37:17.144Z,1591389437.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2020-06-05T20:37:17.228Z,1591389437.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2020-06-05T20:37:17.334Z,1591389437.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2020-06-05T20:37:17.695Z,1591389437.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2020-06-05T20:37:18.171Z,1591389438.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-06-05T20:37:18.171Z,1591389438.171 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2020-06-05T20:37:18.326Z,1591389438.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2020-06-05T20:37:18.555Z,1591389438.555 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2020-06-05T20:37:18.653Z,1591389438.653 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2020-06-05T20:37:18.748Z,1591389438.748 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/
2020-06-05T20:37:18.748Z,1591389438.748 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-06-05T20:37:18.754Z,1591389438.754 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-06-05T20:37:18.826Z,1591389438.826 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-06-05T20:37:18.827Z,1591389438.827 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-06-05T20:37:18.845Z,1591389438.845 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-06-05T20:37:18.846Z,1591389438.846 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-06-05T20:37:18.886Z,1591389438.886 [DepthRateCalculator] Loaded
2020-06-05T20:37:18.886Z,1591389438.886 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-06-05T20:37:18.892Z,1591389438.892 [PitchRateCalculator] Loaded
2020-06-05T20:37:18.892Z,1591389438.892 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-06-05T20:37:18.907Z,1591389438.907 [SpeedCalculator] Loaded
2020-06-05T20:37:18.908Z,1591389438.908 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-06-05T20:37:18.929Z,1591389438.929 [TempGradientCalculator] Loaded
2020-06-05T20:37:18.929Z,1591389438.929 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-06-05T20:37:18.935Z,1591389438.935 [YawRateCalculator] Loaded
2020-06-05T20:37:18.935Z,1591389438.935 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-06-05T20:37:18.974Z,1591389438.974 [ElevatorOffsetCalculator] Loaded
2020-06-05T20:37:18.974Z,1591389438.974 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-06-05T20:37:18.974Z,1591389438.974 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-06-05T20:37:18.975Z,1591389438.975 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-06-05T20:37:19.020Z,1591389439.020 [VerticalControl](DEBUG): Construct VerticalControl.
2020-06-05T20:37:19.117Z,1591389439.117 [VerticalControl] Loaded
2020-06-05T20:37:19.118Z,1591389439.118 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-06-05T20:37:19.118Z,1591389439.118 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-06-05T20:37:19.197Z,1591389439.197 [HorizontalControl] Loaded
2020-06-05T20:37:19.198Z,1591389439.198 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-06-05T20:37:19.198Z,1591389439.198 [SpeedControl](DEBUG): Construct SpeedControl.
2020-06-05T20:37:19.203Z,1591389439.203 [SpeedControl] Loaded
2020-06-05T20:37:19.204Z,1591389439.204 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-06-05T20:37:19.204Z,1591389439.204 [LoopControl](DEBUG): Construct LoopControl.
2020-06-05T20:37:19.205Z,1591389439.205 [LoopControl] Loaded
2020-06-05T20:37:19.205Z,1591389439.205 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-06-05T20:37:19.205Z,1591389439.205 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-06-05T20:37:19.206Z,1591389439.206 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-06-05T20:37:19.319Z,1591389439.319 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-06-05T20:37:19.319Z,1591389439.319 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-06-05T20:37:19.339Z,1591389439.339 [NavChart] Loaded
2020-06-05T20:37:19.339Z,1591389439.339 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-06-05T20:37:19.343Z,1591389439.343 [UniversalFixResidualReporter] Loaded
2020-06-05T20:37:19.343Z,1591389439.343 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-06-05T20:37:19.344Z,1591389439.344 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-06-05T20:37:19.344Z,1591389439.344 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-06-05T20:37:19.440Z,1591389439.440 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-06-05T20:37:19.440Z,1591389439.440 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-06-05T20:37:19.717Z,1591389439.717 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-06-05T20:37:19.722Z,1591389439.722 [AHRS_M2](INFO): created writer for : platform_orientation
2020-06-05T20:37:19.724Z,1591389439.724 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-06-05T20:37:19.729Z,1591389439.729 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-06-05T20:37:19.730Z,1591389439.730 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-06-05T20:37:19.734Z,1591389439.734 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-06-05T20:37:19.735Z,1591389439.735 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-06-05T20:37:19.740Z,1591389439.740 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-06-05T20:37:19.810Z,1591389439.810 [AHRS_M2] Loaded
2020-06-05T20:37:19.810Z,1591389439.810 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-06-05T20:37:19.887Z,1591389439.887 [DataOverHttps] Loaded
2020-06-05T20:37:19.888Z,1591389439.888 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-06-05T20:37:19.889Z,1591389439.889 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408544E0
2020-06-05T20:37:19.889Z,1591389439.889 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9944
2020-06-05T20:37:19.903Z,1591389439.903 [Depth_Keller] Loaded
2020-06-05T20:37:19.903Z,1591389439.903 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-06-05T20:37:19.908Z,1591389439.908 [DropWeight] Loaded
2020-06-05T20:37:19.908Z,1591389439.908 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-06-05T20:37:19.952Z,1591389439.952 [DVL_micro] Loaded
2020-06-05T20:37:19.953Z,1591389439.953 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2020-06-05T20:37:20.046Z,1591389440.046 [NAL9602] Loaded
2020-06-05T20:37:20.046Z,1591389440.046 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-06-05T20:37:20.085Z,1591389440.085 [Onboard] Loaded
2020-06-05T20:37:20.085Z,1591389440.085 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-06-05T20:37:20.088Z,1591389440.088 [Radio_Surface] Loaded
2020-06-05T20:37:20.089Z,1591389440.089 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-06-05T20:37:20.090Z,1591389440.090 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408844E0
2020-06-05T20:37:20.090Z,1591389440.090 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9945
2020-06-05T20:37:20.220Z,1591389440.220 [DAT] Loaded
2020-06-05T20:37:20.220Z,1591389440.220 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-06-05T20:37:21.662Z,1591389441.662 [BPC1] Loaded
2020-06-05T20:37:21.662Z,1591389441.662 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-06-05T20:37:21.663Z,1591389441.663 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-06-05T20:37:21.663Z,1591389441.663 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-06-05T20:37:21.764Z,1591389441.764 [BuoyancyServo] Loaded
2020-06-05T20:37:21.764Z,1591389441.764 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-06-05T20:37:21.776Z,1591389441.776 [ElevatorServo] Loaded
2020-06-05T20:37:21.776Z,1591389441.776 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-06-05T20:37:21.787Z,1591389441.787 [MassServo] Loaded
2020-06-05T20:37:21.787Z,1591389441.787 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-06-05T20:37:21.798Z,1591389441.798 [RudderServo] Loaded
2020-06-05T20:37:21.798Z,1591389441.798 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-06-05T20:37:21.809Z,1591389441.809 [ThrusterServo] Loaded
2020-06-05T20:37:21.809Z,1591389441.809 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-06-05T20:37:21.809Z,1591389441.809 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-06-05T20:37:21.810Z,1591389441.810 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-06-05T20:37:21.887Z,1591389441.887 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-06-05T20:37:21.888Z,1591389441.888 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-06-05T20:37:21.913Z,1591389441.913 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-06-05T20:37:21.914Z,1591389441.914 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-06-05T20:37:22.189Z,1591389442.189 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-06-05T20:37:22.189Z,1591389442.189 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-06-05T20:37:22.368Z,1591389442.368 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-06-05T20:37:22.374Z,1591389442.374 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-06-05T20:37:22.374Z,1591389442.374 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-06-05T20:37:22.379Z,1591389442.379 [CTD_Seabird](INFO): created writer for : depth
2020-06-05T20:37:22.379Z,1591389442.379 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-06-05T20:37:22.384Z,1591389442.384 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-06-05T20:37:22.384Z,1591389442.384 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-06-05T20:37:22.390Z,1591389442.390 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-06-05T20:37:22.390Z,1591389442.390 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-06-05T20:37:22.395Z,1591389442.395 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-06-05T20:37:22.396Z,1591389442.396 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-06-05T20:37:22.401Z,1591389442.401 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-06-05T20:37:22.401Z,1591389442.401 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-06-05T20:37:22.406Z,1591389442.406 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-06-05T20:37:22.433Z,1591389442.433 [CTD_Seabird] Loaded
2020-06-05T20:37:22.434Z,1591389442.434 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-06-05T20:37:22.435Z,1591389442.435 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A904E0
2020-06-05T20:37:22.435Z,1591389442.435 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9946
2020-06-05T20:37:22.465Z,1591389442.465 [ESPComponent] Loaded
2020-06-05T20:37:22.465Z,1591389442.465 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-06-05T20:37:22.479Z,1591389442.479 [PAR_Licor] Loaded
2020-06-05T20:37:22.479Z,1591389442.479 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-06-05T20:37:22.486Z,1591389442.486 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-06-05T20:37:22.486Z,1591389442.486 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-06-05T20:37:22.490Z,1591389442.490 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-06-05T20:37:22.490Z,1591389442.490 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-06-05T20:37:22.494Z,1591389442.494 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-06-05T20:37:22.494Z,1591389442.494 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-06-05T20:37:22.498Z,1591389442.498 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-06-05T20:37:22.498Z,1591389442.498 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-06-05T20:37:22.502Z,1591389442.502 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-06-05T20:37:22.503Z,1591389442.503 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-06-05T20:37:22.507Z,1591389442.507 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-06-05T20:37:22.507Z,1591389442.507 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-06-05T20:37:22.511Z,1591389442.511 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-06-05T20:37:22.511Z,1591389442.511 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-06-05T20:37:22.515Z,1591389442.515 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-06-05T20:37:22.520Z,1591389442.520 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-06-05T20:37:22.520Z,1591389442.520 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-06-05T20:37:22.520Z,1591389442.520 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-06-05T20:37:22.524Z,1591389442.524 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-06-05T20:37:22.524Z,1591389442.524 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-06-05T20:37:22.529Z,1591389442.529 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-06-05T20:37:22.529Z,1591389442.529 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-06-05T20:37:22.533Z,1591389442.533 [WetLabsBB2FL] Loaded
2020-06-05T20:37:22.533Z,1591389442.533 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-06-05T20:37:22.534Z,1591389442.534 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC04E0
2020-06-05T20:37:22.535Z,1591389442.535 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9947
2020-06-05T20:37:22.535Z,1591389442.535 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-06-05T20:37:22.536Z,1591389442.536 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-06-05T20:37:22.666Z,1591389442.666 [SBIT](DEBUG): Construct Startup Built In Test.
2020-06-05T20:37:22.677Z,1591389442.677 [SBIT] Loaded
2020-06-05T20:37:22.677Z,1591389442.677 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-06-05T20:37:22.678Z,1591389442.678 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-06-05T20:37:22.690Z,1591389442.690 [IBIT] Loaded
2020-06-05T20:37:22.691Z,1591389442.691 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-06-05T20:37:22.694Z,1591389442.694 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-06-05T20:37:22.831Z,1591389442.831 [CBIT] Loaded
2020-06-05T20:37:22.832Z,1591389442.832 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-06-05T20:37:22.832Z,1591389442.832 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-06-05T20:37:22.847Z,1591389442.847 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-06-05T20:37:22.889Z,1591389442.889 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-06-05T20:37:22.913Z,1591389442.913 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-06-05T20:37:22.955Z,1591389442.955 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B2B4E0
2020-06-05T20:37:22.955Z,1591389442.955 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9948
2020-06-05T20:37:22.966Z,1591389442.966 [Supervisor](INFO): Main Thread ID is 9857
2020-06-05T20:37:22.966Z,1591389442.966 [Supervisor](DEBUG): Running supervisor.
2020-06-05T20:37:22.967Z,1591389442.967 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9949
2020-06-05T20:37:22.971Z,1591389442.971 [controlThread ThreadHandler](INFO): Handler Thread ID is 9950
2020-06-05T20:37:22.972Z,1591389442.972 [controlThread](DEBUG): Initializing ControlThread
2020-06-05T20:37:22.973Z,1591389442.973 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-06-05T20:37:22.973Z,1591389442.973 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-06-05T20:37:22.973Z,1591389442.973 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-06-05T20:37:22.974Z,1591389442.974 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-06-05T20:37:22.974Z,1591389442.974 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-06-05T20:37:22.975Z,1591389442.975 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-06-05T20:37:22.975Z,1591389442.975 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-06-05T20:37:22.977Z,1591389442.977 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-06-05T20:37:22.977Z,1591389442.977 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-06-05T20:37:22.978Z,1591389442.978 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-06-05T20:37:22.979Z,1591389442.979 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-06-05T20:37:22.979Z,1591389442.979 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-06-05T20:37:22.989Z,1591389442.989 [SBIT](INFO): Initialize SBIT Component.
2020-06-05T20:37:22.990Z,1591389442.990 [SBIT](IMPORTANT): git: 2020-05-06_C-9-gb1ae7b3
2020-06-05T20:37:22.990Z,1591389442.990 [SBIT](INFO): git hash: b1ae7b35592dd2096e9950fc8990f0aea39effde
2020-06-05T20:37:22.990Z,1591389442.990 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-06-05T20:37:22.990Z,1591389442.990 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
2020-06-05T20:37:22.991Z,1591389442.991 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2020-06-05T20:37:22.992Z,1591389442.992 [IBIT](INFO): Initialize IBIT Component.
2020-06-05T20:37:22.993Z,1591389442.993 [CBIT](DEBUG): Initialize CBIT Component.
2020-06-05T20:37:22.994Z,1591389442.994 [logger ThreadHandler](INFO): Handler Thread ID is 9951
2020-06-05T20:37:23.005Z,1591389443.005 [CBIT](DEBUG): Initialized mux pins.
2020-06-05T20:37:23.005Z,1591389443.005 [CBIT](DEBUG): Initializing the watchdog timer.
2020-06-05T20:37:23.013Z,1591389443.013 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9952
2020-06-05T20:37:23.014Z,1591389443.014 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-06-05T20:37:23.025Z,1591389443.025 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9953
2020-06-05T20:37:23.029Z,1591389443.029 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-06-05T20:37:23.029Z,1591389443.029 [CBIT](DEBUG): Initializing heartbeat.
2020-06-05T20:37:23.037Z,1591389443.037 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9954
2020-06-05T20:37:23.038Z,1591389443.038 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-06-05T20:37:23.042Z,1591389443.042 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9956
2020-06-05T20:37:23.043Z,1591389443.043 [WetLabsBB2FL](INFO): Powering down
2020-06-05T20:37:23.073Z,1591389443.073 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9957
2020-06-05T20:37:23.076Z,1591389443.076 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-06-05T20:37:23.077Z,1591389443.077 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-06-05T20:37:23.077Z,1591389443.077 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-06-05T20:37:23.077Z,1591389443.077 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-06-05T20:37:23.077Z,1591389443.077 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-06-05T20:37:23.078Z,1591389443.078 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-06-05T20:37:23.078Z,1591389443.078 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-06-05T20:37:23.078Z,1591389443.078 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-06-05T20:37:23.078Z,1591389443.078 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-06-05T20:37:23.078Z,1591389443.078 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-06-05T20:37:23.079Z,1591389443.079 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-06-05T20:37:23.079Z,1591389443.079 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-06-05T20:37:23.079Z,1591389443.079 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-06-05T20:37:23.079Z,1591389443.079 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-06-05T20:37:23.079Z,1591389443.079 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-06-05T20:37:23.079Z,1591389443.079 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-06-05T20:37:23.101Z,1591389443.101 [CBIT](DEBUG): Deactivating GF circuits.
2020-06-05T20:37:23.101Z,1591389443.101 [CBIT](DEBUG): Deactivating emergency mode.
2020-06-05T20:37:23.137Z,1591389443.137 [CBIT](DEBUG): Backplane powered.
2020-06-05T20:37:23.138Z,1591389443.138 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-06-05T20:37:23.148Z,1591389443.148 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-06-05T20:37:23.158Z,1591389443.158 [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-06-05T20:37:23.159Z,1591389443.159 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-06-05T20:37:23.246Z,1591389443.246 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-06-05T20:37:23.248Z,1591389443.248 [Default:A.Wait](DEBUG): Construct Wait.
2020-06-05T20:37:23.249Z,1591389443.249 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-06-05T20:37:23.260Z,1591389443.260 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-06-05T20:37:23.286Z,1591389443.286 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-06-05T20:37:23.292Z,1591389443.292 [Default:E.Execute](DEBUG): Construct Execute.
2020-06-05T20:37:23.319Z,1591389443.319 [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-06-05T20:37:23.324Z,1591389443.324 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-06-05T20:37:23.353Z,1591389443.353 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-06-05T20:37:23.387Z,1591389443.387 [Depth_Keller](ERROR): Pressure reading out of range: 1829.799438 decibar
2020-06-05T20:37:23.411Z,1591389443.411 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-06-05T20:37:23.411Z,1591389443.411 [DAT](INFO): Powering up
2020-06-05T20:37:23.411Z,1591389443.411 [DAT](DEBUG): Initializing DAT.
2020-06-05T20:37:23.429Z,1591389443.429 [Radio_Surface](INFO): Powering up
2020-06-05T20:37:23.470Z,1591389443.470 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-06-05T20:37:23.488Z,1591389443.488 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-06-05T20:37:23.563Z,1591389443.563 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-06-05T20:37:23.569Z,1591389443.569 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-06-05T20:37:23.570Z,1591389443.570 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-06-05T20:37:23.581Z,1591389443.581 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-06-05T20:37:23.582Z,1591389443.582 [MassServo](DEBUG): Initializing EZServoServo.
2020-06-05T20:37:23.593Z,1591389443.593 [MassServo](DEBUG): Initializing MassServo.
2020-06-05T20:37:23.594Z,1591389443.594 [RudderServo](DEBUG): Initializing EZServoServo.
2020-06-05T20:37:23.605Z,1591389443.605 [RudderServo](DEBUG): Initializing RudderServo.
2020-06-05T20:37:23.606Z,1591389443.606 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-06-05T20:37:23.617Z,1591389443.617 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-06-05T20:37:23.750Z,1591389443.750 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-06-05T20:37:23.750Z,1591389443.750 [DropWeight] Hardware Fault, FailCount= 1
2020-06-05T20:37:23.750Z,1591389443.750 [DropWeight](ERROR): Hardware Fault
2020-06-05T20:37:23.793Z,1591389443.793 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-06-05T20:37:23.808Z,1591389443.808 [CommandLine](FAULT): Scheduling is paused
2020-06-05T20:37:23.809Z,1591389443.809 [CBIT](INFO): Critical error at 20200605T203723
2020-06-05T20:37:23.809Z,1591389443.809 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-06-05T20:37:23.811Z,1591389443.811 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-06-05T20:37:23.811Z,1591389443.811 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-06-05T20:37:24.529Z,1591389444.529 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-06-05T20:37:24.529Z,1591389444.529 [RudderServo](FAULT): Rudder failed to initialize
2020-06-05T20:37:24.529Z,1591389444.529 [RudderServo] Communications Fault, FailCount= 1
2020-06-05T20:37:24.529Z,1591389444.529 [RudderServo](ERROR): Communications Fault
2020-06-05T20:37:24.638Z,1591389444.638 [CBIT](INFO): Critical error at 20200605T203723
2020-06-05T20:37:24.641Z,1591389444.641 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-06-05T20:37:24.842Z,1591389444.842 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-06-05T20:37:24.842Z,1591389444.842 [RudderServo](INFO): Powering down
2020-06-05T20:37:25.489Z,1591389445.489 [RudderServo](DEBUG): Initializing EZServoServo.
2020-06-05T20:37:25.610Z,1591389445.610 [RudderServo](DEBUG): Initializing RudderServo.
2020-06-05T20:37:25.614Z,1591389445.614 [CBIT](INFO): Clearing failed state for component RudderServo
2020-06-05T20:37:25.614Z,1591389445.614 [RudderServo] No Fault, FailCount= 1
2020-06-05T20:37:36.141Z,1591389456.141 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006350
2020-06-05T20:37:37.598Z,1591389457.598 [DAT](INFO): commRate: 800
2020-06-05T20:37:37.599Z,1591389457.599 [DAT](INFO): commRate: 800
2020-06-05T20:37:38.006Z,1591389458.006 [DAT](INFO): entering command mode
2020-06-05T20:37:38.476Z,1591389458.476 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:38.809Z,1591389458.809 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:39.223Z,1591389459.223 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:39.625Z,1591389459.625 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:40.021Z,1591389460.021 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:40.434Z,1591389460.434 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:40.830Z,1591389460.830 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:41.241Z,1591389461.241 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:41.637Z,1591389461.637 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:42.048Z,1591389462.048 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:42.445Z,1591389462.445 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:42.849Z,1591389462.849 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:43.264Z,1591389463.264 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:43.665Z,1591389463.665 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:44.062Z,1591389464.062 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:44.473Z,1591389464.473 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:44.870Z,1591389464.870 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:45.272Z,1591389465.272 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:45.690Z,1591389465.690 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:46.090Z,1591389466.090 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:46.486Z,1591389466.486 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:46.889Z,1591389466.889 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:47.292Z,1591389467.292 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:47.712Z,1591389467.712 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:48.117Z,1591389468.117 [DAT](DEBUG): checking for command mode acknowledgment
2020-06-05T20:37:48.117Z,1591389468.117 [DAT](FAULT): failed to enter command mode
2020-06-05T20:37:48.506Z,1591389468.506 [DAT](INFO): entering command mode
2020-06-05T20:37:48.920Z,1591389468.920 [DAT](INFO): setting verbose to 3
2020-06-05T20:37:49.314Z,1591389469.314 [DAT](INFO): set verbose to 3
2020-06-05T20:37:49.315Z,1591389469.315 [DAT](INFO): setting DatVerbose to 27440
2020-06-05T20:37:49.721Z,1591389469.721 [DAT](INFO): set DatVerbose to 27440
2020-06-05T20:37:49.722Z,1591389469.722 [DAT](INFO): setting transmit power to 8
2020-06-05T20:37:50.103Z,1591389470.103 [NAL9602](INFO): Powering up NAL9602
2020-06-05T20:37:50.120Z,1591389470.120 [DAT](INFO): set transmit power to 8
2020-06-05T20:37:50.120Z,1591389470.120 [DAT](INFO): setting local address to 8
2020-06-05T20:37:50.526Z,1591389470.526 [DAT](INFO): set local address to 8
2020-06-05T20:38:01.016Z,1591389481.016 [NAL9602](INFO): NAL9602 initialized
2020-06-05T20:38:22.487Z,1591389502.487 [SBIT](IMPORTANT): Beginning Startup BIT
2020-06-05T20:38:22.495Z,1591389502.495 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-05T20:38:23.847Z,1591389503.847 [CommandLine](IMPORTANT): got command failComponent
2020-06-05T20:38:23.847Z,1591389503.847 [CommandLine](IMPORTANT): Failed components:
2020-06-05T20:38:23.848Z,1591389503.848 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-06-05T20:38:33.585Z,1591389513.585 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003778
CHAN A1 (24V): 0.003825
CHAN A2 (12V): -0.004369
CHAN A3 (5V): -0.003089
CHAN B0 (3.3V): -0.001570
CHAN B1 (3.15aV): -0.001475
CHAN B2 (3.15bV): -0.001425
CHAN B3 (GND): -0.001172
OPEN: 0.003520
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-05T20:38:47.409Z,1591389527.409 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T20:38:47.409Z,1591389527.409 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2020-06-05T20:39:10.543Z,1591389550.543 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:39:10.543Z,1591389550.543 [DVL_micro](ERROR): Failed to parse:
:TS0000000,35.0,+18.1,0000.0,1489.0,000
2020-06-05T20:39:15.836Z,1591389555.836 [SBIT](IMPORTANT): SBIT PASSED
2020-06-05T20:39:15.836Z,1591389555.836 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-06-05T20:39:15.837Z,1591389555.837 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2020-06-05T20:39:15.837Z,1591389555.837 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2020-06-05T20:39:15.837Z,1591389555.837 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=242.907926 cubic_centimeter;

2020-06-05T20:39:15.837Z,1591389555.837 [SBIT](IMPORTANT): VerticalControl.massDefault=9.890285 millimeter;

2020-06-05T20:39:16.247Z,1591389556.247 [MissionManager](IMPORTANT): Started mission Startup
2020-06-05T20:39:16.247Z,1591389556.247 [Startup] Running Loop=1
2020-06-05T20:39:16.247Z,1591389556.247 [Startup](DEBUG): Aggregate::initialize Startup
2020-06-05T20:39:16.248Z,1591389556.248 [Startup:A.GoToSurface] Running Loop=1
2020-06-05T20:39:16.248Z,1591389556.248 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-06-05T20:39:16.248Z,1591389556.248 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-06-05T20:39:16.249Z,1591389556.249 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-06-05T20:39:16.249Z,1591389556.249 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-06-05T20:39:16.250Z,1591389556.250 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-06-05T20:39:16.250Z,1591389556.250 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-06-05T20:39:16.251Z,1591389556.251 [Startup:StartupSatComms] Running Loop=1
2020-06-05T20:39:16.252Z,1591389556.252 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-06-05T20:39:16.252Z,1591389556.252 [Startup:StartupSatComms:A] Running Loop=1
2020-06-05T20:39:16.660Z,1591389556.660 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-06-05T20:39:38.438Z,1591389578.438 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:39:38.438Z,1591389578.438 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+18.1,089.0,000
2020-06-05T20:40:11.564Z,1591389611.564 [DVL_micro](ERROR): Failed to parse:
:WI,+00200,-00106,-00021,+00000,A
2020-06-05T20:40:16.468Z,1591389616.468 [Startup:StartupSatComms:A](INFO): Timed out from 2020-06-05T20:39:16.3Z
2020-06-05T20:40:16.468Z,1591389616.468 [Startup:StartupSatComms:A] Stopped
2020-06-05T20:40:16.468Z,1591389616.468 [Startup:StartupSatComms:B] Running Loop=1
2020-06-05T20:40:16.836Z,1591389616.836 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-06-05T20:40:23.320Z,1591389623.320 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-06-05T20:40:23.320Z,1591389623.320 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T20:40:23.340Z,1591389623.340 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T20:40:23.851Z,1591389623.851 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T20:40:23.851Z,1591389623.851 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-06-05T20:40:25.709Z,1591389625.709 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20200604T204609/Courier0004.lzma
2020-06-05T20:40:26.711Z,1591389626.711 [DataOverHttps](INFO): Moved sent file to Logs/20200604T204609/Courier0004.lzma.bak
2020-06-05T20:40:26.711Z,1591389626.711 [DataOverHttps](INFO): SBD MOMSN=12367863
2020-06-05T20:40:44.225Z,1591389644.225 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20200605T203712/Courier0000.lzma
2020-06-05T20:40:45.226Z,1591389645.226 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0000.lzma.bak
2020-06-05T20:40:45.227Z,1591389645.227 [DataOverHttps](INFO): SBD MOMSN=12367865
2020-06-05T20:41:06.242Z,1591389666.242 [DataOverHttps](INFO): Sending 979 bytes from file Logs/20200604T204609/Express0001.lzma
2020-06-05T20:41:07.242Z,1591389667.242 [DataOverHttps](INFO): Moved sent file to Logs/20200604T204609/Express0001.lzma.bak
2020-06-05T20:41:07.243Z,1591389667.243 [DataOverHttps](INFO): SBD MOMSN=12367870
2020-06-05T20:41:15.396Z,1591389675.396 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T20:41:15.401Z,1591389675.401 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999..99
2020-06-05T20:41:16.639Z,1591389676.639 [Startup:StartupSatComms:B](INFO): Timed out from 2020-06-05T20:40:16.5Z
2020-06-05T20:41:16.640Z,1591389676.640 [Startup:StartupSatComms:B] Stopped
2020-06-05T20:41:16.640Z,1591389676.640 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-06-05T20:41:16.640Z,1591389676.640 [Startup:StartupSatComms] Stopped
2020-06-05T20:41:16.640Z,1591389676.640 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-06-05T20:41:16.645Z,1591389676.645 [Startup](INFO): Completed Startup
2020-06-05T20:41:16.645Z,1591389676.645 [MissionManager](INFO): Startup is completed.
2020-06-05T20:41:16.645Z,1591389676.645 [MissionManager](INFO): Uninitializing Mission Startup
2020-06-05T20:41:16.645Z,1591389676.645 [Startup] Stopped
2020-06-05T20:41:16.645Z,1591389676.645 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-06-05T20:41:16.645Z,1591389676.645 [Startup:A.GoToSurface] Stopped
2020-06-05T20:41:16.645Z,1591389676.645 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-05T20:41:17.049Z,1591389677.049 [MissionManager](IMPORTANT): Started mission Default
2020-06-05T20:41:17.049Z,1591389677.049 [Default] Running Loop=1
2020-06-05T20:41:17.050Z,1591389677.050 [Default](DEBUG): Aggregate::initialize Default
2020-06-05T20:41:17.050Z,1591389677.050 [Default:B.GoToSurface] Running Loop=1
2020-06-05T20:41:17.050Z,1591389677.050 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-06-05T20:41:17.050Z,1591389677.050 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-06-05T20:41:17.050Z,1591389677.050 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-06-05T20:41:17.051Z,1591389677.051 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-06-05T20:41:17.051Z,1591389677.051 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-06-05T20:41:17.051Z,1591389677.051 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-06-05T20:41:17.051Z,1591389677.051 [Default:A.Wait] Running Loop=1
2020-06-05T20:41:17.052Z,1591389677.052 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-06-05T20:41:26.307Z,1591389686.307 [DataOverHttps](INFO): Sending 1640 bytes from file Logs/20200604T204609/Express0005.lzma
2020-06-05T20:41:27.307Z,1591389687.307 [DataOverHttps](INFO): Moved sent file to Logs/20200604T204609/Express0005.lzma.bak
2020-06-05T20:41:27.307Z,1591389687.307 [DataOverHttps](INFO): SBD MOMSN=12367896
2020-06-05T20:41:30.376Z,1591389690.376 [Default:A.Wait](INFO): Done Waiting.
2020-06-05T20:41:30.376Z,1591389690.376 [Default:A.Wait] Stopped
2020-06-05T20:41:30.376Z,1591389690.376 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T20:41:30.771Z,1591389690.771 [Default:CheckIn] Running Loop=1
2020-06-05T20:41:30.772Z,1591389690.772 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T20:41:30.772Z,1591389690.772 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T20:41:31.175Z,1591389691.175 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-06-05T20:41:43.608Z,1591389703.608 [CommandLine](IMPORTANT): got command gfscan
2020-06-05T20:41:43.716Z,1591389703.716 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-05T20:41:48.526Z,1591389708.526 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:41:48.526Z,1591389708.526 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35,0000.0,1489.0,000
2020-06-05T20:41:54.644Z,1591389714.644 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003455
CHAN A1 (24V): 0.003601
CHAN A2 (12V): -0.004199
CHAN A3 (5V): -0.002607
CHAN B0 (3.3V): -0.001369
CHAN B1 (3.15aV): -0.001485
CHAN B2 (3.15bV): -0.000666
CHAN B3 (GND): -0.000694
OPEN: 0.003926
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-05T20:42:39.899Z,1591389759.899 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset 0.000000
2020-06-05T20:42:39.899Z,1591389759.899 [CommandLine](FAULT): Incomplete syntax. Try: help configSet
2020-06-05T20:42:43.673Z,1591389763.673 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset 0.000000 microampere
2020-06-05T20:42:47.109Z,1591389767.109 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:42:47.109Z,1591389767.109 [DVL_micro](ERROR): Failed to parse:
:TS0000000,35.0,+18.2,0000.0,1489.0,000
2020-06-05T20:42:54.772Z,1591389774.772 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:42:54.772Z,1591389774.772 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+18.2,089.0,000
2020-06-05T20:42:54.811Z,1591389774.811 [CommandLine](IMPORTANT): got command configSet CBIT.gf12Offset 0.000000 ampere
2020-06-05T20:43:04.063Z,1591389784.063 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-06-05T20:43:05.089Z,1591389785.089 [CommandLine](IMPORTANT): got command configSet CBIT.gf5Offset 0.000000 ampere
2020-06-05T20:43:21.416Z,1591389801.416 [CommandLine](IMPORTANT): got command configSet CBIT.gf3_3Offset 0.000000 ampere
2020-06-05T20:43:24.315Z,1591389804.315 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-06-05T20:43:24.315Z,1591389804.315 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T20:43:24.324Z,1591389804.324 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T20:43:24.734Z,1591389804.734 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T20:43:24.734Z,1591389804.734 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-06-05T20:43:29.174Z,1591389809.174 [CommandLine](IMPORTANT): got command configSet CBIT.gf3_15Offset 0.000000 ampere
2020-06-05T20:43:40.564Z,1591389820.564 [CommandLine](IMPORTANT): got command configSet CBIT.gfCommOffset 0.000000 ampere
2020-06-05T20:43:42.831Z,1591389822.831 [CommandLine](IMPORTANT): got command gfscan
2020-06-05T20:43:42.928Z,1591389822.928 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-05T20:43:54.218Z,1591389834.218 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.002897
CHAN A1 (24V): 0.154501
CHAN A2 (12V): 0.062830
CHAN A3 (5V): 0.008192
CHAN B0 (3.3V): -0.003728
CHAN B1 (3.15aV): -0.005379
CHAN B2 (3.15bV): -0.004085
CHAN B3 (GND): -0.029374
OPEN: 0.003458
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-05T20:44:34.160Z,1591389874.160 [DVL_micro](ERROR): Failed to parse:
0000000000,35.0,+18.3,0000.0,1489.0,000
2020-06-05T20:44:49.984Z,1591389889.984 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset 154.500000 microampere
2020-06-05T20:45:07.096Z,1591389907.096 [CommandLine](IMPORTANT): got command configSet CBIT.gf12Offset 62.299999 microampere
2020-06-05T20:45:20.695Z,1591389920.695 [CommandLine](IMPORTANT): got command configSet CBIT.gf5Offset 8.200000 microampere
2020-06-05T20:45:32.739Z,1591389932.739 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T20:45:32.739Z,1591389932.739 [DVL_micro](ERROR): Failed to parse:
:BI,+01143,+0019,+00000+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-05T20:45:34.814Z,1591389934.814 [CommandLine](IMPORTANT): got command configSet CBIT.gf3_3Offset -3.700000 microampere
2020-06-05T20:46:01.712Z,1591389961.712 [CommandLine](IMPORTANT): got command configSet CBIT.gf3_15Offset -4.600000 microampere
2020-06-05T20:46:25.317Z,1591389985.317 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-06-05T20:46:25.317Z,1591389985.317 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T20:46:25.361Z,1591389985.361 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T20:46:25.722Z,1591389985.722 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T20:46:25.722Z,1591389985.722 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-06-05T20:46:28.368Z,1591389988.368 [CommandLine](IMPORTANT): got command configSet CBIT.gfCommOffset -29.400000 microampere
2020-06-05T20:46:30.590Z,1591389990.590 [CommandLine](IMPORTANT): got command gfscan
2020-06-05T20:46:30.944Z,1591389990.944 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T20:41:30.8Z
2020-06-05T20:46:30.944Z,1591389990.944 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T20:46:30.945Z,1591389990.945 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T20:46:30.953Z,1591389990.953 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-05T20:46:31.383Z,1591389991.383 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-06-05T20:46:40.740Z,1591390000.740 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200605T203712/Courier0004.lzma
2020-06-05T20:46:41.742Z,1591390001.742 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0004.lzma.bak
2020-06-05T20:46:41.743Z,1591390001.743 [DataOverHttps](INFO): SBD MOMSN=12368009
2020-06-05T20:46:41.885Z,1591390001.885 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002389
CHAN A1 (24V): 0.000396
CHAN A2 (12V): 0.001093
CHAN A3 (5V): -0.000449
CHAN B0 (3.3V): 0.000021
CHAN B1 (3.15aV): 0.000018
CHAN B2 (3.15bV): -0.000100
CHAN B3 (GND): 0.001226
OPEN: 0.003452
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-05T20:47:01.782Z,1591390021.782 [DataOverHttps](INFO): Sending 929 bytes from file Logs/20200605T203712/Express0001.lzma
2020-06-05T20:47:02.783Z,1591390022.783 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0001.lzma.bak
2020-06-05T20:47:02.783Z,1591390022.783 [DataOverHttps](INFO): SBD MOMSN=12368011
2020-06-05T20:47:06.958Z,1591390026.958 [CommandLine](IMPORTANT): got command configSet CBIT.gf12Offset 63.299999 microampere
2020-06-05T20:47:09.429Z,1591390029.429 [CommandLine](IMPORTANT): got command gfscan
2020-06-05T20:47:09.762Z,1591390029.762 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-05T20:47:12.125Z,1591390032.125 [DVL_micro](ERROR): Failed to parse:
:WI,+00280,-00375,-00019,+00000,A
2020-06-05T20:47:20.690Z,1591390040.690 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003380
CHAN A1 (24V): 0.000330
CHAN A2 (12V): -0.000804
CHAN A3 (5V): -0.000211
CHAN B0 (3.3V): 0.000413
CHAN B1 (3.15aV): 0.000303
CHAN B2 (3.15bV): 0.001033
CHAN B3 (GND): -0.000183
OPEN: 0.003658
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-05T20:47:22.313Z,1591390042.313 [DataOverHttps](INFO): Sending 686 bytes from file Logs/20200605T203712/Express0005.lzma
2020-06-05T20:47:23.314Z,1591390043.314 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0005.lzma.bak
2020-06-05T20:47:23.315Z,1591390043.315 [DataOverHttps](INFO): SBD MOMSN=12368034
2020-06-05T20:47:28.322Z,1591390048.322 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T20:47:28.322Z,1591390048.322 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T20:47:28.322Z,1591390048.322 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T20:47:45.271Z,1591390065.271 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-05T20:47:53.264Z,1591390073.264 [CommandLine](IMPORTANT): got command configSet CBIT.gf12Offset 63.000000 microampere
2020-06-05T20:47:59.173Z,1591390079.173 [CommandLine](IMPORTANT): got command gfscan
2020-06-05T20:47:59.450Z,1591390079.450 [CBIT](IMPORTANT): Beginning ground fault scan
2020-06-05T20:48:04.267Z,1591390084.267 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T20:48:04.267Z,1591390084.267 [NAL9602] Data Fault, FailCount= 1
2020-06-05T20:48:04.267Z,1591390084.267 [NAL9602](ERROR): Data Fault
2020-06-05T20:48:04.339Z,1591390084.339 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T20:48:04.667Z,1591390084.667 [NAL9602](INFO): Powering down
2020-06-05T20:48:05.585Z,1591390085.585 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T20:48:05.585Z,1591390085.585 [NAL9602] No Fault, FailCount= 1
2020-06-05T20:48:10.456Z,1591390090.456 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002525
CHAN A1 (24V): 0.000540
CHAN A2 (12V): 0.000469
CHAN A3 (5V): 0.000172
CHAN B0 (3.3V): 0.000536
CHAN B1 (3.15aV): 0.000021
CHAN B2 (3.15bV): 0.000056
CHAN B3 (GND): 0.000437
OPEN: 0.003785
Full Scale Calc: 4.765 mA, -1.589 mA
2020-06-05T20:48:24.406Z,1591390104.406 [CommandLine](IMPORTANT): got command show variable cbit.gf
2020-06-05T20:48:24.415Z,1591390104.415 [CommandLine](IMPORTANT): CBIT.GFActive (bool)
2020-06-05T20:48:24.484Z,1591390104.484 [CommandLine](IMPORTANT): CBIT.gfChanA0_Threshold (milliampere)
2020-06-05T20:48:24.485Z,1591390104.485 [CommandLine](IMPORTANT): CBIT.gfChanA1_Threshold (milliampere)
2020-06-05T20:48:24.485Z,1591390104.485 [CommandLine](IMPORTANT): CBIT.gfChanA2_Threshold (milliampere)
2020-06-05T20:48:24.486Z,1591390104.486 [CommandLine](IMPORTANT): CBIT.gfChanA3_Threshold (milliampere)
2020-06-05T20:48:24.486Z,1591390104.486 [CommandLine](IMPORTANT): CBIT.gfChanB0_Threshold (milliampere)
2020-06-05T20:48:24.486Z,1591390104.486 [CommandLine](IMPORTANT): CBIT.gfChanB1_Threshold (milliampere)
2020-06-05T20:48:24.487Z,1591390104.487 [CommandLine](IMPORTANT): CBIT.gfChanB2_Threshold (milliampere)
2020-06-05T20:48:24.487Z,1591390104.487 [CommandLine](IMPORTANT): CBIT.gfChanB3_Threshold (milliampere)
2020-06-05T20:48:24.487Z,1591390104.487 [CommandLine](IMPORTANT): CBIT.gfScanTimeout (hour)
2020-06-05T20:48:24.488Z,1591390104.488 [CommandLine](IMPORTANT): CBIT.gfBattOffset (microampere)
2020-06-05T20:48:24.488Z,1591390104.488 [CommandLine](IMPORTANT): CBIT.gf24Offset (microampere)
2020-06-05T20:48:24.488Z,1591390104.488 [CommandLine](IMPORTANT): CBIT.gf12Offset (microampere)
2020-06-05T20:48:24.489Z,1591390104.489 [CommandLine](IMPORTANT): CBIT.gf5Offset (microampere)
2020-06-05T20:48:24.489Z,1591390104.489 [CommandLine](IMPORTANT): CBIT.gf3_3Offset (microampere)
2020-06-05T20:48:24.490Z,1591390104.490 [CommandLine](IMPORTANT): CBIT.gf3_15Offset (microampere)
2020-06-05T20:48:24.490Z,1591390104.490 [CommandLine](IMPORTANT): CBIT.gfCommOffset (microampere)
2020-06-05T20:48:24.689Z,1591390104.689 [CommandLine](IMPORTANT): CBIT.GFCHANA0Current (milliampere)
2020-06-05T20:48:24.690Z,1591390104.690 [CommandLine](IMPORTANT): CBIT.GFCHANA1Current (milliampere)
2020-06-05T20:48:24.690Z,1591390104.690 [CommandLine](IMPORTANT): CBIT.GFCHANA2Current (milliampere)
2020-06-05T20:48:24.690Z,1591390104.690 [CommandLine](IMPORTANT): CBIT.GFCHANA3Current (milliampere)
2020-06-05T20:48:24.691Z,1591390104.691 [CommandLine](IMPORTANT): CBIT.GFCHANB0Current (milliampere)
2020-06-05T20:48:24.691Z,1591390104.691 [CommandLine](IMPORTANT): CBIT.GFCHANB1Current (milliampere)
2020-06-05T20:48:24.691Z,1591390104.691 [CommandLine](IMPORTANT): CBIT.GFCHANB2Current (milliampere)
2020-06-05T20:48:24.692Z,1591390104.692 [CommandLine](IMPORTANT): CBIT.GFCHANB3Current (milliampere)
2020-06-05T20:48:24.692Z,1591390104.692 [CommandLine](IMPORTANT): CBIT.GFCHANOpenCurrent (milliampere)
2020-06-05T20:48:28.576Z,1591390108.576 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:48:28.576Z,1591390108.576 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0000.0,1489.0,000
2020-06-05T20:48:33.655Z,1591390113.655 [CommandLine](IMPORTANT): got command get CBIT.gf24Offset
2020-06-05T20:48:33.655Z,1591390113.655 [CommandLine](IMPORTANT): CBIT.gf24Offset 154.500000 uA
2020-06-05T20:48:35.039Z,1591390115.039 [NAL9602](INFO): Powering up NAL9602
2020-06-05T20:48:45.943Z,1591390125.943 [NAL9602](INFO): NAL9602 initialized
2020-06-05T20:48:51.199Z,1591390131.199 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T20:48:51.199Z,1591390131.199 [DVL_micro](ERROR): Failed to parse:
:BI,+00457,+00004,-0004,I
2020-06-05T20:49:01.700Z,1591390141.700 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:49:01.701Z,1591390141.701 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+18.4,0000.0,1489.0,000
2020-06-05T20:49:03.397Z,1591390143.397 [CommandLine](IMPORTANT): got command get CBIT.gf12Offset
2020-06-05T20:49:03.398Z,1591390143.398 [CommandLine](IMPORTANT): CBIT.gf12Offset 63.000000 uA
2020-06-05T20:49:26.380Z,1591390166.380 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-06-05T20:49:26.380Z,1591390166.380 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T20:49:26.390Z,1591390166.390 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T20:49:26.794Z,1591390166.794 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T20:49:26.794Z,1591390166.794 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-06-05T20:49:30.590Z,1591390170.590 [CommandLine](IMPORTANT): got command get CBIT.gf5Offset
2020-06-05T20:49:30.591Z,1591390170.591 [CommandLine](IMPORTANT): CBIT.gf5Offset 8.200000 uA
2020-06-05T20:49:34.832Z,1591390174.832 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:49:34.832Z,1591390174.832 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+0.0,1489.0,000
2020-06-05T20:49:44.940Z,1591390184.940 [DVL_micro](ERROR): Failed to parse:
:WI,+00412,+00036,+00000,+00000,A
2020-06-05T20:49:58.495Z,1591390198.495 [CommandLine](IMPORTANT): got command get CBIT.gf3_3Offset
2020-06-05T20:49:58.496Z,1591390198.496 [CommandLine](IMPORTANT): CBIT.gf3_3Offset -3.700000 uA
2020-06-05T20:50:31.159Z,1591390231.159 [CommandLine](IMPORTANT): got command get CBIT.gf3_15Offset
2020-06-05T20:50:31.160Z,1591390231.160 [CommandLine](IMPORTANT): CBIT.gf3_15Offset -4.600000 uA
2020-06-05T20:50:45.142Z,1591390245.142 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2020-06-05T20:50:53.176Z,1591390253.176 [CommandLine](IMPORTANT): got command get CBIT.gfCommOffset
2020-06-05T20:50:53.176Z,1591390253.176 [CommandLine](IMPORTANT): CBIT.gfCommOffset -29.400000 uA
2020-06-05T20:51:24.326Z,1591390284.326 [DVL_micro](ERROR): Failed to parse:
9.99,+9999.99,+9999.99,+9999.99
2020-06-05T20:52:24.153Z,1591390344.153 [CBIT](INFO): Clearing failed state for component DropWeight
2020-06-05T20:52:24.153Z,1591390344.153 [DropWeight] No Fault, FailCount= 1
2020-06-05T20:52:27.381Z,1591390347.381 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-06-05T20:52:27.381Z,1591390347.381 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T20:52:27.408Z,1591390347.408 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T20:52:27.810Z,1591390347.810 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T20:52:27.810Z,1591390347.810 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-06-05T20:52:29.003Z,1591390349.003 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T20:52:29.003Z,1591390349.003 [Default:CheckIn:C.Wait] Stopped
2020-06-05T20:52:29.003Z,1591390349.003 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T20:52:29.003Z,1591390349.003 [Default:CheckIn:D] Running Loop=1
2020-06-05T20:52:29.411Z,1591390349.411 [Default:CheckIn:D] Stopped
2020-06-05T20:52:29.411Z,1591390349.411 [Default:CheckIn:E] Running Loop=1
2020-06-05T20:52:29.804Z,1591390349.804 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.206024 min 
2020-06-05T20:52:29.804Z,1591390349.804 [Default:CheckIn:E] Stopped
2020-06-05T20:52:29.804Z,1591390349.804 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T20:52:29.804Z,1591390349.804 [Default:CheckIn] Stopped
2020-06-05T20:52:29.804Z,1591390349.804 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T20:52:29.805Z,1591390349.805 [Default:CheckIn](INFO): Running loop #2
2020-06-05T20:52:29.805Z,1591390349.805 [Default:CheckIn] Running Loop=2
2020-06-05T20:52:29.805Z,1591390349.805 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T20:52:29.805Z,1591390349.805 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T20:52:30.585Z,1591390350.585 [DVL_micro](ERROR): Failed to parse:
02,-00062,+00028,+00000,I
2020-06-05T20:53:03.706Z,1591390383.706 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:53:03.706Z,1591390383.706 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+18.5,000.0,000
2020-06-05T20:53:34.405Z,1591390414.405 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:53:34.405Z,1591390414.405 [DVL_micro](ERROR): Failed to parse:
:TS,000000000.0,+18.5,0000.0,1489.0,000
2020-06-05T20:54:07.541Z,1591390447.541 [DVL_micro](ERROR): Failed to parse:
:WI,+00234,-00050,+00036,+00000,A
2020-06-05T20:54:40.677Z,1591390480.677 [DVL_micro](ERROR): Failed to parse:
71,-00299,+0000,I
2020-06-05T20:55:28.432Z,1591390528.432 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-06-05T20:55:28.432Z,1591390528.432 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T20:55:28.442Z,1591390528.442 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T20:55:28.823Z,1591390528.823 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T20:55:28.823Z,1591390528.823 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-06-05T20:56:20.061Z,1591390580.061 [DVL_micro](ERROR): Failed to parse:
:WI,+00460,+00038,+00086,+00000,A
2020-06-05T20:57:18.629Z,1591390638.629 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:57:18.629Z,1591390638.629 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+18.6,0000.0,00
2020-06-05T20:57:29.987Z,1591390649.987 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T20:52:29.8Z
2020-06-05T20:57:29.987Z,1591390649.987 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T20:57:29.988Z,1591390649.988 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T20:57:39.244Z,1591390659.244 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200605T203712/Courier0007.lzma
2020-06-05T20:57:40.247Z,1591390660.247 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0007.lzma.bak
2020-06-05T20:57:40.247Z,1591390660.247 [DataOverHttps](INFO): SBD MOMSN=12368072
2020-06-05T20:57:59.410Z,1591390679.410 [DataOverHttps](INFO): Sending 879 bytes from file Logs/20200605T203712/Express0008.lzma
2020-06-05T20:58:00.411Z,1591390680.411 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0008.lzma.bak
2020-06-05T20:58:00.411Z,1591390680.411 [DataOverHttps](INFO): SBD MOMSN=12368074
2020-06-05T20:58:05.156Z,1591390685.156 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T20:58:05.156Z,1591390685.156 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T20:58:05.156Z,1591390685.156 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T20:58:27.314Z,1591390707.314 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T20:58:27.314Z,1591390707.314 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+18.7,0000.0,1489.0,000
2020-06-05T20:58:29.382Z,1591390709.382 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-06-05T20:58:29.382Z,1591390709.382 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T20:58:29.392Z,1591390709.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T20:58:29.804Z,1591390709.804 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T20:58:29.804Z,1591390709.804 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-06-05T20:58:47.911Z,1591390727.911 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T20:58:47.911Z,1591390727.911 [NAL9602] Data Fault, FailCount= 2
2020-06-05T20:58:47.911Z,1591390727.911 [NAL9602](ERROR): Data Fault
2020-06-05T20:58:47.958Z,1591390727.958 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T20:58:48.315Z,1591390728.315 [NAL9602](INFO): Powering down
2020-06-05T20:58:49.158Z,1591390729.158 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T20:58:49.158Z,1591390729.158 [NAL9602] No Fault, FailCount= 2
2020-06-05T20:59:18.623Z,1591390758.623 [NAL9602](INFO): Powering up NAL9602
2020-06-05T20:59:29.519Z,1591390769.519 [NAL9602](INFO): NAL9602 initialized
2020-06-05T20:59:31.149Z,1591390771.149 [DVL_micro](ERROR): Failed to parse:
:WI,+00002,+00129,+00192,+00000,A
2020-06-05T21:00:39.855Z,1591390839.855 [DVL_micro](ERROR): Failed to parse:
:SA,-01.10,-06.79,035.7
2020-06-05T21:01:30.370Z,1591390890.370 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-06-05T21:01:30.370Z,1591390890.370 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:01:30.380Z,1591390890.380 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:01:30.788Z,1591390890.788 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:01:30.788Z,1591390890.788 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-06-05T21:03:05.713Z,1591390985.713 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T21:03:05.713Z,1591390985.713 [Default:CheckIn:C.Wait] Stopped
2020-06-05T21:03:05.713Z,1591390985.713 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T21:03:05.714Z,1591390985.714 [Default:CheckIn:D] Running Loop=1
2020-06-05T21:03:06.111Z,1591390986.111 [Default:CheckIn:D] Stopped
2020-06-05T21:03:06.111Z,1591390986.111 [Default:CheckIn:E] Running Loop=1
2020-06-05T21:03:06.514Z,1591390986.514 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.817690 min 
2020-06-05T21:03:06.514Z,1591390986.514 [Default:CheckIn:E] Stopped
2020-06-05T21:03:06.515Z,1591390986.515 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T21:03:06.515Z,1591390986.515 [Default:CheckIn] Stopped
2020-06-05T21:03:06.515Z,1591390986.515 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T21:03:06.515Z,1591390986.515 [Default:CheckIn](INFO): Running loop #3
2020-06-05T21:03:06.515Z,1591390986.515 [Default:CheckIn] Running Loop=3
2020-06-05T21:03:06.515Z,1591390986.515 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T21:03:06.515Z,1591390986.515 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T21:03:07.705Z,1591390987.705 [DVL_micro](ERROR): Failed to parse:
:WI,+00010,-01598,+00448,+00000,A
2020-06-05T21:03:33.152Z,1591391013.152 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:03:33.152Z,1591391013.152 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0000.0,148

2020-06-05T21:04:31.378Z,1591391071.378 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-06-05T21:04:31.378Z,1591391071.378 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:04:31.405Z,1591391071.405 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:04:31.803Z,1591391071.803 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:04:31.803Z,1591391071.803 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-06-05T21:04:39.414Z,1591391079.414 [DVL_micro](ERROR): Failed to parse:
73,-01514,+00183,+00000,I
2020-06-05T21:05:48.099Z,1591391148.099 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T21:05:48.099Z,1591391148.099 [DVL_micro](ERROR): Failed to parse:
:BI,+01566,-00133,+00445,
2020-06-05T21:06:51.925Z,1591391211.925 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:06:51.925Z,1591391211.925 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+0.0,1489.0,000
2020-06-05T21:07:32.395Z,1591391252.395 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-06-05T21:07:32.395Z,1591391252.395 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:07:32.405Z,1591391252.405 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:07:32.771Z,1591391252.771 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:07:32.771Z,1591391252.771 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-06-05T21:08:06.720Z,1591391286.720 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T21:03:06.5Z
2020-06-05T21:08:06.720Z,1591391286.720 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T21:08:06.720Z,1591391286.720 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T21:08:15.644Z,1591391295.644 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200605T203712/Courier0010.lzma
2020-06-05T21:08:16.647Z,1591391296.647 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0010.lzma.bak
2020-06-05T21:08:16.647Z,1591391296.647 [DataOverHttps](INFO): SBD MOMSN=12368145
2020-06-05T21:08:23.637Z,1591391303.637 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:08:23.637Z,1591391303.637 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,00.0,1489.0,000
2020-06-05T21:08:24.060Z,1591391304.060 [BPC1](ERROR): Battery stick #6 (s/n: 00CA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2020-06-05T21:08:24.060Z,1591391304.060 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 6.
2020-06-05T21:08:24.063Z,1591391304.063 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2020-06-05T21:08:37.741Z,1591391317.741 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20200605T203712/Express0011.lzma
2020-06-05T21:08:38.743Z,1591391318.743 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0011.lzma.bak
2020-06-05T21:08:38.743Z,1591391318.743 [DataOverHttps](INFO): SBD MOMSN=12368147
2020-06-05T21:08:43.470Z,1591391323.470 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T21:08:43.470Z,1591391323.470 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T21:08:43.470Z,1591391323.470 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T21:08:49.089Z,1591391329.089 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:08:49.089Z,1591391329.089 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.1,000.0,000
2020-06-05T21:09:32.719Z,1591391372.719 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T21:09:32.719Z,1591391372.719 [NAL9602] Data Fault, FailCount= 3
2020-06-05T21:09:32.719Z,1591391372.719 [NAL9602](ERROR): Data Fault
2020-06-05T21:09:32.815Z,1591391372.815 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T21:09:33.119Z,1591391373.119 [NAL9602](INFO): Powering down
2020-06-05T21:09:34.001Z,1591391374.001 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T21:09:34.001Z,1591391374.001 [NAL9602] No Fault, FailCount= 3
2020-06-05T21:10:03.427Z,1591391403.427 [NAL9602](INFO): Powering up NAL9602
2020-06-05T21:10:14.335Z,1591391414.335 [NAL9602](INFO): NAL9602 initialized
2020-06-05T21:10:33.398Z,1591391433.398 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-06-05T21:10:33.398Z,1591391433.398 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:10:33.407Z,1591391433.407 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:10:33.761Z,1591391433.761 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:10:33.761Z,1591391433.761 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-06-05T21:10:53.931Z,1591391453.931 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T21:10:53.931Z,1591391453.931 [DVL_micro](ERROR): Failed to parse:
:BI,+01528,+0435,+00000,I
2020-06-05T21:12:00.184Z,1591391520.184 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:12:00.185Z,1591391520.185 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000009.2,0000.0,1489.0,000
2020-06-05T21:12:35.751Z,1591391555.751 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T21:12:35.751Z,1591391555.751 [DVL_micro](ERROR): Failed to parse:
:BI,+01669,-00456,+00571,+0:BD,+00000000.00,+00000000.00,+00000000.09,000.00999.99,+99999.99,+9999.99
2020-06-05T21:13:01.194Z,1591391581.194 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T21:13:01.194Z,1591391581.194 [DVL_micro](ERROR): Failed to parse:
:BI,+01473,-0185,+00000,I
2020-06-05T21:13:24.217Z,1591391604.217 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:13:24.217Z,1591391604.217 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+19.2,000.0,000
2020-06-05T21:13:34.378Z,1591391614.378 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-06-05T21:13:34.378Z,1591391614.378 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:13:34.392Z,1591391614.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:13:34.774Z,1591391614.774 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:13:34.774Z,1591391614.774 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-06-05T21:13:44.029Z,1591391624.029 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T21:13:44.029Z,1591391624.029 [Default:CheckIn:C.Wait] Stopped
2020-06-05T21:13:44.029Z,1591391624.029 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T21:13:44.029Z,1591391624.029 [Default:CheckIn:D] Running Loop=1
2020-06-05T21:13:44.477Z,1591391624.477 [Default:CheckIn:D] Stopped
2020-06-05T21:13:44.477Z,1591391624.477 [Default:CheckIn:E] Running Loop=1
2020-06-05T21:13:44.855Z,1591391624.855 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.457123 min 
2020-06-05T21:13:44.855Z,1591391624.855 [Default:CheckIn:E] Stopped
2020-06-05T21:13:44.855Z,1591391624.855 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T21:13:44.855Z,1591391624.855 [Default:CheckIn] Stopped
2020-06-05T21:13:44.855Z,1591391624.855 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T21:13:44.856Z,1591391624.856 [Default:CheckIn](INFO): Running loop #4
2020-06-05T21:13:44.856Z,1591391624.856 [Default:CheckIn] Running Loop=4
2020-06-05T21:13:44.856Z,1591391624.856 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T21:13:44.856Z,1591391624.856 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T21:13:57.349Z,1591391637.349 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T21:13:57.349Z,1591391637.349 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,,+9999.99
2020-06-05T21:15:56.931Z,1591391756.931 [DVL_micro](ERROR): Failed to parse:
:WI,+01630,-01472,+00813,+00000,A
2020-06-05T21:16:27.630Z,1591391787.630 [DVL_micro](ERROR): Failed to parse:
67,-00044,+0000,I
2020-06-05T21:16:35.346Z,1591391795.346 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-06-05T21:16:35.346Z,1591391795.346 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:16:35.374Z,1591391795.374 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:16:35.754Z,1591391795.754 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:16:35.755Z,1591391795.755 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-06-05T21:18:24.792Z,1591391904.792 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T21:18:24.792Z,1591391904.792 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+99999.99
2020-06-05T21:18:45.065Z,1591391925.065 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T21:13:44.9Z
2020-06-05T21:18:45.065Z,1591391925.065 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T21:18:45.066Z,1591391925.066 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T21:18:54.444Z,1591391934.444 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20200605T203712/Courier0013.lzma
2020-06-05T21:18:55.447Z,1591391935.447 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0013.lzma.bak
2020-06-05T21:18:55.447Z,1591391935.447 [DataOverHttps](INFO): SBD MOMSN=12368173
2020-06-05T21:19:14.721Z,1591391954.721 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200605T203712/Express0014.lzma
2020-06-05T21:19:15.723Z,1591391955.723 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0014.lzma.bak
2020-06-05T21:19:15.723Z,1591391955.723 [DataOverHttps](INFO): SBD MOMSN=12368175
2020-06-05T21:19:20.603Z,1591391960.603 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T21:19:20.603Z,1591391960.603 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T21:19:20.603Z,1591391960.603 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T21:19:23.372Z,1591391963.372 [DVL_micro](ERROR): Failed to parse:
28,-01320+00000,A
2020-06-05T21:19:36.333Z,1591391976.333 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-06-05T21:19:36.333Z,1591391976.333 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:19:36.354Z,1591391976.354 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:19:36.750Z,1591391976.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:19:36.750Z,1591391976.750 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-06-05T21:20:16.708Z,1591392016.708 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T21:20:16.708Z,1591392016.708 [NAL9602] Data Fault, FailCount= 4
2020-06-05T21:20:16.708Z,1591392016.708 [NAL9602](ERROR): Data Fault
2020-06-05T21:20:16.768Z,1591392016.768 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T21:20:17.099Z,1591392017.099 [NAL9602](INFO): Powering down
2020-06-05T21:20:17.956Z,1591392017.956 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T21:20:17.956Z,1591392017.956 [NAL9602] No Fault, FailCount= 4
2020-06-05T21:20:47.408Z,1591392047.408 [NAL9602](INFO): Powering up NAL9602
2020-06-05T21:20:58.307Z,1591392058.307 [NAL9602](INFO): NAL9602 initialized
2020-06-05T21:21:45.985Z,1591392105.985 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:21:45.985Z,1591392105.985 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+19.5,000.0,000
2020-06-05T21:22:11.438Z,1591392131.438 [DVL_micro](ERROR): Failed to parse:
:BD,+000,+0000000000000.00,900.00
2020-06-05T21:22:25.574Z,1591392145.574 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-06-05T21:22:25.575Z,1591392145.575 [DropWeight] Hardware Fault, FailCount= 1
2020-06-05T21:22:25.575Z,1591392145.575 [DropWeight](ERROR): Hardware Fault
2020-06-05T21:22:25.606Z,1591392145.606 [CBIT](INFO): Critical error at 20200605T212225
2020-06-05T21:22:25.608Z,1591392145.608 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-06-05T21:22:25.608Z,1591392145.608 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-06-05T21:22:26.017Z,1591392146.017 [CBIT](INFO): Critical error at 20200605T212225
2020-06-05T21:22:36.887Z,1591392156.887 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T21:22:36.887Z,1591392156.887 [DVL_micro](ERROR): Failed to parse:
:BI,+0105,+00783,+00000,I
2020-06-05T21:22:37.318Z,1591392157.318 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-06-05T21:22:37.318Z,1591392157.318 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:22:37.328Z,1591392157.328 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:22:37.732Z,1591392157.732 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:22:37.732Z,1591392157.732 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-06-05T21:23:10.021Z,1591392190.021 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T21:23:10.021Z,1591392190.021 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.999,+9999.999
2020-06-05T21:24:03.343Z,1591392243.343 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:24:03.343Z,1591392243.343 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.,1489.0,,+00504,-1162,+000I,+00504+01162,+00000,I
2020-06-05T21:24:21.170Z,1591392261.170 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T21:24:21.170Z,1591392261.170 [Default:CheckIn:C.Wait] Stopped
2020-06-05T21:24:21.170Z,1591392261.170 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T21:24:21.170Z,1591392261.170 [Default:CheckIn:D] Running Loop=1
2020-06-05T21:24:21.557Z,1591392261.557 [Default:CheckIn:D] Stopped
2020-06-05T21:24:21.557Z,1591392261.557 [Default:CheckIn:E] Running Loop=1
2020-06-05T21:24:21.950Z,1591392261.950 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.075122 min 
2020-06-05T21:24:21.950Z,1591392261.950 [Default:CheckIn:E] Stopped
2020-06-05T21:24:21.950Z,1591392261.950 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T21:24:21.950Z,1591392261.950 [Default:CheckIn] Stopped
2020-06-05T21:24:21.950Z,1591392261.950 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T21:24:21.950Z,1591392261.950 [Default:CheckIn](INFO): Running loop #5
2020-06-05T21:24:21.950Z,1591392261.950 [Default:CheckIn] Running Loop=5
2020-06-05T21:24:21.951Z,1591392261.951 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T21:24:21.951Z,1591392261.951 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T21:24:26.375Z,1591392266.375 [DVL_micro](ERROR): Failed to parse:
:WI,+01218,+00200,+00680,+00000,A
2020-06-05T21:25:01.932Z,1591392301.932 [DVL_micro](ERROR): Failed to parse:99.99,+9999.99,+9999.99,+9999.99
2020-06-05T21:25:27.377Z,1591392327.377 [DVL_micro](ERROR): Failed to parse:
:WI,+01424,-01346,+00810,

2020-06-05T21:25:38.322Z,1591392338.322 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-06-05T21:25:38.322Z,1591392338.322 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:25:38.348Z,1591392338.348 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:25:38.735Z,1591392338.735 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:25:38.735Z,1591392338.735 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-06-05T21:26:23.535Z,1591392383.535 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T21:26:23.535Z,1591392383.535 [DVL_micro](ERROR): Failed to parse:
:BI,+0140,+00622,+00000,I
2020-06-05T21:26:48.980Z,1591392408.980 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:26:48.980Z,1591392408.980 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000019.5,0000.0,10
2020-06-05T21:27:14.438Z,1591392434.438 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T21:27:14.439Z,1591392434.439 [DVL_micro](ERROR): Failed to parse:
:BI,+0213,+0000,I
2020-06-05T21:27:49.987Z,1591392469.987 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:27:49.987Z,1591392469.987 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+190,1489.0,,+02056,-0819,+00BI,+02056+00819,+

2020-06-05T21:28:39.302Z,1591392519.302 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-06-05T21:28:39.302Z,1591392519.302 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:28:39.312Z,1591392519.312 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:28:39.746Z,1591392519.746 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:28:39.746Z,1591392519.746 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-06-05T21:29:22.150Z,1591392562.150 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T21:24:21.0Z
2020-06-05T21:29:22.150Z,1591392562.150 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T21:29:22.150Z,1591392562.150 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T21:29:30.776Z,1591392570.776 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20200605T203712/Courier0016.lzma
2020-06-05T21:29:31.779Z,1591392571.779 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0016.lzma.bak
2020-06-05T21:29:31.779Z,1591392571.779 [DataOverHttps](INFO): SBD MOMSN=12368189
2020-06-05T21:29:51.936Z,1591392591.936 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200605T203712/Express0017.lzma
2020-06-05T21:29:52.939Z,1591392592.939 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0017.lzma.bak
2020-06-05T21:29:52.939Z,1591392592.939 [DataOverHttps](INFO): SBD MOMSN=12368193
2020-06-05T21:29:57.722Z,1591392597.722 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T21:29:57.722Z,1591392597.722 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T21:29:57.722Z,1591392597.722 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T21:31:01.507Z,1591392661.507 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T21:31:01.507Z,1591392661.507 [NAL9602] Data Fault, FailCount= 5
2020-06-05T21:31:01.507Z,1591392661.507 [NAL9602](ERROR): Data Fault
2020-06-05T21:31:01.565Z,1591392661.565 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T21:31:01.565Z,1591392661.565 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-06-05T21:31:01.907Z,1591392661.907 [NAL9602](INFO): Powering down
2020-06-05T21:31:01.965Z,1591392661.965 [CBIT](INFO): Critical error at 20200605T213101
2020-06-05T21:31:40.311Z,1591392700.311 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-06-05T21:31:40.311Z,1591392700.311 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:31:40.341Z,1591392700.341 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:31:40.725Z,1591392700.725 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:31:40.725Z,1591392700.725 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-06-05T21:33:01.547Z,1591392781.547 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T21:33:01.547Z,1591392781.547 [NAL9602] No Fault, FailCount= 5
2020-06-05T21:33:01.908Z,1591392781.908 [NAL9602](INFO): Powering up NAL9602
2020-06-05T21:33:12.807Z,1591392792.807 [NAL9602](INFO): NAL9602 initialized
2020-06-05T21:33:49.180Z,1591392829.180 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2020-06-05T21:34:14.624Z,1591392854.624 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:34:14.625Z,1591392854.625 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0000.,000
2020-06-05T21:34:40.073Z,1591392880.073 [DVL_micro](ERROR): Failed to parse:
:WI,+01278,-02610,+01151,+00000,A
2020-06-05T21:34:41.318Z,1591392881.318 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-06-05T21:34:41.318Z,1591392881.318 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:34:41.328Z,1591392881.328 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:34:41.726Z,1591392881.726 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:34:41.726Z,1591392881.726 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-06-05T21:34:58.280Z,1591392898.280 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T21:34:58.280Z,1591392898.280 [Default:CheckIn:C.Wait] Stopped
2020-06-05T21:34:58.280Z,1591392898.280 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T21:34:58.280Z,1591392898.280 [Default:CheckIn:D] Running Loop=1
2020-06-05T21:34:58.691Z,1591392898.691 [Default:CheckIn:D] Stopped
2020-06-05T21:34:58.691Z,1591392898.691 [Default:CheckIn:E] Running Loop=1
2020-06-05T21:34:59.105Z,1591392899.105 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.694031 min 
2020-06-05T21:34:59.106Z,1591392899.106 [Default:CheckIn:E] Stopped
2020-06-05T21:34:59.106Z,1591392899.106 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T21:34:59.106Z,1591392899.106 [Default:CheckIn] Stopped
2020-06-05T21:34:59.106Z,1591392899.106 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T21:34:59.106Z,1591392899.106 [Default:CheckIn](INFO): Running loop #6
2020-06-05T21:34:59.106Z,1591392899.106 [Default:CheckIn] Running Loop=6
2020-06-05T21:34:59.106Z,1591392899.106 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T21:34:59.106Z,1591392899.106 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T21:35:05.528Z,1591392905.528 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:35:05.528Z,1591392905.528 [DVL_micro](ERROR): Failed to parse:
:TS,000000035.0,+19.7,0000.0,1489.0,000
2020-06-05T21:36:31.992Z,1591392991.992 [DVL_micro](ERROR): Failed to parse:
9.99,+9999.99,,+9999.99
2020-06-05T21:36:55.012Z,1591393015.012 [DVL_micro](ERROR): Failed to parse:
:000000000,35.0,+19.8,0000.0,1489.0,000
2020-06-05T21:37:20.466Z,1591393040.466 [DVL_micro](ERROR): Failed to parse:
96,-00046,+00945,+00000,I
2020-06-05T21:37:25.746Z,1591393045.746 [CBIT](INFO): Clearing failed state for component DropWeight
2020-06-05T21:37:25.746Z,1591393045.746 [DropWeight] No Fault, FailCount= 1
2020-06-05T21:37:42.303Z,1591393062.303 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2020-06-05T21:37:42.303Z,1591393062.303 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:37:42.313Z,1591393062.313 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:37:42.718Z,1591393062.718 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:37:42.718Z,1591393062.718 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2020-06-05T21:39:59.271Z,1591393199.271 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T21:34:59.1Z
2020-06-05T21:39:59.271Z,1591393199.271 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T21:39:59.271Z,1591393199.271 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T21:40:08.028Z,1591393208.028 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20200605T203712/Courier0019.lzma
2020-06-05T21:40:09.030Z,1591393209.030 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0019.lzma.bak
2020-06-05T21:40:09.031Z,1591393209.031 [DataOverHttps](INFO): SBD MOMSN=12368206
2020-06-05T21:40:28.739Z,1591393228.739 [DVL_micro](ERROR): Failed to parse:
9.99,+9999.99,+9999.99,+9999.99
2020-06-05T21:40:29.904Z,1591393229.904 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200605T203712/Express0020.lzma
2020-06-05T21:40:30.907Z,1591393230.907 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0020.lzma.bak
2020-06-05T21:40:30.907Z,1591393230.907 [DataOverHttps](INFO): SBD MOMSN=12368209
2020-06-05T21:40:35.658Z,1591393235.658 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T21:40:35.658Z,1591393235.658 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T21:40:35.658Z,1591393235.658 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T21:40:43.313Z,1591393243.313 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2020-06-05T21:40:43.313Z,1591393243.313 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:40:43.331Z,1591393243.331 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:40:43.714Z,1591393243.714 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:40:43.714Z,1591393243.714 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2020-06-05T21:41:24.957Z,1591393284.957 [DVL_micro](ERROR): DVL uart error: serial timeout
2020-06-05T21:41:24.957Z,1591393284.957 [DVL_micro] Communications Fault, FailCount= 1
2020-06-05T21:41:24.957Z,1591393284.957 [DVL_micro](ERROR): Communications Fault
2020-06-05T21:41:24.957Z,1591393284.957 [DVL_micro](ERROR): Failed to parse:
2020-06-05T21:41:25.055Z,1591393285.055 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-06-05T21:41:25.349Z,1591393285.349 [DVL_micro](INFO): Powering down
2020-06-05T21:41:26.150Z,1591393286.150 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-06-05T21:41:26.150Z,1591393286.150 [DVL_micro] No Fault, FailCount= 1
2020-06-05T21:42:17.416Z,1591393337.416 [DVL_micro](ERROR): Failed to parse:000000000019.8,000.0,000
2020-06-05T21:42:42.875Z,1591393362.875 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T21:42:42.875Z,1591393362.875 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+99999.99
2020-06-05T21:43:15.184Z,1591393395.184 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T21:43:15.184Z,1591393395.184 [NAL9602] Data Fault, FailCount= 1
2020-06-05T21:43:15.184Z,1591393395.184 [NAL9602](ERROR): Data Fault
2020-06-05T21:43:15.220Z,1591393395.220 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T21:43:15.605Z,1591393395.605 [NAL9602](INFO): Powering down
2020-06-05T21:43:16.444Z,1591393396.444 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T21:43:16.444Z,1591393396.444 [NAL9602] No Fault, FailCount= 1
2020-06-05T21:43:44.300Z,1591393424.300 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2020-06-05T21:43:44.300Z,1591393424.300 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:43:44.310Z,1591393424.310 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:43:44.722Z,1591393424.722 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:43:44.722Z,1591393424.722 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2020-06-05T21:43:45.902Z,1591393425.902 [NAL9602](INFO): Powering up NAL9602
2020-06-05T21:43:56.799Z,1591393436.799 [NAL9602](INFO): NAL9602 initialized
2020-06-05T21:45:23.254Z,1591393523.254 [DVL_micro](ERROR): Failed to parse:
:BD,+0000000000000.00,+000,9999.0
2020-06-05T21:45:36.230Z,1591393536.230 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T21:45:36.230Z,1591393536.230 [Default:CheckIn:C.Wait] Stopped
2020-06-05T21:45:36.230Z,1591393536.230 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T21:45:36.230Z,1591393536.230 [Default:CheckIn:D] Running Loop=1
2020-06-05T21:45:36.631Z,1591393536.631 [Default:CheckIn:D] Stopped
2020-06-05T21:45:36.631Z,1591393536.631 [Default:CheckIn:E] Running Loop=1
2020-06-05T21:45:37.015Z,1591393537.015 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.326359 min 
2020-06-05T21:45:37.015Z,1591393537.015 [Default:CheckIn:E] Stopped
2020-06-05T21:45:37.015Z,1591393537.016 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T21:45:37.016Z,1591393537.016 [Default:CheckIn] Stopped
2020-06-05T21:45:37.016Z,1591393537.016 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T21:45:37.016Z,1591393537.016 [Default:CheckIn](INFO): Running loop #7
2020-06-05T21:45:37.016Z,1591393537.016 [Default:CheckIn] Running Loop=7
2020-06-05T21:45:37.016Z,1591393537.016 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T21:45:37.016Z,1591393537.016 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T21:46:26.683Z,1591393586.683 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T21:46:26.683Z,1591393586.683 [DVL_micro](ERROR): Failed to parse:
:BI,+0129,+00705,+00000,I
2020-06-05T21:46:31.937Z,1591393591.937 [DVL_micro](ERROR): Failed to parse:
:WI,+01538,-00508,+00729,+00000,01538,-0729,+00000,I
2020-06-05T21:46:45.329Z,1591393605.329 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2020-06-05T21:46:45.329Z,1591393605.329 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:46:45.350Z,1591393605.350 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:46:45.745Z,1591393605.745 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:46:45.745Z,1591393605.745 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2020-06-05T21:46:59.820Z,1591393619.820 [DVL_micro](ERROR): Failed to parse:
:R99,+9999.99,,+9999.99
2020-06-05T21:47:48.298Z,1591393668.298 [DVL_micro](ERROR): Failed to parse:
:WI,+01409,-00002,+00666,+00000,A
2020-06-05T21:49:14.746Z,1591393754.746 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:49:14.746Z,1591393754.746 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.0,00000,000
2020-06-05T21:49:40.208Z,1591393780.208 [DVL_micro](ERROR): Failed to parse:99.99,++9999.99,
2020-06-05T21:49:46.283Z,1591393786.283 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2020-06-05T21:49:46.283Z,1591393786.283 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:49:46.294Z,1591393786.294 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:49:46.708Z,1591393786.708 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:49:46.708Z,1591393786.708 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2020-06-05T21:50:03.226Z,1591393803.226 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T21:50:03.226Z,1591393803.226 [DVL_micro](ERROR): Failed to parse:
:BI,+01669,-01396,+00925I
2020-06-05T21:50:37.207Z,1591393837.207 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T21:45:37.0Z
2020-06-05T21:50:37.207Z,1591393837.207 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T21:50:37.207Z,1591393837.207 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T21:50:46.168Z,1591393846.168 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200605T203712/Courier0022.lzma
2020-06-05T21:50:47.171Z,1591393847.171 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0022.lzma.bak
2020-06-05T21:50:47.171Z,1591393847.171 [DataOverHttps](INFO): SBD MOMSN=12368225
2020-06-05T21:51:06.732Z,1591393866.732 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200605T203712/Express0023.lzma
2020-06-05T21:51:07.735Z,1591393867.735 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0023.lzma.bak
2020-06-05T21:51:07.735Z,1591393867.735 [DataOverHttps](INFO): SBD MOMSN=12368227
2020-06-05T21:51:12.354Z,1591393872.354 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T21:51:12.354Z,1591393872.354 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T21:51:12.354Z,1591393872.354 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T21:51:22.017Z,1591393882.017 [DVL_micro](ERROR): Failed to parse::SA,-01.11,-06.76,040.4
2020-06-05T21:52:47.274Z,1591393967.274 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2020-06-05T21:52:47.274Z,1591393967.274 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:52:47.284Z,1591393967.284 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:52:47.698Z,1591393967.698 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:52:47.698Z,1591393967.698 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2020-06-05T21:53:03.820Z,1591393983.820 [DVL_micro](ERROR): Failed to parse:99.99,,+9999.999
2020-06-05T21:53:29.271Z,1591394009.271 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:53:29.271Z,1591394009.271 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.0,0000.,000
2020-06-05T21:53:52.292Z,1591394032.292 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T21:53:52.293Z,1591394032.293 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,00.0,1489.0,000
2020-06-05T21:53:57.951Z,1591394037.951 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T21:53:57.951Z,1591394037.951 [NAL9602] Data Fault, FailCount= 2
2020-06-05T21:53:57.951Z,1591394037.951 [NAL9602](ERROR): Data Fault
2020-06-05T21:53:58.001Z,1591394038.001 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T21:53:58.351Z,1591394038.351 [NAL9602](INFO): Powering down
2020-06-05T21:53:59.204Z,1591394039.204 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T21:53:59.204Z,1591394039.204 [NAL9602] No Fault, FailCount= 2
2020-06-05T21:54:28.663Z,1591394068.663 [NAL9602](INFO): Powering up NAL9602
2020-06-05T21:54:39.559Z,1591394079.559 [NAL9602](INFO): NAL9602 initialized
2020-06-05T21:55:08.655Z,1591394108.655 [DVL_micro](ERROR): Failed to parse:
:BI,-00037,+00077,+00900,+00000,I
2020-06-05T21:55:34.100Z,1591394134.100 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T21:55:34.100Z,1591394134.100 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.999,+9999..99
2020-06-05T21:55:48.263Z,1591394148.263 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2020-06-05T21:55:48.263Z,1591394148.263 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:55:48.273Z,1591394148.273 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:55:48.695Z,1591394148.695 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:55:48.695Z,1591394148.695 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2020-06-05T21:56:12.939Z,1591394172.939 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T21:56:12.939Z,1591394172.939 [Default:CheckIn:C.Wait] Stopped
2020-06-05T21:56:12.939Z,1591394172.939 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T21:56:12.939Z,1591394172.939 [Default:CheckIn:D] Running Loop=1
2020-06-05T21:56:13.330Z,1591394173.330 [Default:CheckIn:D] Stopped
2020-06-05T21:56:13.330Z,1591394173.330 [Default:CheckIn:E] Running Loop=1
2020-06-05T21:56:13.737Z,1591394173.737 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.938013 min 
2020-06-05T21:56:13.738Z,1591394173.738 [Default:CheckIn:E] Stopped
2020-06-05T21:56:13.738Z,1591394173.738 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T21:56:13.738Z,1591394173.738 [Default:CheckIn] Stopped
2020-06-05T21:56:13.738Z,1591394173.738 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T21:56:13.738Z,1591394173.738 [Default:CheckIn](INFO): Running loop #8
2020-06-05T21:56:13.738Z,1591394173.738 [Default:CheckIn] Running Loop=8
2020-06-05T21:56:13.738Z,1591394173.738 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T21:56:13.738Z,1591394173.738 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T21:57:33.697Z,1591394253.697 [DVL_micro](ERROR): Failed to parse:
00000000020.1,0000.0,1489.0,000
2020-06-05T21:58:27.024Z,1591394307.024 [DVL_micro](ERROR): Failed to parse:
:WI,-00072,-01474,+01288,+00000,A
2020-06-05T21:58:49.282Z,1591394329.282 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27
2020-06-05T21:58:49.282Z,1591394329.282 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T21:58:49.292Z,1591394329.292 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T21:58:49.698Z,1591394329.698 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T21:58:49.698Z,1591394329.698 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27
2020-06-05T21:58:50.049Z,1591394330.049 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T21:58:50.049Z,1591394330.049 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2020-06-05T21:59:17.935Z,1591394357.935 [DVL_micro](ERROR): Failed to parse:
:R99,+9999.99,+9999.99,+9999.99
2020-06-05T22:00:24.173Z,1591394424.173 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T22:00:24.173Z,1591394424.173 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+99999.99
2020-06-05T22:01:13.897Z,1591394473.897 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T21:56:13.7Z
2020-06-05T22:01:13.897Z,1591394473.897 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T22:01:13.897Z,1591394473.897 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T22:01:22.540Z,1591394482.540 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200605T203712/Courier0025.lzma
2020-06-05T22:01:23.543Z,1591394483.543 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0025.lzma.bak
2020-06-05T22:01:23.543Z,1591394483.543 [DataOverHttps](INFO): SBD MOMSN=12368241
2020-06-05T22:01:40.532Z,1591394500.532 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-05T22:01:44.168Z,1591394504.168 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20200605T203712/Express0026.lzma
2020-06-05T22:01:45.171Z,1591394505.171 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0026.lzma.bak
2020-06-05T22:01:45.171Z,1591394505.171 [DataOverHttps](INFO): SBD MOMSN=12368243
2020-06-05T22:01:49.879Z,1591394509.879 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T22:01:49.879Z,1591394509.879 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T22:01:49.879Z,1591394509.879 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T22:01:50.276Z,1591394510.276 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28
2020-06-05T22:01:50.276Z,1591394510.276 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:01:50.299Z,1591394510.299 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:01:50.747Z,1591394510.747 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:01:50.747Z,1591394510.747 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28
2020-06-05T22:02:05.988Z,1591394525.988 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:02:05.988Z,1591394525.988 [DVL_micro](ERROR): Failed to parse:
:TS,000000005.0,+20.1,000.0,000
2020-06-05T22:04:13.240Z,1591394653.240 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:04:13.240Z,1591394653.240 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+20.,1489.0,000
2020-06-05T22:04:38.697Z,1591394678.697 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T22:04:38.697Z,1591394678.697 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999..99
2020-06-05T22:04:40.711Z,1591394680.711 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T22:04:40.711Z,1591394680.711 [NAL9602] Data Fault, FailCount= 3
2020-06-05T22:04:40.711Z,1591394680.711 [NAL9602](ERROR): Data Fault
2020-06-05T22:04:40.766Z,1591394680.766 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T22:04:41.125Z,1591394681.125 [NAL9602](INFO): Powering down
2020-06-05T22:04:41.962Z,1591394681.962 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T22:04:41.962Z,1591394681.962 [NAL9602] No Fault, FailCount= 3
2020-06-05T22:04:51.253Z,1591394691.253 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29
2020-06-05T22:04:51.253Z,1591394691.253 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:04:51.263Z,1591394691.263 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:04:51.655Z,1591394691.655 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:04:51.656Z,1591394691.656 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29
2020-06-05T22:05:04.143Z,1591394704.143 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-05T22:05:11.433Z,1591394711.433 [NAL9602](INFO): Powering up NAL9602
2020-06-05T22:05:22.319Z,1591394722.319 [NAL9602](INFO): NAL9602 initialized
2020-06-05T22:05:55.057Z,1591394755.057 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T22:05:55.057Z,1591394755.057 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,,+9999.99
2020-06-05T22:06:22.931Z,1591394782.931 [DVL_micro](ERROR): Failed to parse:
:BD,+.00,+00000000.00,+00000000.00,9999.99,000.00999.99,++9999.99,+9999.99
2020-06-05T22:06:48.378Z,1591394808.378 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:06:48.378Z,1591394808.378 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+2.0,1489.0,000
2020-06-05T22:06:50.454Z,1591394810.454 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T22:06:50.454Z,1591394810.454 [Default:CheckIn:C.Wait] Stopped
2020-06-05T22:06:50.455Z,1591394810.455 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T22:06:50.455Z,1591394810.455 [Default:CheckIn:D] Running Loop=1
2020-06-05T22:06:50.843Z,1591394810.843 [Default:CheckIn:D] Stopped
2020-06-05T22:06:50.843Z,1591394810.843 [Default:CheckIn:E] Running Loop=1
2020-06-05T22:06:51.238Z,1591394811.238 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.563224 min 
2020-06-05T22:06:51.238Z,1591394811.238 [Default:CheckIn:E] Stopped
2020-06-05T22:06:51.239Z,1591394811.239 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T22:06:51.239Z,1591394811.239 [Default:CheckIn] Stopped
2020-06-05T22:06:51.239Z,1591394811.239 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T22:06:51.239Z,1591394811.239 [Default:CheckIn](INFO): Running loop #9
2020-06-05T22:06:51.239Z,1591394811.239 [Default:CheckIn] Running Loop=9
2020-06-05T22:06:51.239Z,1591394811.239 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T22:06:51.239Z,1591394811.239 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T22:07:13.833Z,1591394833.833 [DVL_micro](ERROR): Failed to parse:
:WI,+01652,-01745,+00922,+00000,A1652,-01745,+00922,+00000,I
2020-06-05T22:07:27.162Z,1591394847.162 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-06-05T22:07:27.162Z,1591394847.162 [DropWeight] Hardware Fault, FailCount= 1
2020-06-05T22:07:27.162Z,1591394847.162 [DropWeight](ERROR): Hardware Fault
2020-06-05T22:07:27.246Z,1591394847.246 [CBIT](INFO): Critical error at 20200605T220727
2020-06-05T22:07:27.248Z,1591394847.248 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-06-05T22:07:27.248Z,1591394847.248 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-06-05T22:07:27.617Z,1591394847.617 [CBIT](INFO): Critical error at 20200605T220727
2020-06-05T22:07:52.228Z,1591394872.228 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 30
2020-06-05T22:07:52.228Z,1591394872.228 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:07:52.258Z,1591394872.258 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:07:52.647Z,1591394872.647 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:07:52.647Z,1591394872.647 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 30
2020-06-05T22:07:57.059Z,1591394877.059 [DVL_micro](ERROR): Failed to parse:
:WI,-00417,-00184,+01061,+00000,A
2020-06-05T22:08:45.537Z,1591394925.537 [DVL_micro](ERROR): Failed to parse:
:WI,+02557,-02579,+00949,+00000,A
2020-06-05T22:09:18.664Z,1591394958.664 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:09:18.664Z,1591394958.664 [DVL_micro](ERROR): Failed to parse::TS,000000,35.0,+20.,1489.0,000
2020-06-05T22:09:44.123Z,1591394984.123 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T22:09:44.123Z,1591394984.123 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2020-06-05T22:10:12.003Z,1591395012.003 [DVL_micro](ERROR): Failed to parse:
:SA,-01.15,-06.81,040.4
2020-06-05T22:10:35.029Z,1591395035.029 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:10:35.030Z,1591395035.030 [DVL_micro](ERROR): Failed to parse:
:TS0000000,35.0,+20.2,0000.0,1489.0,000
2020-06-05T22:10:53.226Z,1591395053.226 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 31
2020-06-05T22:10:53.226Z,1591395053.226 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:10:53.256Z,1591395053.256 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:10:53.643Z,1591395053.643 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:10:53.643Z,1591395053.643 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 31
2020-06-05T22:11:00.484Z,1591395060.484 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:11:00.485Z,1591395060.485 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.2,0000.0,14
2020-06-05T22:11:25.941Z,1591395085.941 [DVL_micro](ERROR): Failed to parse:
:WI,+00495,-00907,+01142,+00000,A
2020-06-05T22:11:51.446Z,1591395111.446 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T22:06:51.2Z
2020-06-05T22:11:51.446Z,1591395111.446 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T22:11:51.446Z,1591395111.446 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T22:12:00.240Z,1591395120.240 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20200605T203712/Courier0028.lzma
2020-06-05T22:12:01.243Z,1591395121.243 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0028.lzma.bak
2020-06-05T22:12:01.243Z,1591395121.243 [DataOverHttps](INFO): SBD MOMSN=12368255
2020-06-05T22:12:09.179Z,1591395129.179 [DVL_micro](ERROR): Failed to parse:
:000000000,32,0000.0000
2020-06-05T22:12:19.681Z,1591395139.681 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20200605T203712/Express0029.lzma
2020-06-05T22:12:20.683Z,1591395140.683 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0029.lzma.bak
2020-06-05T22:12:20.683Z,1591395140.683 [DataOverHttps](INFO): SBD MOMSN=12368259
2020-06-05T22:12:26.303Z,1591395146.303 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T22:12:26.303Z,1591395146.303 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T22:12:26.303Z,1591395146.303 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T22:13:28.083Z,1591395208.083 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-05T22:13:53.542Z,1591395233.542 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T22:13:53.543Z,1591395233.543 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,,+9999.99
2020-06-05T22:13:54.393Z,1591395234.393 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 32
2020-06-05T22:13:54.393Z,1591395234.393 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:13:54.403Z,1591395234.403 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:13:54.778Z,1591395234.778 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:13:54.778Z,1591395234.778 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 32
2020-06-05T22:14:36.774Z,1591395276.774 [DVL_micro](ERROR): only read 1 of 4 data items
2020-06-05T22:14:36.774Z,1591395276.774 [DVL_micro](ERROR): Failed to parse:
:RD,.99
2020-06-05T22:15:02.213Z,1591395302.213 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:15:02.213Z,1591395302.213 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,.2,0000.0,1489.0,000
2020-06-05T22:15:25.643Z,1591395325.643 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T22:15:25.643Z,1591395325.643 [NAL9602] Data Fault, FailCount= 4
2020-06-05T22:15:25.643Z,1591395325.643 [NAL9602](ERROR): Data Fault
2020-06-05T22:15:25.692Z,1591395325.692 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T22:15:26.051Z,1591395326.051 [NAL9602](INFO): Powering down
2020-06-05T22:15:26.900Z,1591395326.900 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T22:15:26.900Z,1591395326.900 [NAL9602] No Fault, FailCount= 4
2020-06-05T22:15:56.347Z,1591395356.347 [NAL9602](INFO): Powering up NAL9602
2020-06-05T22:16:07.255Z,1591395367.255 [NAL9602](INFO): NAL9602 initialized
2020-06-05T22:16:18.577Z,1591395378.577 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:16:18.577Z,1591395378.577 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+2.0,1489.0,000
2020-06-05T22:16:55.374Z,1591395415.374 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 33
2020-06-05T22:16:55.374Z,1591395415.374 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:16:55.384Z,1591395415.384 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:16:55.836Z,1591395415.836 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:16:55.837Z,1591395415.837 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 33
2020-06-05T22:17:26.883Z,1591395446.883 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T22:17:26.883Z,1591395446.883 [Default:CheckIn:C.Wait] Stopped
2020-06-05T22:17:26.883Z,1591395446.883 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T22:17:26.883Z,1591395446.883 [Default:CheckIn:D] Running Loop=1
2020-06-05T22:17:27.310Z,1591395447.310 [Default:CheckIn:D] Stopped
2020-06-05T22:17:27.310Z,1591395447.310 [Default:CheckIn:E] Running Loop=1
2020-06-05T22:17:27.677Z,1591395447.677 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.171012 min 
2020-06-05T22:17:27.678Z,1591395447.678 [Default:CheckIn:E] Stopped
2020-06-05T22:17:27.678Z,1591395447.678 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T22:17:27.678Z,1591395447.678 [Default:CheckIn] Stopped
2020-06-05T22:17:27.678Z,1591395447.678 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T22:17:27.678Z,1591395447.678 [Default:CheckIn](INFO): Running loop #10
2020-06-05T22:17:27.678Z,1591395447.678 [Default:CheckIn] Running Loop=10
2020-06-05T22:17:27.678Z,1591395447.678 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T22:17:27.678Z,1591395447.678 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T22:17:34.936Z,1591395454.936 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T22:17:34.936Z,1591395454.936 [DVL_micro](ERROR): Failed to parse:
:BI,+00803,-01576,+01207,+

2020-06-05T22:18:18.152Z,1591395498.152 [DVL_micro](ERROR): Failed to parse:00000000000,35.0,+20.2,0000.0,1489.0,000
2020-06-05T22:18:51.283Z,1591395531.283 [DVL_micro](ERROR): Failed to parse:
:B,+00282,+01055,+00000,I
2020-06-05T22:19:16.740Z,1591395556.740 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T22:19:16.740Z,1591395556.740 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2020-06-05T22:19:42.192Z,1591395582.192 [DVL_micro](ERROR): only read 1 of 4 data items
2020-06-05T22:19:42.192Z,1591395582.192 [DVL_micro](ERROR): Failed to parse:
:RD,+9999..99,+9999.99,+9999.99
2020-06-05T22:19:56.366Z,1591395596.366 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 34
2020-06-05T22:19:56.366Z,1591395596.366 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:19:56.391Z,1591395596.391 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:19:56.778Z,1591395596.778 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:19:56.778Z,1591395596.778 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 34
2020-06-05T22:22:27.557Z,1591395747.557 [CBIT](INFO): Clearing failed state for component DropWeight
2020-06-05T22:22:27.557Z,1591395747.557 [DropWeight] No Fault, FailCount= 1
2020-06-05T22:22:27.869Z,1591395747.869 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T22:17:27.7Z
2020-06-05T22:22:27.869Z,1591395747.869 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T22:22:27.869Z,1591395747.869 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T22:22:36.672Z,1591395756.672 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200605T203712/Courier0031.lzma
2020-06-05T22:22:37.675Z,1591395757.675 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0031.lzma.bak
2020-06-05T22:22:37.675Z,1591395757.675 [DataOverHttps](INFO): SBD MOMSN=12368323
2020-06-05T22:22:57.032Z,1591395777.032 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20200605T203712/Express0032.lzma
2020-06-05T22:22:57.439Z,1591395777.439 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 35
2020-06-05T22:22:57.439Z,1591395777.439 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:22:57.449Z,1591395777.449 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:22:57.883Z,1591395777.883 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:22:57.883Z,1591395777.883 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 35
2020-06-05T22:22:58.035Z,1591395778.035 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0032.lzma.bak
2020-06-05T22:22:58.035Z,1591395778.035 [DataOverHttps](INFO): SBD MOMSN=12368325
2020-06-05T22:23:02.690Z,1591395782.690 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T22:23:02.690Z,1591395782.690 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T22:23:02.690Z,1591395782.690 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T22:23:51.550Z,1591395831.550 [DVL_micro](ERROR): Failed to parse:
:WI,+01783,+00206,+00651,+00000,A
2020-06-05T22:24:17.005Z,1591395857.005 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:24:17.005Z,1591395857.005 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,32,0000.0,1489.0,000
2020-06-05T22:25:33.365Z,1591395933.365 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:25:33.365Z,1591395933.365 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.2,000.0,000
2020-06-05T22:25:58.474Z,1591395958.474 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 36
2020-06-05T22:25:58.474Z,1591395958.474 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:25:58.484Z,1591395958.484 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:25:58.855Z,1591395958.855 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:25:58.855Z,1591395958.855 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 36
2020-06-05T22:26:01.239Z,1591395961.239 [DVL_micro](ERROR): Failed to parse:
:WI,+00236,+00932,+00769,+00000,A
2020-06-05T22:26:08.503Z,1591395968.503 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T22:26:08.503Z,1591395968.503 [NAL9602] Data Fault, FailCount= 5
2020-06-05T22:26:08.503Z,1591395968.503 [NAL9602](ERROR): Data Fault
2020-06-05T22:26:08.569Z,1591395968.569 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T22:26:08.569Z,1591395968.569 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-06-05T22:26:08.916Z,1591395968.916 [NAL9602](INFO): Powering down
2020-06-05T22:26:08.982Z,1591395968.982 [CBIT](INFO): Critical error at 20200605T222608
2020-06-05T22:27:15.169Z,1591396035.169 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T22:27:15.169Z,1591396035.169 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2020-06-05T22:27:40.621Z,1591396060.621 [DVL_micro](ERROR): Failed to parse:
:WI,+05824,-01102,+00131,+00000,A
2020-06-05T22:28:03.296Z,1591396083.296 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T22:28:03.296Z,1591396083.296 [Default:CheckIn:C.Wait] Stopped
2020-06-05T22:28:03.296Z,1591396083.296 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T22:28:03.296Z,1591396083.296 [Default:CheckIn:D] Running Loop=1
2020-06-05T22:28:03.665Z,1591396083.665 [Default:CheckIn:D] Stopped
2020-06-05T22:28:03.665Z,1591396083.665 [Default:CheckIn:E] Running Loop=1
2020-06-05T22:28:04.073Z,1591396084.073 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.776921 min 
2020-06-05T22:28:04.073Z,1591396084.073 [Default:CheckIn:E] Stopped
2020-06-05T22:28:04.073Z,1591396084.073 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T22:28:04.073Z,1591396084.073 [Default:CheckIn] Stopped
2020-06-05T22:28:04.073Z,1591396084.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T22:28:04.073Z,1591396084.073 [Default:CheckIn](INFO): Running loop #11
2020-06-05T22:28:04.073Z,1591396084.073 [Default:CheckIn] Running Loop=11
2020-06-05T22:28:04.073Z,1591396084.073 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T22:28:04.073Z,1591396084.073 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T22:28:08.559Z,1591396088.559 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T22:28:08.559Z,1591396088.559 [NAL9602] No Fault, FailCount= 5
2020-06-05T22:28:08.903Z,1591396088.903 [NAL9602](INFO): Powering up NAL9602
2020-06-05T22:28:19.807Z,1591396099.807 [NAL9602](INFO): NAL9602 initialized
2020-06-05T22:28:23.855Z,1591396103.855 [DVL_micro](ERROR): Failed to parse:
:WI,+00424,+00173,+00798,+00000,A
2020-06-05T22:28:31.134Z,1591396111.134 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T22:28:31.134Z,1591396111.134 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.999
2020-06-05T22:28:59.470Z,1591396139.470 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 37
2020-06-05T22:28:59.470Z,1591396139.470 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:28:59.494Z,1591396139.494 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:28:59.866Z,1591396139.866 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:28:59.866Z,1591396139.866 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 37
2020-06-05T22:29:40.202Z,1591396180.202 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T22:29:40.202Z,1591396180.202 [DVL_micro](ERROR): Failed to parse:
:BI,+0249,+00603I
2020-06-05T22:30:05.654Z,1591396205.654 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T22:30:05.655Z,1591396205.655 [DVL_micro](ERROR): Failed to parse::BI,+0080,+01002,
2020-06-05T22:30:56.562Z,1591396256.562 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T22:30:56.562Z,1591396256.562 [DVL_micro](ERROR): Failed to parse:
:BI,00315,+00991,
2020-06-05T22:31:24.446Z,1591396284.446 [DVL_micro](ERROR): Failed to parse:99.99,+9999.99,+9999.99,+9999.99
2020-06-05T22:32:00.419Z,1591396320.419 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 38
2020-06-05T22:32:00.419Z,1591396320.419 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:32:00.428Z,1591396320.428 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:32:00.847Z,1591396320.847 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:32:00.847Z,1591396320.847 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 38
2020-06-05T22:32:05.238Z,1591396325.238 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 1444
2020-06-05T22:32:05.239Z,1591396325.239 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.999,+9999.99
2020-06-05T22:32:56.145Z,1591396376.145 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:32:56.145Z,1591396376.145 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,33,0000.0,1489.0,000
2020-06-05T22:33:04.271Z,1591396384.271 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T22:28:04.1Z
2020-06-05T22:33:04.271Z,1591396384.271 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T22:33:04.271Z,1591396384.271 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T22:33:13.204Z,1591396393.204 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20200605T203712/Courier0034.lzma
2020-06-05T22:33:14.207Z,1591396394.207 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0034.lzma.bak
2020-06-05T22:33:14.207Z,1591396394.207 [DataOverHttps](INFO): SBD MOMSN=12368350
2020-06-05T22:33:33.676Z,1591396413.676 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20200605T203712/Express0035.lzma
2020-06-05T22:33:34.679Z,1591396414.679 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0035.lzma.bak
2020-06-05T22:33:34.679Z,1591396414.679 [DataOverHttps](INFO): SBD MOMSN=12368353
2020-06-05T22:33:39.407Z,1591396419.407 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T22:33:39.407Z,1591396419.407 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T22:33:39.408Z,1591396419.408 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T22:35:01.413Z,1591396501.413 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 39
2020-06-05T22:35:01.413Z,1591396501.413 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:35:01.423Z,1591396501.423 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:35:01.828Z,1591396501.828 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:35:01.828Z,1591396501.828 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 39
2020-06-05T22:35:15.938Z,1591396515.938 [DVL_micro](ERROR): Failed to parse:
:WI,+01797,-00230,+0000,A
2020-06-05T22:36:32.293Z,1591396592.293 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:36:32.294Z,1591396592.294 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.,1489.0,,+01526,-1099,+000I,+01526,01099,+0:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2020-06-05T22:38:02.421Z,1591396682.421 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 40
2020-06-05T22:38:02.421Z,1591396682.421 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:38:02.439Z,1591396682.439 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:38:02.858Z,1591396682.858 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:38:02.858Z,1591396682.858 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 40
2020-06-05T22:38:06.433Z,1591396686.433 [DVL_micro](ERROR): Failed to parse:
9.99,+9999.99,+9999.99,+9999.99
2020-06-05T22:38:22.175Z,1591396702.175 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T22:38:22.175Z,1591396702.175 [NAL9602] Data Fault, FailCount= 1
2020-06-05T22:38:22.175Z,1591396702.175 [NAL9602](ERROR): Data Fault
2020-06-05T22:38:22.231Z,1591396702.231 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T22:38:22.579Z,1591396702.579 [NAL9602](INFO): Powering down
2020-06-05T22:38:23.427Z,1591396703.427 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T22:38:23.427Z,1591396703.427 [NAL9602] No Fault, FailCount= 1
2020-06-05T22:38:24.249Z,1591396704.249 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2020-06-05T22:38:39.994Z,1591396719.994 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T22:38:39.994Z,1591396719.994 [Default:CheckIn:C.Wait] Stopped
2020-06-05T22:38:39.994Z,1591396719.994 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T22:38:39.994Z,1591396719.994 [Default:CheckIn:D] Running Loop=1
2020-06-05T22:38:40.376Z,1591396720.376 [Default:CheckIn:D] Stopped
2020-06-05T22:38:40.389Z,1591396720.389 [Default:CheckIn:E] Running Loop=1
2020-06-05T22:38:40.782Z,1591396720.782 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.388786 min 
2020-06-05T22:38:40.782Z,1591396720.782 [Default:CheckIn:E] Stopped
2020-06-05T22:38:40.782Z,1591396720.782 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T22:38:40.782Z,1591396720.782 [Default:CheckIn] Stopped
2020-06-05T22:38:40.782Z,1591396720.782 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T22:38:40.782Z,1591396720.782 [Default:CheckIn](INFO): Running loop #12
2020-06-05T22:38:40.782Z,1591396720.782 [Default:CheckIn] Running Loop=12
2020-06-05T22:38:40.783Z,1591396720.783 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T22:38:40.783Z,1591396720.783 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T22:38:47.225Z,1591396727.225 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:38:47.225Z,1591396727.225 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.3,489.0,000
2020-06-05T22:38:52.883Z,1591396732.883 [NAL9602](INFO): Powering up NAL9602
2020-06-05T22:39:03.787Z,1591396743.787 [NAL9602](INFO): NAL9602 initialized
2020-06-05T22:39:12.682Z,1591396752.682 [DVL_micro](ERROR): only read 1 of 4 data items
2020-06-05T22:39:12.682Z,1591396752.682 [DVL_micro](ERROR): Failed to parse::RD,+9999..99,+9999.99,+9999.99
2020-06-05T22:39:24.831Z,1591396764.831 [BPC1](ERROR): Battery stick #6 (s/n: 00CA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2020-06-05T22:39:24.832Z,1591396764.832 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 6.
2020-06-05T22:39:24.834Z,1591396764.834 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2020-06-05T22:39:55.905Z,1591396795.905 [DVL_micro](ERROR): Failed to parse:
:WI,+0165,+01057,
2020-06-05T22:40:54.481Z,1591396854.481 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:40:54.481Z,1591396854.481 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+2.0,1489.0,000
2020-06-05T22:41:03.401Z,1591396863.401 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 41
2020-06-05T22:41:03.401Z,1591396863.401 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:41:03.411Z,1591396863.411 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:41:03.818Z,1591396863.818 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:41:03.818Z,1591396863.818 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 41
2020-06-05T22:41:19.961Z,1591396879.961 [DVL_micro](ERROR): only read 2 of 4 data items
2020-06-05T22:41:19.961Z,1591396879.961 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,++9999.99
2020-06-05T22:41:47.818Z,1591396907.818 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:41:47.818Z,1591396907.818 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.4,009.0,000
2020-06-05T22:42:13.274Z,1591396933.274 [DVL_micro](ERROR): Failed to parse:
:SA,-00.96,-06.79,035.0
2020-06-05T22:43:40.971Z,1591397020.971 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T22:38:40.8Z
2020-06-05T22:43:40.971Z,1591397020.971 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T22:43:40.971Z,1591397020.971 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T22:43:44.973Z,1591397024.973 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:43:44.973Z,1591397024.973 [DVL_micro](ERROR): Failed to parse:
:TS,000000005.0,+20.,1489.0,0000152,-0216,+00000,A
2020-06-05T22:43:50.340Z,1591397030.340 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200605T203712/Courier0037.lzma
2020-06-05T22:43:51.343Z,1591397031.343 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0037.lzma.bak
2020-06-05T22:43:51.343Z,1591397031.343 [DataOverHttps](INFO): SBD MOMSN=12368380
2020-06-05T22:44:04.389Z,1591397044.389 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 42
2020-06-05T22:44:04.389Z,1591397044.389 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:44:04.399Z,1591397044.399 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:44:04.819Z,1591397044.819 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:44:04.819Z,1591397044.819 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 42
2020-06-05T22:44:10.780Z,1591397050.780 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200605T203712/Express0038.lzma
2020-06-05T22:44:11.783Z,1591397051.783 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0038.lzma.bak
2020-06-05T22:44:11.783Z,1591397051.783 [DataOverHttps](INFO): SBD MOMSN=12368382
2020-06-05T22:44:16.542Z,1591397056.542 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T22:44:16.542Z,1591397056.542 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T22:44:16.542Z,1591397056.542 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T22:45:09.013Z,1591397109.013 [DVL_micro](ERROR): only read 1 of 4 data items
2020-06-05T22:45:09.014Z,1591397109.014 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,++9999.99,+9999.99
2020-06-05T22:45:34.456Z,1591397134.456 [DVL_micro](ERROR): Failed to parse:
:0000000020.4,00000,000
2020-06-05T22:46:17.685Z,1591397177.685 [DVL_micro](ERROR): Failed to parse:
:WI,-00324,-01317,+01378,+00000,A
2020-06-05T22:46:43.139Z,1591397203.139 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,0.00,+000,9999.99,000.00
2020-06-05T22:47:05.384Z,1591397225.384 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 43
2020-06-05T22:47:05.385Z,1591397225.385 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:47:05.394Z,1591397225.394 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:47:05.840Z,1591397225.840 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:47:05.841Z,1591397225.841 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 43
2020-06-05T22:47:36.471Z,1591397256.471 [DVL_micro](ERROR): Failed to parse:
:WI,+01779,+00082,+00602,+00000,A
2020-06-05T22:48:24.957Z,1591397304.957 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:48:24.957Z,1591397304.957 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.,1489.0,000
2020-06-05T22:48:52.838Z,1591397332.838 [DVL_micro](ERROR): Failed to parse:99.99,+9999.99,+9999.99,+9999.99
2020-06-05T22:49:06.151Z,1591397346.151 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T22:49:06.151Z,1591397346.151 [NAL9602] Data Fault, FailCount= 2
2020-06-05T22:49:06.151Z,1591397346.151 [NAL9602](ERROR): Data Fault
2020-06-05T22:49:06.204Z,1591397346.204 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T22:49:06.559Z,1591397346.559 [NAL9602](INFO): Powering down
2020-06-05T22:49:07.398Z,1591397347.398 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T22:49:07.398Z,1591397347.398 [NAL9602] No Fault, FailCount= 2
2020-06-05T22:49:17.087Z,1591397357.087 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T22:49:17.087Z,1591397357.087 [Default:CheckIn:C.Wait] Stopped
2020-06-05T22:49:17.087Z,1591397357.087 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T22:49:17.087Z,1591397357.087 [Default:CheckIn:D] Running Loop=1
2020-06-05T22:49:17.489Z,1591397357.489 [Default:CheckIn:D] Stopped
2020-06-05T22:49:17.489Z,1591397357.489 [Default:CheckIn:E] Running Loop=1
2020-06-05T22:49:17.899Z,1591397357.899 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.007324 min 
2020-06-05T22:49:17.899Z,1591397357.899 [Default:CheckIn:E] Stopped
2020-06-05T22:49:17.899Z,1591397357.899 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T22:49:17.899Z,1591397357.899 [Default:CheckIn] Stopped
2020-06-05T22:49:17.899Z,1591397357.899 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T22:49:17.900Z,1591397357.900 [Default:CheckIn](INFO): Running loop #13
2020-06-05T22:49:17.900Z,1591397357.900 [Default:CheckIn] Running Loop=13
2020-06-05T22:49:17.900Z,1591397357.900 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T22:49:17.900Z,1591397357.900 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T22:49:36.863Z,1591397376.863 [NAL9602](INFO): Powering up NAL9602
2020-06-05T22:49:41.309Z,1591397381.309 [DVL_micro](ERROR): only read 1 of 4 data items
2020-06-05T22:49:41.309Z,1591397381.309 [DVL_micro](ERROR): Failed to parse:
:RD,+9999..99,+9999.99,+9999.99
2020-06-05T22:49:47.763Z,1591397387.763 [NAL9602](INFO): NAL9602 initialized
2020-06-05T22:50:06.422Z,1591397406.422 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 44
2020-06-05T22:50:06.422Z,1591397406.422 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:50:06.431Z,1591397406.431 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:50:06.806Z,1591397406.806 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:50:06.806Z,1591397406.806 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 44
2020-06-05T22:51:48.561Z,1591397508.561 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:51:48.561Z,1591397508.561 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.5,0000.0,148

2020-06-05T22:52:14.019Z,1591397534.019 [DVL_micro](ERROR): Failed to parse:
:WI,+05314,00269,+00BI,+05314,-01211,+00269,+00000,I
2020-06-05T22:52:28.954Z,1591397548.954 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-06-05T22:52:28.954Z,1591397548.954 [DropWeight] Hardware Fault, FailCount= 1
2020-06-05T22:52:28.954Z,1591397548.954 [DropWeight](ERROR): Hardware Fault
2020-06-05T22:52:29.046Z,1591397549.046 [CBIT](INFO): Critical error at 20200605T225228
2020-06-05T22:52:29.048Z,1591397549.048 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-06-05T22:52:29.049Z,1591397549.049 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-06-05T22:52:29.417Z,1591397549.417 [CBIT](INFO): Critical error at 20200605T225229
2020-06-05T22:53:07.345Z,1591397587.345 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T22:53:07.345Z,1591397587.345 [DVL_micro](ERROR): Failed to parse:
:BI,+01744,-01174,+0000,I
2020-06-05T22:53:07.400Z,1591397587.400 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 45
2020-06-05T22:53:07.400Z,1591397587.400 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:53:07.419Z,1591397587.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:53:07.798Z,1591397587.798 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:53:07.798Z,1591397587.798 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 45
2020-06-05T22:54:18.071Z,1591397658.071 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T22:49:17.9Z
2020-06-05T22:54:18.071Z,1591397658.071 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T22:54:18.071Z,1591397658.071 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T22:54:21.278Z,1591397661.278 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T22:54:21.278Z,1591397661.278 [DVL_micro](ERROR): Failed to parse:
:BI,+01923,-01893,+0108,I
2020-06-05T22:54:26.984Z,1591397666.984 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20200605T203712/Courier0040.lzma
2020-06-05T22:54:27.987Z,1591397667.987 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0040.lzma.bak
2020-06-05T22:54:27.987Z,1591397667.987 [DataOverHttps](INFO): SBD MOMSN=12368402
2020-06-05T22:54:46.724Z,1591397686.724 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:54:46.725Z,1591397686.725 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0000.0,148

2020-06-05T22:54:48.344Z,1591397688.344 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20200605T203712/Express0041.lzma
2020-06-05T22:54:49.347Z,1591397689.347 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0041.lzma.bak
2020-06-05T22:54:49.347Z,1591397689.347 [DataOverHttps](INFO): SBD MOMSN=12368406
2020-06-05T22:54:54.434Z,1591397694.434 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T22:54:54.434Z,1591397694.434 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T22:54:54.434Z,1591397694.434 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T22:56:08.360Z,1591397768.360 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 46
2020-06-05T22:56:08.360Z,1591397768.360 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:56:08.370Z,1591397768.370 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:56:08.795Z,1591397768.795 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:56:08.795Z,1591397768.795 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 46
2020-06-05T22:56:53.998Z,1591397813.998 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T22:56:53.998Z,1591397813.998 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,
2020-06-05T22:58:02.669Z,1591397882.669 [DVL_micro](ERROR): Failed to parse:
:WI,+01620,-02072,+01355,+00000,A
2020-06-05T22:59:09.354Z,1591397949.354 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 47
2020-06-05T22:59:09.354Z,1591397949.354 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T22:59:09.364Z,1591397949.364 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T22:59:09.779Z,1591397949.779 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T22:59:09.779Z,1591397949.779 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 47
2020-06-05T22:59:19.029Z,1591397959.029 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T22:59:19.030Z,1591397959.030 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,00.0,1489.0,000
2020-06-05T22:59:50.127Z,1591397990.127 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T22:59:50.127Z,1591397990.127 [NAL9602] Data Fault, FailCount= 3
2020-06-05T22:59:50.127Z,1591397990.127 [NAL9602](ERROR): Data Fault
2020-06-05T22:59:50.216Z,1591397990.216 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T22:59:50.535Z,1591397990.535 [NAL9602](INFO): Powering down
2020-06-05T22:59:51.390Z,1591397991.390 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T22:59:51.390Z,1591397991.390 [NAL9602] No Fault, FailCount= 3
2020-06-05T22:59:55.018Z,1591397995.018 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T22:59:55.018Z,1591397995.018 [Default:CheckIn:C.Wait] Stopped
2020-06-05T22:59:55.018Z,1591397995.018 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T22:59:55.018Z,1591397995.018 [Default:CheckIn:D] Running Loop=1
2020-06-05T22:59:55.409Z,1591397995.409 [Default:CheckIn:D] Stopped
2020-06-05T22:59:55.409Z,1591397995.409 [Default:CheckIn:E] Running Loop=1
2020-06-05T22:59:55.822Z,1591397995.822 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.639323 min 
2020-06-05T22:59:55.822Z,1591397995.822 [Default:CheckIn:E] Stopped
2020-06-05T22:59:55.822Z,1591397995.822 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T22:59:55.822Z,1591397995.822 [Default:CheckIn] Stopped
2020-06-05T22:59:55.822Z,1591397995.822 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T22:59:55.822Z,1591397995.822 [Default:CheckIn](INFO): Running loop #14
2020-06-05T22:59:55.822Z,1591397995.822 [Default:CheckIn] Running Loop=14
2020-06-05T22:59:55.822Z,1591397995.822 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T22:59:55.823Z,1591397995.823 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T23:00:20.834Z,1591398020.834 [NAL9602](INFO): Powering up NAL9602
2020-06-05T23:00:31.744Z,1591398031.744 [NAL9602](INFO): NAL9602 initialized
2020-06-05T23:00:55.575Z,1591398055.575 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T23:00:55.575Z,1591398055.575 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+0.0,1489.WI,-02777,-00193,+01593,+00000,A
2020-06-05T23:02:09.516Z,1591398129.516 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T23:02:09.517Z,1591398129.517 [DVL_micro](ERROR): Failed to parse:
:TS,000000000.0,+20.5,0000.0,1489.0,000
2020-06-05T23:02:10.345Z,1591398130.345 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 48
2020-06-05T23:02:10.345Z,1591398130.345 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T23:02:10.355Z,1591398130.355 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T23:02:10.768Z,1591398130.768 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T23:02:10.768Z,1591398130.768 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 48
2020-06-05T23:03:53.751Z,1591398233.751 [DVL_micro](ERROR): Failed to parse:
:WI,+00238,+00117,+01147,+00000,A
2020-06-05T23:04:55.981Z,1591398295.981 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-05T22:59:55.8Z
2020-06-05T23:04:55.982Z,1591398295.982 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T23:04:55.982Z,1591398295.982 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-06-05T23:05:05.328Z,1591398305.328 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200605T203712/Courier0043.lzma
2020-06-05T23:05:06.331Z,1591398306.331 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Courier0043.lzma.bak
2020-06-05T23:05:06.331Z,1591398306.331 [DataOverHttps](INFO): SBD MOMSN=12368419
2020-06-05T23:05:11.338Z,1591398311.338 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 49
2020-06-05T23:05:11.338Z,1591398311.338 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T23:05:11.348Z,1591398311.348 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T23:05:11.787Z,1591398311.787 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T23:05:11.787Z,1591398311.787 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 49
2020-06-05T23:05:25.564Z,1591398325.564 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20200605T203712/Express0044.lzma
2020-06-05T23:05:26.567Z,1591398326.567 [DataOverHttps](INFO): Moved sent file to Logs/20200605T203712/Express0044.lzma.bak
2020-06-05T23:05:26.567Z,1591398326.567 [DataOverHttps](INFO): SBD MOMSN=12368421
2020-06-05T23:05:31.171Z,1591398331.171 [Default:CheckIn:Read_Iridium] Stopped
2020-06-05T23:05:31.171Z,1591398331.171 [Default:CheckIn:C.Wait] Running Loop=1
2020-06-05T23:05:31.171Z,1591398331.171 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-06-05T23:06:00.999Z,1591398360.999 [DVL_micro](ERROR): Failed to parse:
:WI,+00856,-01299,+01172,+0000,+00856,-01299,+01172,+00000,I
2020-06-05T23:07:29.123Z,1591398449.123 [CBIT](INFO): Clearing failed state for component DropWeight
2020-06-05T23:07:29.123Z,1591398449.123 [DropWeight] No Fault, FailCount= 1
2020-06-05T23:08:05.844Z,1591398485.844 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T23:08:05.844Z,1591398485.844 [DVL_micro](ERROR): Failed to parse::TS,000000,35.0,+20.51489.0,000
2020-06-05T23:08:12.332Z,1591398492.332 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 50
2020-06-05T23:08:12.332Z,1591398492.332 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T23:08:12.342Z,1591398492.342 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T23:08:12.753Z,1591398492.753 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T23:08:12.753Z,1591398492.753 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 50
2020-06-05T23:09:14.522Z,1591398554.522 [DVL_micro](ERROR): Failed to parse:
:WI,+01219,-01057,+0119,A
2020-06-05T23:09:39.973Z,1591398579.973 [DVL_micro](ERROR): Failed to parse:
:BI,-00503,-01502,+0148,I
2020-06-05T23:10:31.723Z,1591398631.723 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-06-05T23:10:31.723Z,1591398631.723 [Default:CheckIn:C.Wait] Stopped
2020-06-05T23:10:31.723Z,1591398631.723 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-06-05T23:10:31.723Z,1591398631.723 [Default:CheckIn:D] Running Loop=1
2020-06-05T23:10:32.122Z,1591398632.122 [Default:CheckIn:D] Stopped
2020-06-05T23:10:32.122Z,1591398632.122 [Default:CheckIn:E] Running Loop=1
2020-06-05T23:10:32.518Z,1591398632.518 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.251204 min 
2020-06-05T23:10:32.518Z,1591398632.518 [Default:CheckIn:E] Stopped
2020-06-05T23:10:32.518Z,1591398632.518 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-06-05T23:10:32.518Z,1591398632.518 [Default:CheckIn] Stopped
2020-06-05T23:10:32.518Z,1591398632.518 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T23:10:32.519Z,1591398632.519 [Default:CheckIn](INFO): Running loop #15
2020-06-05T23:10:32.519Z,1591398632.519 [Default:CheckIn] Running Loop=15
2020-06-05T23:10:32.519Z,1591398632.519 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-06-05T23:10:32.519Z,1591398632.519 [Default:CheckIn:Read_GPS] Running Loop=1
2020-06-05T23:10:34.103Z,1591398634.103 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-06-05T23:10:34.103Z,1591398634.103 [NAL9602] Data Fault, FailCount= 4
2020-06-05T23:10:34.103Z,1591398634.103 [NAL9602](ERROR): Data Fault
2020-06-05T23:10:34.166Z,1591398634.166 [CBIT](ERROR): Data Fault in component: NAL9602
2020-06-05T23:10:34.507Z,1591398634.507 [NAL9602](INFO): Powering down
2020-06-05T23:10:35.350Z,1591398635.350 [CBIT](INFO): Clearing failed state for component NAL9602
2020-06-05T23:10:35.351Z,1591398635.351 [NAL9602] No Fault, FailCount= 4
2020-06-05T23:10:56.330Z,1591398656.330 [DVL_micro](ERROR): Failed to parse:
:WI,+03447,-00323,+00557,+00000,A
2020-06-05T23:11:04.803Z,1591398664.803 [NAL9602](INFO): Powering up NAL9602
2020-06-05T23:11:13.324Z,1591398673.324 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 51
2020-06-05T23:11:13.324Z,1591398673.324 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-06-05T23:11:13.334Z,1591398673.334 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-06-05T23:11:13.727Z,1591398673.727 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-06-05T23:11:13.727Z,1591398673.727 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 51
2020-06-05T23:11:15.719Z,1591398675.719 [NAL9602](INFO): NAL9602 initialized
2020-06-05T23:11:21.780Z,1591398681.780 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-06-05T23:11:21.780Z,1591398681.780 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,00.0,148

2020-06-05T23:11:49.666Z,1591398709.666 [DVL_micro](ERROR): Failed to parse:
:.99,+9999.99,+9999.99,+9999.99
2020-06-05T23:12:12.699Z,1591398732.699 [DVL_micro](ERROR): Failed to parse:
:WI,+03891,-01271,+00539,+00000,A
2020-06-05T23:12:38.134Z,1591398758.134 [DVL_micro](ERROR): only read 3 of 4 data items
2020-06-05T23:12:38.135Z,1591398758.135 [DVL_micro](ERROR): Failed to parse:
:BI,01103,+00993,+00000,I
2020-06-05T23:12:59.410Z,1591398779.410 [CommandLine](IMPORTANT): got command quit
2020-06-05T23:13:00.417Z,1591398780.417 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-05T23:13:00.418Z,1591398780.418 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:00.565Z,1591398780.565 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-06-05T23:13:00.565Z,1591398780.565 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:00.566Z,1591398780.566 [CommandLine](INFO): Join timeout helper Thread ID is 9981
2020-06-05T23:13:00.566Z,1591398780.566 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-06-05T23:13:00.566Z,1591398780.566 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:00.567Z,1591398780.567 [NavChartDb](INFO): Join timeout helper Thread ID is 9982
2020-06-05T23:13:00.906Z,1591398780.906 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-05T23:13:00.906Z,1591398780.906 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:00.917Z,1591398780.917 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-06-05T23:13:00.917Z,1591398780.917 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:00.917Z,1591398780.917 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9983
2020-06-05T23:13:01.289Z,1591398781.289 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-05T23:13:01.289Z,1591398781.289 [WetLabsBB2FL](INFO): Powering down
2020-06-05T23:13:01.290Z,1591398781.290 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:01.301Z,1591398781.301 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-06-05T23:13:01.301Z,1591398781.301 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:01.301Z,1591398781.301 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9984
2020-06-05T23:13:01.469Z,1591398781.469 [CTD_Seabird](INFO): Powering down
2020-06-05T23:13:01.481Z,1591398781.481 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-05T23:13:01.481Z,1591398781.481 [CTD_Seabird](INFO): Powering down
2020-06-05T23:13:01.493Z,1591398781.493 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:01.501Z,1591398781.501 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-06-05T23:13:01.501Z,1591398781.501 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:01.501Z,1591398781.501 [Radio_Surface](INFO): Join timeout helper Thread ID is 9985
2020-06-05T23:13:01.713Z,1591398781.713 [Radio_Surface](INFO): Powering down
2020-06-05T23:13:01.714Z,1591398781.714 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-05T23:13:01.714Z,1591398781.714 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:01.726Z,1591398781.726 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-06-05T23:13:01.726Z,1591398781.726 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:01.726Z,1591398781.726 [DataOverHttps](INFO): Join timeout helper Thread ID is 9986
2020-06-05T23:13:01.833Z,1591398781.833 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-05T23:13:01.833Z,1591398781.833 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:01.852Z,1591398781.852 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-06-05T23:13:01.853Z,1591398781.853 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:01.853Z,1591398781.853 [logger](INFO): Join timeout helper Thread ID is 9987
2020-06-05T23:13:01.901Z,1591398781.901 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-05T23:13:01.901Z,1591398781.901 [logger ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:01.913Z,1591398781.913 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-06-05T23:13:01.914Z,1591398781.914 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:01.914Z,1591398781.914 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-06-05T23:13:01.914Z,1591398781.914 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:01.914Z,1591398781.914 [controlThread](INFO): Join timeout helper Thread ID is 9988
2020-06-05T23:13:01.954Z,1591398781.954 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-06-05T23:13:01.955Z,1591398781.955 [controlThread](DEBUG): Uninitializing ControlThread
2020-06-05T23:13:01.956Z,1591398781.956 [AHRS_M2](INFO): Powering down
2020-06-05T23:13:02.097Z,1591398782.097 [DVL_micro](INFO): Powering down
2020-06-05T23:13:02.098Z,1591398782.098 [NAL9602](INFO): Powering down
2020-06-05T23:13:02.099Z,1591398782.099 [DAT](INFO): Powering down
2020-06-05T23:13:02.236Z,1591398782.236 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-06-05T23:13:02.237Z,1591398782.237 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-06-05T23:13:02.238Z,1591398782.238 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-06-05T23:13:02.238Z,1591398782.238 [MissionManager](INFO): Uninitializing Mission Default
2020-06-05T23:13:02.238Z,1591398782.238 [Default] Stopped
2020-06-05T23:13:02.239Z,1591398782.239 [Default](DEBUG): Aggregate::uninitialize Default
2020-06-05T23:13:02.239Z,1591398782.239 [Default:B.GoToSurface] Stopped
2020-06-05T23:13:02.239Z,1591398782.239 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-06-05T23:13:02.239Z,1591398782.239 [Default:CheckIn] Stopped
2020-06-05T23:13:02.239Z,1591398782.239 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-06-05T23:13:02.239Z,1591398782.239 [Default:CheckIn:Read_GPS] Stopped
2020-06-05T23:13:02.241Z,1591398782.241 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-06-05T23:13:02.242Z,1591398782.242 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-06-05T23:13:02.242Z,1591398782.242 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-06-05T23:13:02.242Z,1591398782.242 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-06-05T23:13:02.243Z,1591398782.243 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-06-05T23:13:02.243Z,1591398782.243 [BuoyancyServo](INFO): Powering down
2020-06-05T23:13:02.257Z,1591398782.257 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-06-05T23:13:02.257Z,1591398782.257 [ElevatorServo](INFO): Powering down
2020-06-05T23:13:02.258Z,1591398782.258 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-06-05T23:13:02.258Z,1591398782.258 [MassServo](INFO): Powering down
2020-06-05T23:13:02.259Z,1591398782.259 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-06-05T23:13:02.259Z,1591398782.259 [RudderServo](INFO): Powering down
2020-06-05T23:13:02.260Z,1591398782.260 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-06-05T23:13:02.260Z,1591398782.260 [ThrusterServo](INFO): Powering down
2020-06-05T23:13:02.260Z,1591398782.260 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-06-05T23:13:02.261Z,1591398782.261 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-06-05T23:13:02.261Z,1591398782.261 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-06-05T23:13:02.261Z,1591398782.261 [CBIT](DEBUG): Powering off loads.
2020-06-05T23:13:02.273Z,1591398782.273 [CBIT](DEBUG): Disabling WDT.
2020-06-05T23:13:02.285Z,1591398782.285 [CBIT](DEBUG): Opening all GF detection circuits.
2020-06-05T23:13:02.286Z,1591398782.286 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:02.365Z,1591398782.365 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:02.373Z,1591398782.373 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:02.432Z,1591398782.432 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:02.437Z,1591398782.437 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:02.463Z,1591398782.463 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-06-05T23:13:02.523Z,1591398782.523 [logger ThreadHandler](INFO): Thread cancelled.