2020-07-10T13:58:33.071Z,1594389513.071 [Supervisor](DEBUG): Initializing supervisor.
2020-07-10T13:58:33.074Z,1594389513.074 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-07-10T13:58:33.074Z,1594389513.074 [SyncHandler](INFO): Protected caller Thread ID is 821
2020-07-10T13:58:33.075Z,1594389513.075 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-07-10T13:58:33.076Z,1594389513.076 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-07-10T13:58:33.076Z,1594389513.076 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 822
2020-07-10T13:58:33.079Z,1594389513.079 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-07-10T13:58:33.093Z,1594389513.093 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-07-10T13:58:33.094Z,1594389513.094 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-07-10T13:58:33.094Z,1594389513.094 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 823
2020-07-10T13:58:33.095Z,1594389513.095 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-07-10T13:58:33.096Z,1594389513.096 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-07-10T13:58:33.096Z,1594389513.096 [logger ThreadHandler](INFO): Protected caller Thread ID is 824
2020-07-10T13:58:33.098Z,1594389513.098 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-07-10T13:58:33.099Z,1594389513.099 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-07-10T13:58:33.103Z,1594389513.103 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-07-10T13:58:33.302Z,1594389513.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-07-10T13:58:33.304Z,1594389513.304 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-07-10T13:58:33.384Z,1594389513.384 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-07-10T13:58:33.804Z,1594389513.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-07-10T13:58:33.806Z,1594389513.806 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-07-10T13:58:34.124Z,1594389514.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-07-10T13:58:34.125Z,1594389514.125 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-07-10T13:58:34.220Z,1594389514.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-07-10T13:58:34.222Z,1594389514.222 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-07-10T13:58:34.509Z,1594389514.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-07-10T13:58:34.511Z,1594389514.511 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-07-10T13:58:34.700Z,1594389514.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-07-10T13:58:34.701Z,1594389514.701 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-07-10T13:58:35.161Z,1594389515.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-07-10T13:58:35.163Z,1594389515.163 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-07-10T13:58:35.265Z,1594389515.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-07-10T13:58:35.267Z,1594389515.267 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-07-10T13:58:35.366Z,1594389515.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-07-10T13:58:35.368Z,1594389515.368 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-07-10T13:58:36.041Z,1594389516.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-07-10T13:58:36.042Z,1594389516.042 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-07-10T13:58:37.114Z,1594389517.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-07-10T13:58:37.116Z,1594389517.116 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-07-10T13:58:37.320Z,1594389517.320 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-07-10T13:58:37.320Z,1594389517.320 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-07-10T13:58:37.464Z,1594389517.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-07-10T13:58:37.464Z,1594389517.464 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-07-10T13:58:37.610Z,1594389517.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-07-10T13:58:37.612Z,1594389517.612 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-07-10T13:58:37.814Z,1594389517.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-07-10T13:58:37.816Z,1594389517.816 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2020-07-10T13:58:37.818Z,1594389517.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2020-07-10T13:58:37.908Z,1594389517.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2020-07-10T13:58:37.993Z,1594389517.993 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2020-07-10T13:58:38.091Z,1594389518.091 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2020-07-10T13:58:38.173Z,1594389518.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2020-07-10T13:58:38.266Z,1594389518.266 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2020-07-10T13:58:38.365Z,1594389518.365 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2020-07-10T13:58:38.566Z,1594389518.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2020-07-10T13:58:38.642Z,1594389518.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2020-07-10T13:58:38.802Z,1594389518.802 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2020-07-10T13:58:38.944Z,1594389518.944 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2020-07-10T13:58:39.057Z,1594389519.057 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2020-07-10T13:58:39.289Z,1594389519.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-07-10T13:58:39.290Z,1594389519.290 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2020-07-10T13:58:39.290Z,1594389519.290 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-07-10T13:58:39.302Z,1594389519.302 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-07-10T13:58:39.368Z,1594389519.368 [VerticalControl](DEBUG): Construct VerticalControl.
2020-07-10T13:58:39.481Z,1594389519.481 [VerticalControl] Loaded
2020-07-10T13:58:39.481Z,1594389519.481 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-07-10T13:58:39.482Z,1594389519.482 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-07-10T13:58:39.563Z,1594389519.563 [HorizontalControl] Loaded
2020-07-10T13:58:39.563Z,1594389519.563 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-07-10T13:58:39.564Z,1594389519.564 [SpeedControl](DEBUG): Construct SpeedControl.
2020-07-10T13:58:39.569Z,1594389519.569 [SpeedControl] Loaded
2020-07-10T13:58:39.569Z,1594389519.569 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-07-10T13:58:39.570Z,1594389519.570 [LoopControl](DEBUG): Construct LoopControl.
2020-07-10T13:58:39.571Z,1594389519.571 [LoopControl] Loaded
2020-07-10T13:58:39.571Z,1594389519.571 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-07-10T13:58:39.571Z,1594389519.571 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-07-10T13:58:39.573Z,1594389519.573 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-07-10T13:58:39.603Z,1594389519.603 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-07-10T13:58:39.604Z,1594389519.604 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-07-10T13:58:39.728Z,1594389519.728 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-07-10T13:58:39.728Z,1594389519.728 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-07-10T13:58:39.899Z,1594389519.899 [BuoyancyServo] Loaded
2020-07-10T13:58:39.899Z,1594389519.899 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-07-10T13:58:39.927Z,1594389519.927 [ElevatorServo] Loaded
2020-07-10T13:58:39.928Z,1594389519.928 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-07-10T13:58:39.955Z,1594389519.955 [MassServo] Loaded
2020-07-10T13:58:39.955Z,1594389519.955 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-07-10T13:58:39.983Z,1594389519.983 [RudderServo] Loaded
2020-07-10T13:58:39.983Z,1594389519.983 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-07-10T13:58:40.010Z,1594389520.010 [ThrusterServo] Loaded
2020-07-10T13:58:40.010Z,1594389520.010 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-07-10T13:58:40.010Z,1594389520.010 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-07-10T13:58:40.011Z,1594389520.011 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-07-10T13:58:40.153Z,1594389520.153 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-07-10T13:58:40.153Z,1594389520.153 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-07-10T13:58:40.174Z,1594389520.174 [NavChart] Loaded
2020-07-10T13:58:40.174Z,1594389520.174 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-07-10T13:58:40.178Z,1594389520.178 [UniversalFixResidualReporter] Loaded
2020-07-10T13:58:40.178Z,1594389520.178 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-07-10T13:58:40.179Z,1594389520.179 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-07-10T13:58:40.180Z,1594389520.180 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-07-10T13:58:40.304Z,1594389520.304 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-07-10T13:58:40.305Z,1594389520.305 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-07-10T13:58:40.846Z,1594389520.846 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-07-10T13:58:40.851Z,1594389520.851 [AHRS_M2](INFO): created writer for : platform_orientation
2020-07-10T13:58:40.852Z,1594389520.852 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-07-10T13:58:40.858Z,1594389520.858 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-07-10T13:58:40.858Z,1594389520.858 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-07-10T13:58:40.863Z,1594389520.863 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-07-10T13:58:40.864Z,1594389520.864 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-07-10T13:58:40.869Z,1594389520.869 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-07-10T13:58:41.046Z,1594389521.046 [AHRS_M2] Loaded
2020-07-10T13:58:41.047Z,1594389521.047 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-07-10T13:58:41.193Z,1594389521.193 [DataOverHttps] Loaded
2020-07-10T13:58:41.193Z,1594389521.193 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-07-10T13:58:41.194Z,1594389521.194 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4089B4E0
2020-07-10T13:58:41.195Z,1594389521.195 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 910
2020-07-10T13:58:41.226Z,1594389521.226 [Depth_Keller] Loaded
2020-07-10T13:58:41.226Z,1594389521.226 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-07-10T13:58:41.231Z,1594389521.231 [DropWeight] Loaded
2020-07-10T13:58:41.231Z,1594389521.231 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-07-10T13:58:41.344Z,1594389521.344 [NAL9602] Loaded
2020-07-10T13:58:41.344Z,1594389521.344 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-07-10T13:58:41.392Z,1594389521.392 [Onboard] Loaded
2020-07-10T13:58:41.392Z,1594389521.392 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-07-10T13:58:41.393Z,1594389521.393 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408CB4E0
2020-07-10T13:58:41.394Z,1594389521.394 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 911
2020-07-10T13:58:41.413Z,1594389521.413 [Radio_Surface] Loaded
2020-07-10T13:58:41.414Z,1594389521.414 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-07-10T13:58:41.415Z,1594389521.415 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408FB4E0
2020-07-10T13:58:41.415Z,1594389521.415 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 912
2020-07-10T13:58:43.617Z,1594389523.617 [BPC1] Loaded
2020-07-10T13:58:43.618Z,1594389523.618 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-07-10T13:58:43.618Z,1594389523.618 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-07-10T13:58:43.619Z,1594389523.619 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-07-10T13:58:43.674Z,1594389523.674 [DepthRateCalculator] Loaded
2020-07-10T13:58:43.674Z,1594389523.674 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-07-10T13:58:43.680Z,1594389523.680 [PitchRateCalculator] Loaded
2020-07-10T13:58:43.680Z,1594389523.680 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-07-10T13:58:43.692Z,1594389523.692 [SpeedCalculator] Loaded
2020-07-10T13:58:43.692Z,1594389523.692 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-07-10T13:58:43.713Z,1594389523.713 [TempGradientCalculator] Loaded
2020-07-10T13:58:43.714Z,1594389523.714 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-07-10T13:58:43.719Z,1594389523.719 [YawRateCalculator] Loaded
2020-07-10T13:58:43.719Z,1594389523.719 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-07-10T13:58:43.748Z,1594389523.748 [ElevatorOffsetCalculator] Loaded
2020-07-10T13:58:43.749Z,1594389523.749 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-07-10T13:58:43.749Z,1594389523.749 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-07-10T13:58:43.750Z,1594389523.750 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-07-10T13:58:44.044Z,1594389524.044 [CANONSampler] Loaded
2020-07-10T13:58:44.044Z,1594389524.044 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2020-07-10T13:58:44.073Z,1594389524.073 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-07-10T13:58:44.078Z,1594389524.078 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-07-10T13:58:44.078Z,1594389524.078 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-07-10T13:58:44.083Z,1594389524.083 [CTD_Seabird](INFO): created writer for : depth
2020-07-10T13:58:44.083Z,1594389524.083 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-07-10T13:58:44.088Z,1594389524.088 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-07-10T13:58:44.089Z,1594389524.089 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-07-10T13:58:44.094Z,1594389524.094 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-07-10T13:58:44.095Z,1594389524.095 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-07-10T13:58:44.100Z,1594389524.100 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-07-10T13:58:44.101Z,1594389524.101 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-07-10T13:58:44.106Z,1594389524.106 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-07-10T13:58:44.106Z,1594389524.106 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-07-10T13:58:44.112Z,1594389524.112 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-07-10T13:58:44.112Z,1594389524.112 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2020-07-10T13:58:44.118Z,1594389524.118 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2020-07-10T13:58:44.118Z,1594389524.118 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2020-07-10T13:58:44.119Z,1594389524.119 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2020-07-10T13:58:44.151Z,1594389524.151 [CTD_Seabird] Loaded
2020-07-10T13:58:44.151Z,1594389524.151 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-07-10T13:58:44.152Z,1594389524.152 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A074E0
2020-07-10T13:58:44.152Z,1594389524.152 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 913
2020-07-10T13:58:44.184Z,1594389524.184 [PAR_Licor] Loaded
2020-07-10T13:58:44.184Z,1594389524.184 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-07-10T13:58:44.206Z,1594389524.206 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-07-10T13:58:44.206Z,1594389524.206 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-07-10T13:58:44.210Z,1594389524.210 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-07-10T13:58:44.210Z,1594389524.210 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-07-10T13:58:44.214Z,1594389524.214 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-07-10T13:58:44.214Z,1594389524.214 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-07-10T13:58:44.219Z,1594389524.219 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-07-10T13:58:44.219Z,1594389524.219 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-07-10T13:58:44.223Z,1594389524.223 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-07-10T13:58:44.223Z,1594389524.223 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-07-10T13:58:44.228Z,1594389524.228 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-07-10T13:58:44.228Z,1594389524.228 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-07-10T13:58:44.232Z,1594389524.232 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-07-10T13:58:44.232Z,1594389524.232 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-07-10T13:58:44.236Z,1594389524.236 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-07-10T13:58:44.241Z,1594389524.241 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-07-10T13:58:44.241Z,1594389524.241 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-10T13:58:44.242Z,1594389524.242 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-10T13:58:44.246Z,1594389524.246 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-10T13:58:44.246Z,1594389524.246 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-10T13:58:44.250Z,1594389524.250 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-10T13:58:44.250Z,1594389524.250 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-10T13:58:44.254Z,1594389524.254 [WetLabsBB2FL] Loaded
2020-07-10T13:58:44.255Z,1594389524.255 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-07-10T13:58:44.256Z,1594389524.256 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A374E0
2020-07-10T13:58:44.256Z,1594389524.256 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 914
2020-07-10T13:58:44.257Z,1594389524.257 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-07-10T13:58:44.258Z,1594389524.258 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-07-10T13:58:44.298Z,1594389524.298 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-07-10T13:58:44.299Z,1594389524.299 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-07-10T13:58:44.636Z,1594389524.636 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-07-10T13:58:44.636Z,1594389524.636 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-07-10T13:58:44.798Z,1594389524.798 [SBIT](DEBUG): Construct Startup Built In Test.
2020-07-10T13:58:44.809Z,1594389524.809 [SBIT] Loaded
2020-07-10T13:58:44.809Z,1594389524.809 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-07-10T13:58:44.810Z,1594389524.810 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-07-10T13:58:44.823Z,1594389524.823 [IBIT] Loaded
2020-07-10T13:58:44.823Z,1594389524.823 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-07-10T13:58:44.826Z,1594389524.826 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-07-10T13:58:44.967Z,1594389524.967 [CBIT] Loaded
2020-07-10T13:58:44.967Z,1594389524.967 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-07-10T13:58:44.967Z,1594389524.967 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-07-10T13:58:44.968Z,1594389524.968 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-07-10T13:58:45.064Z,1594389525.064 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-07-10T13:58:45.068Z,1594389525.068 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-07-10T13:58:45.069Z,1594389525.069 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-07-10T13:58:45.076Z,1594389525.076 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-07-10T13:58:45.077Z,1594389525.077 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B5D4E0
2020-07-10T13:58:45.078Z,1594389525.078 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 915
2020-07-10T13:58:45.082Z,1594389525.082 [Supervisor](INFO): Main Thread ID is 816
2020-07-10T13:58:45.082Z,1594389525.082 [Supervisor](DEBUG): Running supervisor.
2020-07-10T13:58:45.083Z,1594389525.083 [CommandLine ThreadHandler](INFO): Handler Thread ID is 916
2020-07-10T13:58:45.085Z,1594389525.085 [controlThread ThreadHandler](INFO): Handler Thread ID is 917
2020-07-10T13:58:45.086Z,1594389525.086 [controlThread](DEBUG): Initializing ControlThread
2020-07-10T13:58:45.087Z,1594389525.087 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-07-10T13:58:45.088Z,1594389525.088 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-07-10T13:58:45.089Z,1594389525.089 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-07-10T13:58:45.089Z,1594389525.089 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-07-10T13:58:45.091Z,1594389525.091 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-07-10T13:58:45.092Z,1594389525.092 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-07-10T13:58:45.095Z,1594389525.095 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-07-10T13:58:45.095Z,1594389525.095 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-07-10T13:58:45.095Z,1594389525.095 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-07-10T13:58:45.096Z,1594389525.096 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-07-10T13:58:45.096Z,1594389525.096 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-07-10T13:58:45.096Z,1594389525.096 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-07-10T13:58:45.101Z,1594389525.101 [SBIT](INFO): Initialize SBIT Component.
2020-07-10T13:58:45.102Z,1594389525.102 [SBIT](IMPORTANT): git: 2020-07-06
2020-07-10T13:58:45.102Z,1594389525.102 [SBIT](INFO): git hash: 27d6d613a5802cbc38609e1e9bc8b2bacbda1d86
2020-07-10T13:58:45.102Z,1594389525.102 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-07-10T13:58:45.103Z,1594389525.103 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019
2020-07-10T13:58:45.104Z,1594389525.104 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2020-07-10T13:58:45.105Z,1594389525.105 [IBIT](INFO): Initialize IBIT Component.
2020-07-10T13:58:45.106Z,1594389525.106 [CBIT](DEBUG): Initialize CBIT Component.
2020-07-10T13:58:45.107Z,1594389525.107 [logger ThreadHandler](INFO): Handler Thread ID is 918
2020-07-10T13:58:45.117Z,1594389525.117 [CBIT](DEBUG): Initialized mux pins.
2020-07-10T13:58:45.117Z,1594389525.117 [CBIT](DEBUG): Initializing the watchdog timer.
2020-07-10T13:58:45.125Z,1594389525.125 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 919
2020-07-10T13:58:45.126Z,1594389525.126 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-07-10T13:58:45.137Z,1594389525.137 [Onboard ThreadHandler](INFO): Handler Thread ID is 920
2020-07-10T13:58:45.141Z,1594389525.141 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-07-10T13:58:45.142Z,1594389525.142 [CBIT](DEBUG): Initializing heartbeat.
2020-07-10T13:58:45.167Z,1594389525.167 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 921
2020-07-10T13:58:45.174Z,1594389525.174 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 922
2020-07-10T13:58:45.174Z,1594389525.174 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-07-10T13:58:45.178Z,1594389525.178 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 924
2020-07-10T13:58:45.179Z,1594389525.179 [WetLabsBB2FL](INFO): Powering down
2020-07-10T13:58:45.213Z,1594389525.213 [CBIT](DEBUG): Deactivating GF circuits.
2020-07-10T13:58:45.213Z,1594389525.213 [CBIT](DEBUG): Deactivating emergency mode.
2020-07-10T13:58:45.249Z,1594389525.249 [CBIT](DEBUG): Backplane powered.
2020-07-10T13:58:45.250Z,1594389525.250 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-07-10T13:58:45.311Z,1594389525.311 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 925
2020-07-10T13:58:45.391Z,1594389525.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-07-10T13:58:45.391Z,1594389525.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-07-10T13:58:45.391Z,1594389525.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-07-10T13:58:45.391Z,1594389525.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-07-10T13:58:45.392Z,1594389525.392 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-07-10T13:58:45.392Z,1594389525.392 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-07-10T13:58:45.392Z,1594389525.392 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-07-10T13:58:45.392Z,1594389525.392 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-07-10T13:58:45.392Z,1594389525.392 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-07-10T13:58:45.392Z,1594389525.392 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-07-10T13:58:45.393Z,1594389525.393 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-07-10T13:58:45.393Z,1594389525.393 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-07-10T13:58:45.393Z,1594389525.393 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-07-10T13:58:45.393Z,1594389525.393 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-07-10T13:58:45.393Z,1594389525.393 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-07-10T13:58:45.394Z,1594389525.394 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-07-10T13:58:45.487Z,1594389525.487 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-10T13:58:45.569Z,1594389525.569 [Radio_Surface](INFO): Powering up
2020-07-10T13:58:45.594Z,1594389525.594 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2020-07-10T13:58:45.595Z,1594389525.595 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-07-10T13:58:45.658Z,1594389525.658 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-07-10T13:58:45.659Z,1594389525.659 [Default:A.Wait](DEBUG): Construct Wait.
2020-07-10T13:58:45.683Z,1594389525.683 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-10T13:58:45.719Z,1594389525.719 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-07-10T13:58:45.722Z,1594389525.722 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-07-10T13:58:45.752Z,1594389525.752 [Default:E.Execute](DEBUG): Construct Execute.
2020-07-10T13:58:45.770Z,1594389525.770 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2020-07-10T13:58:45.774Z,1594389525.774 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,BPC1,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-07-10T13:58:45.779Z,1594389525.779 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-07-10T13:58:46.041Z,1594389526.041 [CANONSampler](INFO): Powering down
2020-07-10T13:58:46.055Z,1594389526.055 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-07-10T13:58:46.061Z,1594389526.061 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-07-10T13:58:46.062Z,1594389526.062 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-07-10T13:58:46.069Z,1594389526.069 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-07-10T13:58:46.070Z,1594389526.070 [MassServo](DEBUG): Initializing EZServoServo.
2020-07-10T13:58:46.077Z,1594389526.077 [MassServo](DEBUG): Initializing MassServo.
2020-07-10T13:58:46.078Z,1594389526.078 [RudderServo](DEBUG): Initializing EZServoServo.
2020-07-10T13:58:46.085Z,1594389526.085 [RudderServo](DEBUG): Initializing RudderServo.
2020-07-10T13:58:46.086Z,1594389526.086 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-07-10T13:58:46.093Z,1594389526.093 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-07-10T13:58:46.937Z,1594389526.937 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-07-10T13:58:46.937Z,1594389526.937 [RudderServo](FAULT): Rudder failed to initialize
2020-07-10T13:58:46.937Z,1594389526.937 [RudderServo] Communications Fault, FailCount= 1
2020-07-10T13:58:46.937Z,1594389526.937 [RudderServo](ERROR): Communications Fault
2020-07-10T13:58:47.108Z,1594389527.108 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-07-10T13:58:47.282Z,1594389527.282 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-07-10T13:58:47.282Z,1594389527.282 [RudderServo](INFO): Powering down
2020-07-10T13:58:47.945Z,1594389527.945 [RudderServo](DEBUG): Initializing EZServoServo.
2020-07-10T13:58:48.066Z,1594389528.066 [RudderServo](DEBUG): Initializing RudderServo.
2020-07-10T13:58:48.070Z,1594389528.070 [CBIT](INFO): Clearing failed state for component RudderServo
2020-07-10T13:58:48.070Z,1594389528.070 [RudderServo] No Fault, FailCount= 1
2020-07-10T13:58:52.246Z,1594389532.246 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-07-10T13:59:05.735Z,1594389545.735 [SBIT](IMPORTANT): Beginning Startup BIT
2020-07-10T13:59:05.739Z,1594389545.739 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-10T13:59:11.359Z,1594389551.359 [NAL9602](INFO): Powering up NAL9602
2020-07-10T13:59:16.658Z,1594389556.658 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.013338
CHAN A1 (24V): -0.108528
CHAN A2 (12V): -0.002221
CHAN A3 (5V): -0.001348
CHAN B0 (3.3V): 0.000048
CHAN B1 (3.15aV): -0.000373
CHAN B2 (3.15bV): -0.000225
CHAN B3 (GND): 0.000067
OPEN: -0.000415
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-10T13:59:22.267Z,1594389562.267 [NAL9602](INFO): NAL9602 initialized
2020-07-10T13:59:59.887Z,1594389599.887 [SBIT](IMPORTANT): SBIT PASSED
2020-07-10T13:59:59.888Z,1594389599.888 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-07-10T13:59:59.888Z,1594389599.888 [SBIT](IMPORTANT): CBIT.gf24Offset=154 microampere;

2020-07-10T13:59:59.889Z,1594389599.889 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2020-07-10T13:59:59.889Z,1594389599.889 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter;

2020-07-10T13:59:59.889Z,1594389599.889 [SBIT](IMPORTANT): Express linearApproximation platform_average_power 1.000000 watt;

2020-07-10T13:59:59.889Z,1594389599.889 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=39.071205 cubic_centimeter;

2020-07-10T13:59:59.889Z,1594389599.889 [SBIT](IMPORTANT): VerticalControl.massDefault=0.132715 millimeter;

2020-07-10T14:00:00.262Z,1594389600.262 [MissionManager](IMPORTANT): Started mission Startup
2020-07-10T14:00:00.263Z,1594389600.263 [Startup] Running Loop=1
2020-07-10T14:00:00.263Z,1594389600.263 [Startup](DEBUG): Aggregate::initialize Startup
2020-07-10T14:00:00.263Z,1594389600.263 [Startup:A.GoToSurface] Running Loop=1
2020-07-10T14:00:00.263Z,1594389600.263 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-10T14:00:00.263Z,1594389600.263 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-10T14:00:00.264Z,1594389600.264 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-10T14:00:00.264Z,1594389600.264 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-10T14:00:00.265Z,1594389600.265 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-10T14:00:00.265Z,1594389600.265 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-10T14:00:00.266Z,1594389600.266 [Startup:StartupSatComms] Running Loop=1
2020-07-10T14:00:00.266Z,1594389600.266 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-07-10T14:00:00.267Z,1594389600.267 [Startup:StartupSatComms:A] Running Loop=1
2020-07-10T14:00:00.673Z,1594389600.673 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-07-10T14:00:44.354Z,1594389644.354 [CommandLine](IMPORTANT): got command burn on
2020-07-10T14:00:44.354Z,1594389644.354 [CommandLine](IMPORTANT): Activating dropweight wire
2020-07-10T14:01:00.456Z,1594389660.456 [Startup:StartupSatComms:A](INFO): Timed out from 2020-07-10T14:00:00.3Z
2020-07-10T14:01:00.456Z,1594389660.456 [Startup:StartupSatComms:A] Stopped
2020-07-10T14:01:00.456Z,1594389660.456 [Startup:StartupSatComms:B] Running Loop=1
2020-07-10T14:01:00.887Z,1594389660.887 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-07-10T14:01:14.773Z,1594389674.773 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005495
2020-07-10T14:01:22.013Z,1594389682.013 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200710T135833/Courier0000.lzma
2020-07-10T14:01:23.015Z,1594389683.015 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0000.lzma.bak
2020-07-10T14:01:23.016Z,1594389683.016 [DataOverHttps](INFO): SBD MOMSN=12432308
2020-07-10T14:01:38.443Z,1594389698.443 [DataOverHttps](INFO): Sending 884 bytes from file Logs/20200710T135833/Express0001.lzma
2020-07-10T14:01:39.443Z,1594389699.443 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0001.lzma.bak
2020-07-10T14:01:39.443Z,1594389699.443 [DataOverHttps](INFO): SBD MOMSN=12432311
2020-07-10T14:01:40.470Z,1594389700.470 [Startup:StartupSatComms:B] Stopped
2020-07-10T14:01:40.470Z,1594389700.470 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-07-10T14:01:40.470Z,1594389700.470 [Startup:StartupSatComms] Stopped
2020-07-10T14:01:40.470Z,1594389700.470 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-07-10T14:01:40.471Z,1594389700.471 [Startup](INFO): Completed Startup
2020-07-10T14:01:40.471Z,1594389700.471 [MissionManager](INFO): Startup is completed.
2020-07-10T14:01:40.471Z,1594389700.471 [MissionManager](INFO): Uninitializing Mission Startup
2020-07-10T14:01:40.472Z,1594389700.472 [Startup] Stopped
2020-07-10T14:01:40.472Z,1594389700.472 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-07-10T14:01:40.472Z,1594389700.472 [Startup:A.GoToSurface] Stopped
2020-07-10T14:01:40.472Z,1594389700.472 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-10T14:01:40.863Z,1594389700.863 [MissionManager](IMPORTANT): Started mission Default
2020-07-10T14:01:40.863Z,1594389700.863 [Default] Running Loop=1
2020-07-10T14:01:40.863Z,1594389700.863 [Default](DEBUG): Aggregate::initialize Default
2020-07-10T14:01:40.863Z,1594389700.863 [Default:B.GoToSurface] Running Loop=1
2020-07-10T14:01:40.863Z,1594389700.863 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-10T14:01:40.864Z,1594389700.864 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-10T14:01:40.864Z,1594389700.864 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-10T14:01:40.864Z,1594389700.864 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-10T14:01:40.865Z,1594389700.865 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-10T14:01:40.865Z,1594389700.865 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-10T14:01:40.865Z,1594389700.865 [Default:A.Wait] Running Loop=1
2020-07-10T14:01:40.865Z,1594389700.865 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-07-10T14:01:45.323Z,1594389705.323 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-07-10T14:01:45.323Z,1594389705.323 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:01:45.333Z,1594389705.333 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:01:45.743Z,1594389705.743 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:01:45.743Z,1594389705.743 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-07-10T14:01:54.266Z,1594389714.266 [Default:A.Wait](INFO): Done Waiting.
2020-07-10T14:01:54.266Z,1594389714.266 [Default:A.Wait] Stopped
2020-07-10T14:01:54.266Z,1594389714.266 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T14:01:54.612Z,1594389714.612 [Default:CheckIn] Running Loop=1
2020-07-10T14:01:54.613Z,1594389714.613 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T14:01:54.613Z,1594389714.613 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T14:01:55.016Z,1594389715.016 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-07-10T14:02:06.935Z,1594389726.935 [CommandLine](IMPORTANT): got command burn off
2020-07-10T14:02:06.935Z,1594389726.935 [CommandLine](IMPORTANT): Deactivating dropweight wire
2020-07-10T14:02:56.172Z,1594389776.172 [CommandLine](IMPORTANT): got command gfscan
2020-07-10T14:02:56.546Z,1594389776.546 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-10T14:03:07.485Z,1594389787.485 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.660246
CHAN A1 (24V): -0.162099
CHAN A2 (12V): -0.002559
CHAN A3 (5V): -0.001347
CHAN B0 (3.3V): 0.000385
CHAN B1 (3.15aV): -0.000202
CHAN B2 (3.15bV): -0.000161
CHAN B3 (GND): -0.000026
OPEN: -0.000362
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-10T14:03:39.868Z,1594389819.868 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset -27.000000 microampere persist
2020-07-10T14:03:42.556Z,1594389822.556 [CommandLine](IMPORTANT): got command gfscan
2020-07-10T14:03:42.599Z,1594389822.599 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-10T14:03:53.525Z,1594389833.525 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015246
CHAN A1 (24V): 0.015590
CHAN A2 (12V): -0.002324
CHAN A3 (5V): -0.001388
CHAN B0 (3.3V): -0.000089
CHAN B1 (3.15aV): -0.000434
CHAN B2 (3.15bV): -0.000511
CHAN B3 (GND): -0.000212
OPEN: -0.000562
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-10T14:04:03.532Z,1594389843.532 [CommandLine](IMPORTANT): got command gfscan
2020-07-10T14:04:03.603Z,1594389843.603 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-10T14:04:14.496Z,1594389854.496 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015311
CHAN A1 (24V): 0.014076
CHAN A2 (12V): -0.001803
CHAN A3 (5V): -0.001803
CHAN B0 (3.3V): -0.000024
CHAN B1 (3.15aV): -0.000484
CHAN B2 (3.15bV): -0.000293
CHAN B3 (GND): 0.000111
OPEN: -0.000468
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-10T14:04:26.188Z,1594389866.188 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-07-10T14:04:45.948Z,1594389885.948 [CommandLine](IMPORTANT): got command gfscan
2020-07-10T14:04:46.165Z,1594389886.165 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-07-10T14:04:46.165Z,1594389886.165 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:04:46.209Z,1594389886.209 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:04:46.209Z,1594389886.209 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-10T14:04:46.508Z,1594389886.508 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:04:46.508Z,1594389886.508 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-07-10T14:04:56.920Z,1594389896.920 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.014830
CHAN A1 (24V): 0.011962
CHAN A2 (12V): -0.002433
CHAN A3 (5V): -0.001519
CHAN B0 (3.3V): 0.000122
CHAN B1 (3.15aV): 0.000248
CHAN B2 (3.15bV): -0.000417
CHAN B3 (GND): -0.000286
OPEN: -0.000457
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-10T14:05:14.248Z,1594389914.248 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset 
2020-07-10T14:05:14.248Z,1594389914.248 [CommandLine](FAULT): Incomplete syntax. Try: help configSet
2020-07-10T14:05:22.003Z,1594389922.003 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset 0.000000 microampere
2020-07-10T14:05:24.376Z,1594389924.376 [CommandLine](IMPORTANT): got command gfscan
2020-07-10T14:05:24.396Z,1594389924.396 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-10T14:05:35.301Z,1594389935.301 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015283
CHAN A1 (24V): -0.016735
CHAN A2 (12V): -0.002600
CHAN A3 (5V): -0.001494
CHAN B0 (3.3V): -0.000196
CHAN B1 (3.15aV): -0.000095
CHAN B2 (3.15bV): -0.000105
CHAN B3 (GND): 0.000120
OPEN: -0.000409
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-10T14:05:43.940Z,1594389943.940 [CommandLine](IMPORTANT): got command gfscan
2020-07-10T14:05:44.207Z,1594389944.207 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-10T14:05:55.171Z,1594389955.171 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015128
CHAN A1 (24V): -0.017622
CHAN A2 (12V): -0.002259
CHAN A3 (5V): -0.001409
CHAN B0 (3.3V): 0.000022
CHAN B1 (3.15aV): -0.000398
CHAN B2 (3.15bV): -0.000143
CHAN B3 (GND): -0.000085
OPEN: -0.000394
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-10T14:06:09.428Z,1594389969.428 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset -17.000000 microampere persist
2020-07-10T14:06:10.996Z,1594389970.996 [CommandLine](IMPORTANT): got command gfscan
2020-07-10T14:06:11.253Z,1594389971.253 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-10T14:06:22.196Z,1594389982.196 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015499
CHAN A1 (24V): -0.001283
CHAN A2 (12V): -0.002359
CHAN A3 (5V): -0.001442
CHAN B0 (3.3V): -0.000107
CHAN B1 (3.15aV): -0.000375
CHAN B2 (3.15bV): -0.000270
CHAN B3 (GND): -0.000245
OPEN: -0.000389
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-10T14:06:50.024Z,1594390010.024 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-07-10T14:06:50.039Z,1594390010.039 [BPC1](INFO): Received data from all battery sticks.
2020-07-10T14:06:54.890Z,1594390014.890 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T14:01:54.6Z
2020-07-10T14:06:54.890Z,1594390014.890 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T14:06:54.890Z,1594390014.890 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T14:06:55.301Z,1594390015.301 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-07-10T14:07:02.270Z,1594390022.270 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20200710T135833/Courier0004.lzma
2020-07-10T14:07:03.272Z,1594390023.272 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0004.lzma.bak
2020-07-10T14:07:03.272Z,1594390023.272 [DataOverHttps](INFO): SBD MOMSN=12432331
2020-07-10T14:07:18.623Z,1594390038.623 [DataOverHttps](INFO): Sending 847 bytes from file Logs/20200710T135833/Express0005.lzma
2020-07-10T14:07:19.624Z,1594390039.624 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0005.lzma.bak
2020-07-10T14:07:19.624Z,1594390039.624 [DataOverHttps](INFO): SBD MOMSN=12432333
2020-07-10T14:07:20.732Z,1594390040.732 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T14:07:20.732Z,1594390040.732 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T14:07:20.732Z,1594390040.732 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T14:07:47.016Z,1594390067.016 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-07-10T14:07:47.016Z,1594390067.016 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:07:47.026Z,1594390067.026 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:07:47.416Z,1594390067.416 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:07:47.416Z,1594390067.416 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-07-10T14:09:25.556Z,1594390165.556 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T14:09:25.556Z,1594390165.556 [NAL9602] Data Fault, FailCount= 1
2020-07-10T14:09:25.556Z,1594390165.556 [NAL9602](ERROR): Data Fault
2020-07-10T14:09:25.622Z,1594390165.622 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T14:09:25.968Z,1594390165.968 [NAL9602](INFO): Powering down
2020-07-10T14:09:26.821Z,1594390166.821 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T14:09:26.821Z,1594390166.821 [NAL9602] No Fault, FailCount= 1
2020-07-10T14:09:56.260Z,1594390196.260 [NAL9602](INFO): Powering up NAL9602
2020-07-10T14:10:07.174Z,1594390207.174 [NAL9602](INFO): NAL9602 initialized
2020-07-10T14:10:47.985Z,1594390247.985 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-07-10T14:10:47.985Z,1594390247.985 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:10:47.995Z,1594390247.995 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:10:48.409Z,1594390248.409 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:10:48.409Z,1594390248.409 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-07-10T14:12:21.321Z,1594390341.321 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T14:12:21.321Z,1594390341.321 [Default:CheckIn:C.Wait] Stopped
2020-07-10T14:12:21.321Z,1594390341.321 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T14:12:21.321Z,1594390341.321 [Default:CheckIn:D] Running Loop=1
2020-07-10T14:12:21.736Z,1594390341.736 [Default:CheckIn:D] Stopped
2020-07-10T14:12:21.736Z,1594390341.736 [Default:CheckIn:E] Running Loop=1
2020-07-10T14:12:22.112Z,1594390342.112 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.681211 min 
2020-07-10T14:12:22.112Z,1594390342.112 [Default:CheckIn:E] Stopped
2020-07-10T14:12:22.112Z,1594390342.112 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T14:12:22.112Z,1594390342.112 [Default:CheckIn] Stopped
2020-07-10T14:12:22.112Z,1594390342.112 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T14:12:22.112Z,1594390342.112 [Default:CheckIn](INFO): Running loop #2
2020-07-10T14:12:22.112Z,1594390342.112 [Default:CheckIn] Running Loop=2
2020-07-10T14:12:22.112Z,1594390342.112 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T14:12:22.112Z,1594390342.112 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T14:13:48.999Z,1594390428.999 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-07-10T14:13:48.000Z,1594390429.000 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:13:49.010Z,1594390429.010 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:13:49.388Z,1594390429.388 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:13:49.388Z,1594390429.388 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-07-10T14:16:49.981Z,1594390609.981 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-07-10T14:16:49.981Z,1594390609.981 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:16:49.991Z,1594390609.991 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:16:50.380Z,1594390610.380 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:16:50.380Z,1594390610.380 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-07-10T14:17:22.309Z,1594390642.309 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T14:12:22.1Z
2020-07-10T14:17:22.309Z,1594390642.309 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T14:17:22.309Z,1594390642.309 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T14:17:29.038Z,1594390649.038 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0007.lzma
2020-07-10T14:17:30.040Z,1594390650.040 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0007.lzma.bak
2020-07-10T14:17:30.040Z,1594390650.040 [DataOverHttps](INFO): SBD MOMSN=12432365
2020-07-10T14:17:45.402Z,1594390665.402 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20200710T135833/Express0008.lzma
2020-07-10T14:17:46.404Z,1594390666.404 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0008.lzma.bak
2020-07-10T14:17:46.404Z,1594390666.404 [DataOverHttps](INFO): SBD MOMSN=12432367
2020-07-10T14:17:47.756Z,1594390667.756 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T14:17:47.757Z,1594390667.757 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T14:17:47.757Z,1594390667.757 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T14:19:50.971Z,1594390790.971 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-07-10T14:19:50.971Z,1594390790.971 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:19:50.985Z,1594390790.985 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:19:51.396Z,1594390791.396 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:19:51.396Z,1594390791.396 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-07-10T14:20:10.336Z,1594390810.336 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T14:20:10.337Z,1594390810.337 [NAL9602] Data Fault, FailCount= 2
2020-07-10T14:20:10.337Z,1594390810.337 [NAL9602](ERROR): Data Fault
2020-07-10T14:20:10.353Z,1594390810.353 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T14:20:10.748Z,1594390810.748 [NAL9602](INFO): Powering down
2020-07-10T14:20:11.564Z,1594390811.564 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T14:20:11.564Z,1594390811.564 [NAL9602] No Fault, FailCount= 2
2020-07-10T14:20:41.048Z,1594390841.048 [NAL9602](INFO): Powering up NAL9602
2020-07-10T14:20:51.952Z,1594390851.952 [NAL9602](INFO): NAL9602 initialized
2020-07-10T14:22:48.345Z,1594390968.345 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T14:22:48.345Z,1594390968.345 [Default:CheckIn:C.Wait] Stopped
2020-07-10T14:22:48.345Z,1594390968.345 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T14:22:48.345Z,1594390968.345 [Default:CheckIn:D] Running Loop=1
2020-07-10T14:22:48.755Z,1594390968.755 [Default:CheckIn:D] Stopped
2020-07-10T14:22:48.755Z,1594390968.755 [Default:CheckIn:E] Running Loop=1
2020-07-10T14:22:49.128Z,1594390969.128 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.131527 min 
2020-07-10T14:22:49.128Z,1594390969.128 [Default:CheckIn:E] Stopped
2020-07-10T14:22:49.128Z,1594390969.128 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T14:22:49.128Z,1594390969.128 [Default:CheckIn] Stopped
2020-07-10T14:22:49.128Z,1594390969.128 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T14:22:49.128Z,1594390969.128 [Default:CheckIn](INFO): Running loop #3
2020-07-10T14:22:49.128Z,1594390969.128 [Default:CheckIn] Running Loop=3
2020-07-10T14:22:49.129Z,1594390969.129 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T14:22:49.129Z,1594390969.129 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T14:22:51.955Z,1594390971.955 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-07-10T14:22:51.955Z,1594390971.955 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:22:51.965Z,1594390971.965 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:22:52.368Z,1594390972.368 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:22:52.368Z,1594390972.368 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-07-10T14:25:52.947Z,1594391152.947 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-07-10T14:25:52.947Z,1594391152.947 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:25:52.957Z,1594391152.957 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:25:53.368Z,1594391153.368 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:25:53.368Z,1594391153.368 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-07-10T14:27:49.304Z,1594391269.304 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T14:22:49.1Z
2020-07-10T14:27:49.304Z,1594391269.304 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T14:27:49.304Z,1594391269.304 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T14:27:56.838Z,1594391276.838 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0010.lzma
2020-07-10T14:27:57.840Z,1594391277.840 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0010.lzma.bak
2020-07-10T14:27:57.840Z,1594391277.840 [DataOverHttps](INFO): SBD MOMSN=12432371
2020-07-10T14:28:13.214Z,1594391293.214 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200710T135833/Express0011.lzma
2020-07-10T14:28:14.216Z,1594391294.216 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0011.lzma.bak
2020-07-10T14:28:14.216Z,1594391294.216 [DataOverHttps](INFO): SBD MOMSN=12432373
2020-07-10T14:28:15.564Z,1594391295.564 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T14:28:15.564Z,1594391295.564 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T14:28:15.565Z,1594391295.565 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T14:28:53.944Z,1594391333.944 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-07-10T14:28:53.944Z,1594391333.944 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:28:53.982Z,1594391333.982 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:28:54.391Z,1594391334.391 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:28:54.391Z,1594391334.391 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-07-10T14:29:04.492Z,1594391344.492 [CommandLine](IMPORTANT): got command failComponent
2020-07-10T14:29:04.492Z,1594391344.492 [CommandLine](IMPORTANT): Failed components:
2020-07-10T14:29:04.492Z,1594391344.492 [CommandLine](IMPORTANT): No failed Components.
2020-07-10T14:30:54.332Z,1594391454.332 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T14:30:54.332Z,1594391454.332 [NAL9602] Data Fault, FailCount= 3
2020-07-10T14:30:54.332Z,1594391454.332 [NAL9602](ERROR): Data Fault
2020-07-10T14:30:54.353Z,1594391454.353 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T14:30:54.746Z,1594391454.746 [NAL9602](INFO): Powering down
2020-07-10T14:30:55.564Z,1594391455.564 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T14:30:55.564Z,1594391455.564 [NAL9602] No Fault, FailCount= 3
2020-07-10T14:31:25.040Z,1594391485.040 [NAL9602](INFO): Powering up NAL9602
2020-07-10T14:31:35.948Z,1594391495.948 [NAL9602](INFO): NAL9602 initialized
2020-07-10T14:31:54.943Z,1594391514.943 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-07-10T14:31:54.943Z,1594391514.943 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:31:54.953Z,1594391514.953 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:31:55.365Z,1594391515.365 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:31:55.365Z,1594391515.365 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-07-10T14:33:16.168Z,1594391596.168 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T14:33:16.168Z,1594391596.168 [Default:CheckIn:C.Wait] Stopped
2020-07-10T14:33:16.168Z,1594391596.168 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T14:33:16.168Z,1594391596.168 [Default:CheckIn:D] Running Loop=1
2020-07-10T14:33:16.569Z,1594391596.569 [Default:CheckIn:D] Stopped
2020-07-10T14:33:16.569Z,1594391596.569 [Default:CheckIn:E] Running Loop=1
2020-07-10T14:33:16.949Z,1594391596.949 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.595089 min 
2020-07-10T14:33:16.949Z,1594391596.949 [Default:CheckIn:E] Stopped
2020-07-10T14:33:16.949Z,1594391596.949 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T14:33:16.949Z,1594391596.949 [Default:CheckIn] Stopped
2020-07-10T14:33:16.949Z,1594391596.949 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T14:33:16.949Z,1594391596.949 [Default:CheckIn](INFO): Running loop #4
2020-07-10T14:33:16.949Z,1594391596.949 [Default:CheckIn] Running Loop=4
2020-07-10T14:33:16.949Z,1594391596.949 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T14:33:16.949Z,1594391596.949 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T14:34:55.959Z,1594391695.959 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-07-10T14:34:55.959Z,1594391695.959 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:34:55.970Z,1594391695.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:34:56.372Z,1594391696.372 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:34:56.372Z,1594391696.372 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-07-10T14:37:56.926Z,1594391876.926 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-07-10T14:37:56.927Z,1594391876.927 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:37:56.937Z,1594391876.937 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:37:57.365Z,1594391877.365 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:37:57.365Z,1594391877.365 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-07-10T14:38:17.139Z,1594391897.139 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T14:33:16.9Z
2020-07-10T14:38:17.139Z,1594391897.139 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T14:38:17.139Z,1594391897.139 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T14:38:24.674Z,1594391904.674 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0013.lzma
2020-07-10T14:38:25.676Z,1594391905.676 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0013.lzma.bak
2020-07-10T14:38:25.676Z,1594391905.676 [DataOverHttps](INFO): SBD MOMSN=12432426
2020-07-10T14:38:41.026Z,1594391921.026 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20200710T135833/Express0014.lzma
2020-07-10T14:38:42.028Z,1594391922.028 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0014.lzma.bak
2020-07-10T14:38:42.029Z,1594391922.029 [DataOverHttps](INFO): SBD MOMSN=12432428
2020-07-10T14:38:43.398Z,1594391923.398 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T14:38:43.398Z,1594391923.398 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T14:38:43.398Z,1594391923.398 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T14:40:57.951Z,1594392057.951 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-07-10T14:40:57.951Z,1594392057.951 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:40:57.961Z,1594392057.961 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:40:58.333Z,1594392058.333 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:40:58.333Z,1594392058.333 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-07-10T14:41:37.496Z,1594392097.496 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T14:41:37.496Z,1594392097.496 [NAL9602] Data Fault, FailCount= 4
2020-07-10T14:41:37.496Z,1594392097.496 [NAL9602](ERROR): Data Fault
2020-07-10T14:41:37.513Z,1594392097.513 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T14:41:37.909Z,1594392097.909 [NAL9602](INFO): Powering down
2020-07-10T14:41:38.724Z,1594392098.724 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T14:41:38.725Z,1594392098.725 [NAL9602] No Fault, FailCount= 4
2020-07-10T14:42:08.204Z,1594392128.204 [NAL9602](INFO): Powering up NAL9602
2020-07-10T14:42:19.114Z,1594392139.114 [NAL9602](INFO): NAL9602 initialized
2020-07-10T14:43:43.988Z,1594392223.988 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T14:43:43.988Z,1594392223.988 [Default:CheckIn:C.Wait] Stopped
2020-07-10T14:43:43.988Z,1594392223.988 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T14:43:43.988Z,1594392223.988 [Default:CheckIn:D] Running Loop=1
2020-07-10T14:43:44.377Z,1594392224.377 [Default:CheckIn:D] Stopped
2020-07-10T14:43:44.378Z,1594392224.378 [Default:CheckIn:E] Running Loop=1
2020-07-10T14:43:44.789Z,1594392224.789 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.058569 min 
2020-07-10T14:43:44.789Z,1594392224.789 [Default:CheckIn:E] Stopped
2020-07-10T14:43:44.789Z,1594392224.789 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T14:43:44.789Z,1594392224.789 [Default:CheckIn] Stopped
2020-07-10T14:43:44.789Z,1594392224.789 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T14:43:44.790Z,1594392224.790 [Default:CheckIn](INFO): Running loop #5
2020-07-10T14:43:44.790Z,1594392224.790 [Default:CheckIn] Running Loop=5
2020-07-10T14:43:44.790Z,1594392224.790 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T14:43:44.790Z,1594392224.790 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T14:43:58.936Z,1594392238.936 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-07-10T14:43:58.936Z,1594392238.936 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:43:58.947Z,1594392238.947 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:43:59.357Z,1594392239.357 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:43:59.357Z,1594392239.357 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-07-10T14:46:59.927Z,1594392419.927 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-07-10T14:46:59.927Z,1594392419.927 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:46:59.937Z,1594392419.937 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:47:00.351Z,1594392420.351 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:47:00.351Z,1594392420.351 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-07-10T14:48:44.954Z,1594392524.954 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T14:43:44.8Z
2020-07-10T14:48:44.954Z,1594392524.954 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T14:48:44.954Z,1594392524.954 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T14:48:52.322Z,1594392532.322 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0016.lzma
2020-07-10T14:48:53.324Z,1594392533.324 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0016.lzma.bak
2020-07-10T14:48:53.324Z,1594392533.324 [DataOverHttps](INFO): SBD MOMSN=12432450
2020-07-10T14:49:08.710Z,1594392548.710 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200710T135833/Express0017.lzma
2020-07-10T14:49:09.712Z,1594392549.712 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0017.lzma.bak
2020-07-10T14:49:09.712Z,1594392549.712 [DataOverHttps](INFO): SBD MOMSN=12432452
2020-07-10T14:49:10.804Z,1594392550.804 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T14:49:10.804Z,1594392550.804 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T14:49:10.804Z,1594392550.804 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T14:50:00.909Z,1594392600.909 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-07-10T14:50:00.909Z,1594392600.909 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:50:00.919Z,1594392600.919 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:50:01.337Z,1594392601.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:50:01.337Z,1594392601.337 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-07-10T14:52:20.280Z,1594392740.280 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T14:52:20.280Z,1594392740.280 [NAL9602] Data Fault, FailCount= 5
2020-07-10T14:52:20.280Z,1594392740.280 [NAL9602](ERROR): Data Fault
2020-07-10T14:52:20.331Z,1594392740.331 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T14:52:20.331Z,1594392740.331 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-07-10T14:52:20.680Z,1594392740.680 [NAL9602](INFO): Powering down
2020-07-10T14:52:20.699Z,1594392740.699 [CommandLine](FAULT): Scheduling is paused
2020-07-10T14:52:20.700Z,1594392740.700 [CBIT](INFO): Critical error at 20200710T145220
2020-07-10T14:52:20.700Z,1594392740.700 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-07-10T14:53:01.907Z,1594392781.907 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-07-10T14:53:01.907Z,1594392781.907 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:53:01.917Z,1594392781.917 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:53:02.325Z,1594392782.325 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:53:02.325Z,1594392782.325 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-07-10T14:54:11.395Z,1594392851.395 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T14:54:11.395Z,1594392851.395 [Default:CheckIn:C.Wait] Stopped
2020-07-10T14:54:11.395Z,1594392851.395 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T14:54:11.395Z,1594392851.395 [Default:CheckIn:D] Running Loop=1
2020-07-10T14:54:11.800Z,1594392851.800 [Default:CheckIn:D] Stopped
2020-07-10T14:54:11.800Z,1594392851.800 [Default:CheckIn:E] Running Loop=1
2020-07-10T14:54:12.197Z,1594392852.197 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.515613 min 
2020-07-10T14:54:12.197Z,1594392852.197 [Default:CheckIn:E] Stopped
2020-07-10T14:54:12.197Z,1594392852.197 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T14:54:12.197Z,1594392852.197 [Default:CheckIn] Stopped
2020-07-10T14:54:12.197Z,1594392852.197 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T14:54:12.197Z,1594392852.197 [Default:CheckIn](INFO): Running loop #6
2020-07-10T14:54:12.197Z,1594392852.197 [Default:CheckIn] Running Loop=6
2020-07-10T14:54:12.197Z,1594392852.197 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T14:54:12.198Z,1594392852.198 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T14:54:20.284Z,1594392860.284 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T14:54:20.284Z,1594392860.284 [NAL9602] No Fault, FailCount= 5
2020-07-10T14:54:20.668Z,1594392860.668 [NAL9602](INFO): Powering up NAL9602
2020-07-10T14:54:31.576Z,1594392871.576 [NAL9602](INFO): NAL9602 initialized
2020-07-10T14:56:02.886Z,1594392962.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-07-10T14:56:02.886Z,1594392962.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:56:02.896Z,1594392962.896 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:56:03.305Z,1594392963.305 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T14:56:03.305Z,1594392963.305 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-07-10T14:59:03.884Z,1594393143.884 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2020-07-10T14:59:03.884Z,1594393143.884 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T14:59:03.894Z,1594393143.894 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:59:03.894Z,1594393143.894 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T14:59:12.369Z,1594393152.369 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T14:54:12.2Z
2020-07-10T14:59:12.369Z,1594393152.369 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T14:59:12.369Z,1594393152.369 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T14:59:19.110Z,1594393159.110 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20200710T135833/Courier0019.lzma
2020-07-10T14:59:20.114Z,1594393160.114 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0019.lzma.bak
2020-07-10T14:59:20.114Z,1594393160.114 [DataOverHttps](INFO): SBD MOMSN=12432544
2020-07-10T14:59:35.474Z,1594393175.474 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20200710T135833/Express0020.lzma
2020-07-10T14:59:36.476Z,1594393176.476 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0020.lzma.bak
2020-07-10T14:59:36.476Z,1594393176.476 [DataOverHttps](INFO): SBD MOMSN=12432547
2020-07-10T14:59:37.820Z,1594393177.820 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T14:59:37.820Z,1594393177.820 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T14:59:37.820Z,1594393177.820 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T15:04:34.352Z,1594393474.352 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T15:04:34.352Z,1594393474.352 [NAL9602] Data Fault, FailCount= 1
2020-07-10T15:04:34.352Z,1594393474.352 [NAL9602](ERROR): Data Fault
2020-07-10T15:04:34.368Z,1594393474.368 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T15:04:34.757Z,1594393474.757 [NAL9602](INFO): Powering down
2020-07-10T15:04:35.579Z,1594393475.579 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T15:04:35.579Z,1594393475.579 [NAL9602] No Fault, FailCount= 1
2020-07-10T15:04:38.406Z,1594393478.406 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T15:04:38.407Z,1594393478.407 [Default:CheckIn:C.Wait] Stopped
2020-07-10T15:04:38.407Z,1594393478.407 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T15:04:38.407Z,1594393478.407 [Default:CheckIn:D] Running Loop=1
2020-07-10T15:04:38.804Z,1594393478.804 [Default:CheckIn:D] Stopped
2020-07-10T15:04:38.804Z,1594393478.804 [Default:CheckIn:E] Running Loop=1
2020-07-10T15:04:39.206Z,1594393479.206 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.965670 min 
2020-07-10T15:04:39.207Z,1594393479.207 [Default:CheckIn:E] Stopped
2020-07-10T15:04:39.207Z,1594393479.207 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T15:04:39.207Z,1594393479.207 [Default:CheckIn] Stopped
2020-07-10T15:04:39.207Z,1594393479.207 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T15:04:39.207Z,1594393479.207 [Default:CheckIn](INFO): Running loop #7
2020-07-10T15:04:39.207Z,1594393479.207 [Default:CheckIn] Running Loop=7
2020-07-10T15:04:39.207Z,1594393479.207 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T15:04:39.207Z,1594393479.207 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T15:05:05.056Z,1594393505.056 [NAL9602](INFO): Powering up NAL9602
2020-07-10T15:05:15.966Z,1594393515.966 [NAL9602](INFO): NAL9602 initialized
2020-07-10T15:09:32.525Z,1594393772.525 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2020-07-10T15:09:39.401Z,1594393779.401 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T15:04:39.2Z
2020-07-10T15:09:39.401Z,1594393779.401 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T15:09:39.401Z,1594393779.401 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T15:09:47.049Z,1594393787.049 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20200710T135833/Courier0022.lzma
2020-07-10T15:09:48.052Z,1594393788.052 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0022.lzma.bak
2020-07-10T15:09:48.052Z,1594393788.052 [DataOverHttps](INFO): SBD MOMSN=12432566
2020-07-10T15:10:03.566Z,1594393803.566 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200710T135833/Express0023.lzma
2020-07-10T15:10:04.568Z,1594393804.568 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0023.lzma.bak
2020-07-10T15:10:04.568Z,1594393804.568 [DataOverHttps](INFO): SBD MOMSN=12432568
2020-07-10T15:10:05.664Z,1594393805.664 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T15:10:05.665Z,1594393805.665 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T15:10:05.665Z,1594393805.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T15:14:04.069Z,1594394044.069 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:14:04.069Z,1594394044.069 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2020-07-10T15:14:14.137Z,1594394054.137 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-07-10T15:14:14.139Z,1594394054.139 [BPC1](INFO): Received data from all battery sticks.
2020-07-10T15:15:06.249Z,1594394106.249 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T15:15:06.249Z,1594394106.249 [Default:CheckIn:C.Wait] Stopped
2020-07-10T15:15:06.249Z,1594394106.249 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T15:15:06.249Z,1594394106.249 [Default:CheckIn:D] Running Loop=1
2020-07-10T15:15:06.670Z,1594394106.670 [Default:CheckIn:D] Stopped
2020-07-10T15:15:06.670Z,1594394106.670 [Default:CheckIn:E] Running Loop=1
2020-07-10T15:15:07.048Z,1594394107.048 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.430111 min 
2020-07-10T15:15:07.048Z,1594394107.048 [Default:CheckIn:E] Stopped
2020-07-10T15:15:07.049Z,1594394107.049 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T15:15:07.049Z,1594394107.049 [Default:CheckIn] Stopped
2020-07-10T15:15:07.049Z,1594394107.049 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T15:15:07.049Z,1594394107.049 [Default:CheckIn](INFO): Running loop #8
2020-07-10T15:15:07.049Z,1594394107.049 [Default:CheckIn] Running Loop=8
2020-07-10T15:15:07.049Z,1594394107.049 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T15:15:07.049Z,1594394107.049 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T15:15:17.144Z,1594394117.144 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T15:15:17.144Z,1594394117.144 [NAL9602] Data Fault, FailCount= 2
2020-07-10T15:15:17.144Z,1594394117.144 [NAL9602](ERROR): Data Fault
2020-07-10T15:15:17.161Z,1594394117.161 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T15:15:17.544Z,1594394117.544 [NAL9602](INFO): Powering down
2020-07-10T15:15:18.381Z,1594394118.381 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T15:15:18.381Z,1594394118.381 [NAL9602] No Fault, FailCount= 2
2020-07-10T15:15:47.844Z,1594394147.844 [NAL9602](INFO): Powering up NAL9602
2020-07-10T15:15:58.756Z,1594394158.756 [NAL9602](INFO): NAL9602 initialized
2020-07-10T15:17:04.623Z,1594394224.623 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-07-10T15:17:04.623Z,1594394224.623 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:17:04.633Z,1594394224.633 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:17:05.049Z,1594394225.049 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:17:05.049Z,1594394225.049 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-07-10T15:20:05.619Z,1594394405.619 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-07-10T15:20:05.619Z,1594394405.619 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:20:05.662Z,1594394405.662 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:20:06.079Z,1594394406.079 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:20:06.079Z,1594394406.079 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-07-10T15:20:07.276Z,1594394407.276 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T15:15:07.0Z
2020-07-10T15:20:07.276Z,1594394407.276 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T15:20:07.276Z,1594394407.276 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T15:20:17.434Z,1594394417.434 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200710T135833/Courier0025.lzma
2020-07-10T15:20:18.436Z,1594394418.436 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0025.lzma.bak
2020-07-10T15:20:18.436Z,1594394418.436 [DataOverHttps](INFO): SBD MOMSN=12432583
2020-07-10T15:20:33.746Z,1594394433.746 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20200710T135833/Express0026.lzma
2020-07-10T15:20:34.748Z,1594394434.748 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0026.lzma.bak
2020-07-10T15:20:34.748Z,1594394434.748 [DataOverHttps](INFO): SBD MOMSN=12432585
2020-07-10T15:20:36.007Z,1594394436.007 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T15:20:36.007Z,1594394436.007 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T15:20:36.007Z,1594394436.007 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T15:23:06.716Z,1594394586.716 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-07-10T15:23:06.716Z,1594394586.716 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:23:06.730Z,1594394586.730 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:23:07.123Z,1594394587.123 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:23:07.124Z,1594394587.124 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-07-10T15:25:36.604Z,1594394736.604 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T15:25:36.604Z,1594394736.604 [Default:CheckIn:C.Wait] Stopped
2020-07-10T15:25:36.604Z,1594394736.604 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T15:25:36.604Z,1594394736.604 [Default:CheckIn:D] Running Loop=1
2020-07-10T15:25:37.005Z,1594394737.005 [Default:CheckIn:D] Stopped
2020-07-10T15:25:37.005Z,1594394737.005 [Default:CheckIn:E] Running Loop=1
2020-07-10T15:25:37.390Z,1594394737.390 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.935693 min 
2020-07-10T15:25:37.390Z,1594394737.390 [Default:CheckIn:E] Stopped
2020-07-10T15:25:37.390Z,1594394737.390 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T15:25:37.390Z,1594394737.390 [Default:CheckIn] Stopped
2020-07-10T15:25:37.390Z,1594394737.390 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T15:25:37.391Z,1594394737.391 [Default:CheckIn](INFO): Running loop #9
2020-07-10T15:25:37.391Z,1594394737.391 [Default:CheckIn] Running Loop=9
2020-07-10T15:25:37.391Z,1594394737.391 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T15:25:37.391Z,1594394737.391 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T15:26:00.404Z,1594394760.404 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T15:26:00.404Z,1594394760.404 [NAL9602] Data Fault, FailCount= 3
2020-07-10T15:26:00.404Z,1594394760.404 [NAL9602](ERROR): Data Fault
2020-07-10T15:26:00.442Z,1594394760.442 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T15:26:00.808Z,1594394760.808 [NAL9602](INFO): Powering down
2020-07-10T15:26:01.650Z,1594394761.650 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T15:26:01.650Z,1594394761.650 [NAL9602] No Fault, FailCount= 3
2020-07-10T15:26:07.687Z,1594394767.687 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-07-10T15:26:07.687Z,1594394767.687 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:26:07.697Z,1594394767.697 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:26:08.105Z,1594394768.105 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:26:08.105Z,1594394768.105 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-07-10T15:26:31.104Z,1594394791.104 [NAL9602](INFO): Powering up NAL9602
2020-07-10T15:26:42.016Z,1594394802.016 [NAL9602](INFO): NAL9602 initialized
2020-07-10T15:29:08.706Z,1594394948.706 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-07-10T15:29:08.706Z,1594394948.706 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:29:08.716Z,1594394948.716 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:29:09.104Z,1594394949.104 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:29:09.104Z,1594394949.104 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-07-10T15:30:37.599Z,1594395037.599 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T15:25:37.4Z
2020-07-10T15:30:37.600Z,1594395037.600 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T15:30:37.600Z,1594395037.600 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T15:30:44.306Z,1594395044.306 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0028.lzma
2020-07-10T15:30:45.308Z,1594395045.308 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0028.lzma.bak
2020-07-10T15:30:45.308Z,1594395045.308 [DataOverHttps](INFO): SBD MOMSN=12432591
2020-07-10T15:31:00.670Z,1594395060.670 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20200710T135833/Express0029.lzma
2020-07-10T15:31:01.672Z,1594395061.672 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0029.lzma.bak
2020-07-10T15:31:01.672Z,1594395061.672 [DataOverHttps](INFO): SBD MOMSN=12432593
2020-07-10T15:31:03.025Z,1594395063.025 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T15:31:03.025Z,1594395063.025 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T15:31:03.025Z,1594395063.025 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T15:32:09.694Z,1594395129.694 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-07-10T15:32:09.694Z,1594395129.694 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:32:09.705Z,1594395129.705 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:32:10.116Z,1594395130.116 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:32:10.116Z,1594395130.116 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-07-10T15:35:10.702Z,1594395310.702 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-07-10T15:35:10.702Z,1594395310.702 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:35:10.712Z,1594395310.712 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:35:11.168Z,1594395311.168 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:35:11.168Z,1594395311.168 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-07-10T15:36:03.608Z,1594395363.608 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T15:36:03.609Z,1594395363.609 [Default:CheckIn:C.Wait] Stopped
2020-07-10T15:36:03.609Z,1594395363.609 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T15:36:03.609Z,1594395363.609 [Default:CheckIn:D] Running Loop=1
2020-07-10T15:36:04.017Z,1594395364.017 [Default:CheckIn:D] Stopped
2020-07-10T15:36:04.017Z,1594395364.017 [Default:CheckIn:E] Running Loop=1
2020-07-10T15:36:04.441Z,1594395364.441 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.385889 min 
2020-07-10T15:36:04.441Z,1594395364.441 [Default:CheckIn:E] Stopped
2020-07-10T15:36:04.441Z,1594395364.441 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T15:36:04.441Z,1594395364.441 [Default:CheckIn] Stopped
2020-07-10T15:36:04.441Z,1594395364.441 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T15:36:04.441Z,1594395364.441 [Default:CheckIn](INFO): Running loop #10
2020-07-10T15:36:04.441Z,1594395364.441 [Default:CheckIn] Running Loop=10
2020-07-10T15:36:04.442Z,1594395364.442 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T15:36:04.442Z,1594395364.442 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T15:36:43.592Z,1594395403.592 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T15:36:43.592Z,1594395403.592 [NAL9602] Data Fault, FailCount= 4
2020-07-10T15:36:43.592Z,1594395403.592 [NAL9602](ERROR): Data Fault
2020-07-10T15:36:43.609Z,1594395403.609 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T15:36:43.996Z,1594395403.996 [NAL9602](INFO): Powering down
2020-07-10T15:36:44.828Z,1594395404.828 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T15:36:44.828Z,1594395404.828 [NAL9602] No Fault, FailCount= 4
2020-07-10T15:37:14.300Z,1594395434.300 [NAL9602](INFO): Powering up NAL9602
2020-07-10T15:37:25.208Z,1594395445.208 [NAL9602](INFO): NAL9602 initialized
2020-07-10T15:38:11.682Z,1594395491.682 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-07-10T15:38:11.682Z,1594395491.682 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:38:11.692Z,1594395491.692 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:38:12.101Z,1594395492.101 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:38:12.101Z,1594395492.101 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-07-10T15:41:04.600Z,1594395664.600 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T15:36:04.4Z
2020-07-10T15:41:04.600Z,1594395664.600 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T15:41:04.600Z,1594395664.600 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T15:41:12.688Z,1594395672.688 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-07-10T15:41:12.688Z,1594395672.688 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:41:12.710Z,1594395672.710 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:41:13.123Z,1594395673.123 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:41:13.123Z,1594395673.123 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-07-10T15:41:16.317Z,1594395676.317 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0031.lzma
2020-07-10T15:41:17.320Z,1594395677.320 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0031.lzma.bak
2020-07-10T15:41:17.320Z,1594395677.320 [DataOverHttps](INFO): SBD MOMSN=12432598
2020-07-10T15:41:46.703Z,1594395706.703 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20200710T135833/Express0032.lzma
2020-07-10T15:41:47.704Z,1594395707.704 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0032.lzma.bak
2020-07-10T15:41:47.704Z,1594395707.704 [DataOverHttps](INFO): SBD MOMSN=12432600
2020-07-10T15:41:49.031Z,1594395709.031 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T15:41:49.031Z,1594395709.031 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T15:41:49.031Z,1594395709.031 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T15:44:13.691Z,1594395853.691 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-07-10T15:44:13.691Z,1594395853.691 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:44:13.701Z,1594395853.701 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:44:14.089Z,1594395854.089 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:44:14.090Z,1594395854.090 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-07-10T15:46:49.601Z,1594396009.601 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T15:46:49.601Z,1594396009.601 [Default:CheckIn:C.Wait] Stopped
2020-07-10T15:46:49.601Z,1594396009.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T15:46:49.601Z,1594396009.601 [Default:CheckIn:D] Running Loop=1
2020-07-10T15:46:50.013Z,1594396010.013 [Default:CheckIn:D] Stopped
2020-07-10T15:46:50.013Z,1594396010.013 [Default:CheckIn:E] Running Loop=1
2020-07-10T15:46:50.417Z,1594396010.417 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.152490 min 
2020-07-10T15:46:50.417Z,1594396010.417 [Default:CheckIn:E] Stopped
2020-07-10T15:46:50.417Z,1594396010.417 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T15:46:50.417Z,1594396010.417 [Default:CheckIn] Stopped
2020-07-10T15:46:50.417Z,1594396010.417 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T15:46:50.417Z,1594396010.417 [Default:CheckIn](INFO): Running loop #11
2020-07-10T15:46:50.418Z,1594396010.418 [Default:CheckIn] Running Loop=11
2020-07-10T15:46:50.418Z,1594396010.418 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T15:46:50.418Z,1594396010.418 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T15:47:14.656Z,1594396034.656 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-07-10T15:47:14.656Z,1594396034.656 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:47:14.683Z,1594396034.683 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:47:15.069Z,1594396035.069 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:47:15.069Z,1594396035.069 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-07-10T15:47:28.376Z,1594396048.376 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T15:47:28.377Z,1594396048.377 [NAL9602] Data Fault, FailCount= 5
2020-07-10T15:47:28.377Z,1594396048.377 [NAL9602](ERROR): Data Fault
2020-07-10T15:47:28.394Z,1594396048.394 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T15:47:28.394Z,1594396048.394 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-07-10T15:47:28.780Z,1594396048.780 [NAL9602](INFO): Powering down
2020-07-10T15:47:28.826Z,1594396048.826 [CBIT](INFO): Critical error at 20200710T154728
2020-07-10T15:49:28.402Z,1594396168.402 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T15:49:28.402Z,1594396168.402 [NAL9602] No Fault, FailCount= 5
2020-07-10T15:49:28.768Z,1594396168.768 [NAL9602](INFO): Powering up NAL9602
2020-07-10T15:49:39.686Z,1594396179.686 [NAL9602](INFO): NAL9602 initialized
2020-07-10T15:50:15.664Z,1594396215.664 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-07-10T15:50:15.664Z,1594396215.664 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:50:15.674Z,1594396215.674 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:50:16.077Z,1594396216.077 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:50:16.077Z,1594396216.077 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-07-10T15:51:50.600Z,1594396310.600 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T15:46:50.4Z
2020-07-10T15:51:50.601Z,1594396310.601 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T15:51:50.601Z,1594396310.601 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T15:52:17.242Z,1594396337.242 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20200710T135833/Courier0034.lzma
2020-07-10T15:52:26.422Z,1594396346.422 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20200710T135833/Courier0034.lzma
2020-07-10T15:52:27.424Z,1594396347.424 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0034.lzma.bak
2020-07-10T15:52:27.424Z,1594396347.424 [DataOverHttps](INFO): SBD MOMSN=12432608
2020-07-10T15:52:42.778Z,1594396362.778 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20200710T135833/Express0035.lzma
2020-07-10T15:52:43.780Z,1594396363.780 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0035.lzma.bak
2020-07-10T15:52:43.780Z,1594396363.780 [DataOverHttps](INFO): SBD MOMSN=12432614
2020-07-10T15:52:45.145Z,1594396365.145 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T15:52:45.145Z,1594396365.145 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T15:52:45.145Z,1594396365.145 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T15:53:16.680Z,1594396396.680 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-07-10T15:53:16.680Z,1594396396.680 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:53:16.690Z,1594396396.690 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:53:17.106Z,1594396397.106 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:53:17.106Z,1594396397.106 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-07-10T15:56:17.661Z,1594396577.661 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-07-10T15:56:17.661Z,1594396577.661 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:56:17.671Z,1594396577.671 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:56:18.052Z,1594396578.052 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:56:18.052Z,1594396578.052 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-07-10T15:57:45.721Z,1594396665.721 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T15:57:45.721Z,1594396665.721 [Default:CheckIn:C.Wait] Stopped
2020-07-10T15:57:45.721Z,1594396665.721 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T15:57:45.721Z,1594396665.721 [Default:CheckIn:D] Running Loop=1
2020-07-10T15:57:46.120Z,1594396666.120 [Default:CheckIn:D] Stopped
2020-07-10T15:57:46.120Z,1594396666.120 [Default:CheckIn:E] Running Loop=1
2020-07-10T15:57:46.525Z,1594396666.525 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.087614 min 
2020-07-10T15:57:46.525Z,1594396666.525 [Default:CheckIn:E] Stopped
2020-07-10T15:57:46.525Z,1594396666.525 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T15:57:46.525Z,1594396666.525 [Default:CheckIn] Stopped
2020-07-10T15:57:46.525Z,1594396666.525 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T15:57:46.525Z,1594396666.525 [Default:CheckIn](INFO): Running loop #12
2020-07-10T15:57:46.525Z,1594396666.525 [Default:CheckIn] Running Loop=12
2020-07-10T15:57:46.526Z,1594396666.526 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T15:57:46.526Z,1594396666.526 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T15:59:18.653Z,1594396758.653 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-07-10T15:59:18.653Z,1594396758.653 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T15:59:18.663Z,1594396758.663 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T15:59:19.073Z,1594396759.073 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T15:59:19.073Z,1594396759.073 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-07-10T15:59:42.056Z,1594396782.056 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T15:59:42.056Z,1594396782.056 [NAL9602] Data Fault, FailCount= 1
2020-07-10T15:59:42.056Z,1594396782.056 [NAL9602](ERROR): Data Fault
2020-07-10T15:59:42.081Z,1594396782.081 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T15:59:42.463Z,1594396782.463 [NAL9602](INFO): Powering down
2020-07-10T15:59:43.296Z,1594396783.296 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T15:59:43.296Z,1594396783.296 [NAL9602] No Fault, FailCount= 1
2020-07-10T16:00:12.764Z,1594396812.764 [NAL9602](INFO): Powering up NAL9602
2020-07-10T16:00:23.672Z,1594396823.672 [NAL9602](INFO): NAL9602 initialized
2020-07-10T16:02:19.659Z,1594396939.659 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-07-10T16:02:19.659Z,1594396939.659 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:02:19.669Z,1594396939.669 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:02:20.077Z,1594396940.077 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:02:20.078Z,1594396940.078 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-07-10T16:02:46.700Z,1594396966.700 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T15:57:46.5Z
2020-07-10T16:02:46.700Z,1594396966.700 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T16:02:46.700Z,1594396966.700 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T16:02:54.086Z,1594396974.086 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0037.lzma
2020-07-10T16:02:55.088Z,1594396975.088 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0037.lzma.bak
2020-07-10T16:02:55.088Z,1594396975.088 [DataOverHttps](INFO): SBD MOMSN=12432658
2020-07-10T16:03:19.402Z,1594396999.402 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20200710T135833/Express0038.lzma
2020-07-10T16:03:20.404Z,1594397000.404 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0038.lzma.bak
2020-07-10T16:03:20.404Z,1594397000.404 [DataOverHttps](INFO): SBD MOMSN=12432660
2020-07-10T16:03:21.489Z,1594397001.489 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T16:03:21.489Z,1594397001.489 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T16:03:21.489Z,1594397001.489 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T16:05:20.653Z,1594397120.653 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-07-10T16:05:20.654Z,1594397120.654 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:05:20.663Z,1594397120.663 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:05:21.077Z,1594397121.077 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:05:21.077Z,1594397121.077 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-07-10T16:08:21.648Z,1594397301.648 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-07-10T16:08:21.648Z,1594397301.648 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:08:21.658Z,1594397301.658 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:08:22.052Z,1594397302.052 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T16:08:22.052Z,1594397302.052 [Default:CheckIn:C.Wait] Stopped
2020-07-10T16:08:22.052Z,1594397302.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T16:08:22.052Z,1594397302.052 [Default:CheckIn:D] Running Loop=1
2020-07-10T16:08:22.061Z,1594397302.061 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:08:22.061Z,1594397302.061 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-07-10T16:08:22.468Z,1594397302.468 [Default:CheckIn:D] Stopped
2020-07-10T16:08:22.468Z,1594397302.468 [Default:CheckIn:E] Running Loop=1
2020-07-10T16:08:22.881Z,1594397302.881 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.693408 min 
2020-07-10T16:08:22.881Z,1594397302.881 [Default:CheckIn:E] Stopped
2020-07-10T16:08:22.881Z,1594397302.881 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T16:08:22.881Z,1594397302.881 [Default:CheckIn] Stopped
2020-07-10T16:08:22.881Z,1594397302.881 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T16:08:22.881Z,1594397302.881 [Default:CheckIn](INFO): Running loop #13
2020-07-10T16:08:22.882Z,1594397302.882 [Default:CheckIn] Running Loop=13
2020-07-10T16:08:22.882Z,1594397302.882 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T16:08:22.882Z,1594397302.882 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T16:10:25.664Z,1594397425.664 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T16:10:25.664Z,1594397425.664 [NAL9602] Data Fault, FailCount= 2
2020-07-10T16:10:25.664Z,1594397425.664 [NAL9602](ERROR): Data Fault
2020-07-10T16:10:25.712Z,1594397425.712 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T16:10:26.072Z,1594397426.072 [NAL9602](INFO): Powering down
2020-07-10T16:10:26.900Z,1594397426.900 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T16:10:26.900Z,1594397426.900 [NAL9602] No Fault, FailCount= 2
2020-07-10T16:10:56.368Z,1594397456.368 [NAL9602](INFO): Powering up NAL9602
2020-07-10T16:11:07.284Z,1594397467.284 [NAL9602](INFO): NAL9602 initialized
2020-07-10T16:11:22.659Z,1594397482.659 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-07-10T16:11:22.660Z,1594397482.660 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:11:22.669Z,1594397482.669 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:11:23.081Z,1594397483.081 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:11:23.081Z,1594397483.081 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-07-10T16:13:23.072Z,1594397603.072 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T16:08:22.9Z
2020-07-10T16:13:23.073Z,1594397603.073 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T16:13:23.073Z,1594397603.073 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T16:13:29.610Z,1594397609.610 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0040.lzma
2020-07-10T16:13:30.612Z,1594397610.612 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0040.lzma.bak
2020-07-10T16:13:30.612Z,1594397610.612 [DataOverHttps](INFO): SBD MOMSN=12432664
2020-07-10T16:13:45.894Z,1594397625.894 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20200710T135833/Express0041.lzma
2020-07-10T16:13:46.896Z,1594397626.896 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0041.lzma.bak
2020-07-10T16:13:46.896Z,1594397626.896 [DataOverHttps](INFO): SBD MOMSN=12432666
2020-07-10T16:13:48.105Z,1594397628.105 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T16:13:48.105Z,1594397628.105 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T16:13:48.106Z,1594397628.106 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T16:14:23.632Z,1594397663.632 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2020-07-10T16:14:23.632Z,1594397663.632 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:14:23.674Z,1594397663.674 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:14:23.675Z,1594397663.675 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:18:48.664Z,1594397928.664 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T16:18:48.664Z,1594397928.664 [Default:CheckIn:C.Wait] Stopped
2020-07-10T16:18:48.664Z,1594397928.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T16:18:48.664Z,1594397928.664 [Default:CheckIn:D] Running Loop=1
2020-07-10T16:18:49.058Z,1594397929.058 [Default:CheckIn:D] Stopped
2020-07-10T16:18:49.059Z,1594397929.059 [Default:CheckIn:E] Running Loop=1
2020-07-10T16:18:49.484Z,1594397929.484 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.136589 min 
2020-07-10T16:18:49.484Z,1594397929.484 [Default:CheckIn:E] Stopped
2020-07-10T16:18:49.484Z,1594397929.484 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T16:18:49.484Z,1594397929.484 [Default:CheckIn] Stopped
2020-07-10T16:18:49.485Z,1594397929.485 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T16:18:49.485Z,1594397929.485 [Default:CheckIn](INFO): Running loop #14
2020-07-10T16:18:49.485Z,1594397929.485 [Default:CheckIn] Running Loop=14
2020-07-10T16:18:49.485Z,1594397929.485 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T16:18:49.485Z,1594397929.485 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T16:21:10.444Z,1594398070.444 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T16:21:10.444Z,1594398070.444 [NAL9602] Data Fault, FailCount= 3
2020-07-10T16:21:10.444Z,1594398070.444 [NAL9602](ERROR): Data Fault
2020-07-10T16:21:10.504Z,1594398070.504 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T16:21:10.856Z,1594398070.856 [NAL9602](INFO): Powering down
2020-07-10T16:21:11.687Z,1594398071.687 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T16:21:11.687Z,1594398071.687 [NAL9602] No Fault, FailCount= 3
2020-07-10T16:21:39.143Z,1594398099.143 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-07-10T16:21:39.147Z,1594398099.147 [BPC1](INFO): Received data from all battery sticks.
2020-07-10T16:21:41.160Z,1594398101.160 [NAL9602](INFO): Powering up NAL9602
2020-07-10T16:21:52.068Z,1594398112.068 [NAL9602](INFO): NAL9602 initialized
2020-07-10T16:23:49.649Z,1594398229.649 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T16:18:49.5Z
2020-07-10T16:23:49.649Z,1594398229.649 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T16:23:49.649Z,1594398229.649 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T16:24:05.914Z,1594398245.914 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200710T135833/Courier0043.lzma
2020-07-10T16:24:06.916Z,1594398246.916 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0043.lzma.bak
2020-07-10T16:24:06.916Z,1594398246.916 [DataOverHttps](INFO): SBD MOMSN=12432670
2020-07-10T16:24:25.190Z,1594398265.190 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20200710T135833/Express0044.lzma
2020-07-10T16:24:26.188Z,1594398266.188 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0044.lzma.bak
2020-07-10T16:24:26.188Z,1594398266.188 [DataOverHttps](INFO): SBD MOMSN=12432672
2020-07-10T16:24:27.233Z,1594398267.233 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T16:24:27.233Z,1594398267.233 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T16:24:27.233Z,1594398267.233 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T16:29:23.772Z,1594398563.772 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:29:23.772Z,1594398563.772 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2020-07-10T16:29:27.787Z,1594398567.787 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T16:29:27.787Z,1594398567.787 [Default:CheckIn:C.Wait] Stopped
2020-07-10T16:29:27.787Z,1594398567.787 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T16:29:27.787Z,1594398567.787 [Default:CheckIn:D] Running Loop=1
2020-07-10T16:29:28.180Z,1594398568.180 [Default:CheckIn:D] Stopped
2020-07-10T16:29:28.180Z,1594398568.180 [Default:CheckIn:E] Running Loop=1
2020-07-10T16:29:28.593Z,1594398568.593 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.788607 min 
2020-07-10T16:29:28.593Z,1594398568.593 [Default:CheckIn:E] Stopped
2020-07-10T16:29:28.593Z,1594398568.593 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T16:29:28.593Z,1594398568.593 [Default:CheckIn] Stopped
2020-07-10T16:29:28.593Z,1594398568.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T16:29:28.593Z,1594398568.593 [Default:CheckIn](INFO): Running loop #15
2020-07-10T16:29:28.593Z,1594398568.593 [Default:CheckIn] Running Loop=15
2020-07-10T16:29:28.593Z,1594398568.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T16:29:28.594Z,1594398568.594 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T16:31:55.236Z,1594398715.236 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T16:31:55.236Z,1594398715.236 [NAL9602] Data Fault, FailCount= 4
2020-07-10T16:31:55.236Z,1594398715.236 [NAL9602](ERROR): Data Fault
2020-07-10T16:31:55.277Z,1594398715.277 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T16:31:55.640Z,1594398715.640 [NAL9602](INFO): Powering down
2020-07-10T16:31:56.477Z,1594398716.477 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T16:31:56.477Z,1594398716.477 [NAL9602] No Fault, FailCount= 4
2020-07-10T16:32:24.338Z,1594398744.338 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-07-10T16:32:24.338Z,1594398744.338 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:32:24.348Z,1594398744.348 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:32:24.739Z,1594398744.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:32:24.739Z,1594398744.739 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-07-10T16:32:25.940Z,1594398745.940 [NAL9602](INFO): Powering up NAL9602
2020-07-10T16:32:36.844Z,1594398756.844 [NAL9602](INFO): NAL9602 initialized
2020-07-10T16:34:28.756Z,1594398868.756 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T16:29:28.6Z
2020-07-10T16:34:28.756Z,1594398868.756 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T16:34:28.757Z,1594398868.757 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T16:34:35.937Z,1594398875.937 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0046.lzma
2020-07-10T16:34:36.940Z,1594398876.940 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0046.lzma.bak
2020-07-10T16:34:36.940Z,1594398876.940 [DataOverHttps](INFO): SBD MOMSN=12432679
2020-07-10T16:34:52.286Z,1594398892.286 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20200710T135833/Express0047.lzma
2020-07-10T16:34:53.288Z,1594398893.288 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0047.lzma.bak
2020-07-10T16:34:53.288Z,1594398893.288 [DataOverHttps](INFO): SBD MOMSN=12432681
2020-07-10T16:34:54.621Z,1594398894.621 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T16:34:54.621Z,1594398894.621 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T16:34:54.621Z,1594398894.621 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T16:35:25.336Z,1594398925.336 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-07-10T16:35:25.336Z,1594398925.336 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:35:25.370Z,1594398925.370 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:35:25.761Z,1594398925.761 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:35:25.761Z,1594398925.761 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-07-10T16:38:26.339Z,1594399106.339 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-07-10T16:38:26.339Z,1594399106.339 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:38:26.349Z,1594399106.349 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:38:26.723Z,1594399106.723 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:38:26.723Z,1594399106.723 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-07-10T16:39:55.191Z,1594399195.191 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T16:39:55.191Z,1594399195.191 [Default:CheckIn:C.Wait] Stopped
2020-07-10T16:39:55.191Z,1594399195.191 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T16:39:55.191Z,1594399195.191 [Default:CheckIn:D] Running Loop=1
2020-07-10T16:39:55.614Z,1594399195.614 [Default:CheckIn:D] Stopped
2020-07-10T16:39:55.614Z,1594399195.614 [Default:CheckIn:E] Running Loop=1
2020-07-10T16:39:56.025Z,1594399196.025 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 158.245850 min 
2020-07-10T16:39:56.025Z,1594399196.025 [Default:CheckIn:E] Stopped
2020-07-10T16:39:56.025Z,1594399196.025 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T16:39:56.025Z,1594399196.025 [Default:CheckIn] Stopped
2020-07-10T16:39:56.025Z,1594399196.025 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T16:39:56.026Z,1594399196.026 [Default:CheckIn](INFO): Running loop #16
2020-07-10T16:39:56.026Z,1594399196.026 [Default:CheckIn] Running Loop=16
2020-07-10T16:39:56.026Z,1594399196.026 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T16:39:56.026Z,1594399196.026 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T16:41:27.307Z,1594399287.307 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-07-10T16:41:27.307Z,1594399287.307 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:41:27.317Z,1594399287.317 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:41:27.725Z,1594399287.725 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:41:27.725Z,1594399287.725 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-07-10T16:42:39.216Z,1594399359.216 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T16:42:39.216Z,1594399359.216 [NAL9602] Data Fault, FailCount= 5
2020-07-10T16:42:39.216Z,1594399359.216 [NAL9602](ERROR): Data Fault
2020-07-10T16:42:39.232Z,1594399359.232 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T16:42:39.233Z,1594399359.233 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-07-10T16:42:39.620Z,1594399359.620 [NAL9602](INFO): Powering down
2020-07-10T16:42:39.635Z,1594399359.635 [CBIT](INFO): Critical error at 20200710T164239
2020-07-10T16:44:28.306Z,1594399468.306 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-07-10T16:44:28.306Z,1594399468.306 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:44:28.316Z,1594399468.316 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:44:28.724Z,1594399468.724 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:44:28.724Z,1594399468.724 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-07-10T16:44:39.219Z,1594399479.219 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T16:44:39.219Z,1594399479.219 [NAL9602] No Fault, FailCount= 5
2020-07-10T16:44:39.635Z,1594399479.635 [NAL9602](INFO): Powering up NAL9602
2020-07-10T16:44:50.512Z,1594399490.512 [NAL9602](INFO): NAL9602 initialized
2020-07-10T16:44:56.189Z,1594399496.189 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T16:39:56.0Z
2020-07-10T16:44:56.189Z,1594399496.189 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T16:44:56.189Z,1594399496.189 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T16:45:03.498Z,1594399503.498 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20200710T135833/Courier0049.lzma
2020-07-10T16:45:04.500Z,1594399504.500 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0049.lzma.bak
2020-07-10T16:45:04.500Z,1594399504.500 [DataOverHttps](INFO): SBD MOMSN=12432687
2020-07-10T16:45:19.930Z,1594399519.930 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20200710T135833/Express0050.lzma
2020-07-10T16:45:20.932Z,1594399520.932 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0050.lzma.bak
2020-07-10T16:45:20.932Z,1594399520.932 [DataOverHttps](INFO): SBD MOMSN=12432690
2020-07-10T16:45:22.060Z,1594399522.060 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T16:45:22.060Z,1594399522.060 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T16:45:22.060Z,1594399522.060 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T16:47:29.304Z,1594399649.304 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-07-10T16:47:29.304Z,1594399649.304 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:47:29.322Z,1594399649.322 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:47:29.728Z,1594399649.728 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:47:29.728Z,1594399649.728 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-07-10T16:50:22.644Z,1594399822.644 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T16:50:22.644Z,1594399822.644 [Default:CheckIn:C.Wait] Stopped
2020-07-10T16:50:22.644Z,1594399822.644 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T16:50:22.644Z,1594399822.644 [Default:CheckIn:D] Running Loop=1
2020-07-10T16:50:23.033Z,1594399823.033 [Default:CheckIn:D] Stopped
2020-07-10T16:50:23.033Z,1594399823.033 [Default:CheckIn:E] Running Loop=1
2020-07-10T16:50:23.419Z,1594399823.419 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 168.702832 min 
2020-07-10T16:50:23.419Z,1594399823.419 [Default:CheckIn:E] Stopped
2020-07-10T16:50:23.419Z,1594399823.419 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T16:50:23.419Z,1594399823.419 [Default:CheckIn] Stopped
2020-07-10T16:50:23.419Z,1594399823.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T16:50:23.419Z,1594399823.419 [Default:CheckIn](INFO): Running loop #17
2020-07-10T16:50:23.420Z,1594399823.420 [Default:CheckIn] Running Loop=17
2020-07-10T16:50:23.420Z,1594399823.420 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T16:50:23.420Z,1594399823.420 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T16:50:30.283Z,1594399830.283 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-07-10T16:50:30.283Z,1594399830.283 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:50:30.309Z,1594399830.309 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:50:30.700Z,1594399830.700 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:50:30.700Z,1594399830.700 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-07-10T16:53:31.318Z,1594400011.318 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-07-10T16:53:31.318Z,1594400011.318 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:53:31.340Z,1594400011.340 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:53:31.706Z,1594400011.706 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:53:31.706Z,1594400011.706 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-07-10T16:54:51.668Z,1594400091.668 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T16:54:51.668Z,1594400091.668 [NAL9602] Data Fault, FailCount= 1
2020-07-10T16:54:51.668Z,1594400091.668 [NAL9602](ERROR): Data Fault
2020-07-10T16:54:51.685Z,1594400091.685 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T16:54:52.068Z,1594400092.068 [NAL9602](INFO): Powering down
2020-07-10T16:54:52.910Z,1594400092.910 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T16:54:52.910Z,1594400092.910 [NAL9602] No Fault, FailCount= 1
2020-07-10T16:55:22.368Z,1594400122.368 [NAL9602](INFO): Powering up NAL9602
2020-07-10T16:55:23.597Z,1594400123.597 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T16:50:23.4Z
2020-07-10T16:55:23.597Z,1594400123.597 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T16:55:23.597Z,1594400123.597 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T16:55:30.426Z,1594400130.426 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0052.lzma
2020-07-10T16:55:31.428Z,1594400131.428 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0052.lzma.bak
2020-07-10T16:55:31.428Z,1594400131.428 [DataOverHttps](INFO): SBD MOMSN=12432696
2020-07-10T16:55:33.282Z,1594400133.282 [NAL9602](INFO): NAL9602 initialized
2020-07-10T16:55:46.758Z,1594400146.758 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20200710T135833/Express0053.lzma
2020-07-10T16:55:47.760Z,1594400147.760 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0053.lzma.bak
2020-07-10T16:55:47.760Z,1594400147.760 [DataOverHttps](INFO): SBD MOMSN=12432698
2020-07-10T16:55:49.070Z,1594400149.070 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T16:55:49.070Z,1594400149.070 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T16:55:49.070Z,1594400149.070 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T16:56:32.271Z,1594400192.271 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-07-10T16:56:32.271Z,1594400192.271 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:56:32.282Z,1594400192.282 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:56:32.688Z,1594400192.688 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:56:32.688Z,1594400192.688 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-07-10T16:59:33.264Z,1594400373.264 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-07-10T16:59:33.264Z,1594400373.264 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T16:59:33.324Z,1594400373.324 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T16:59:33.679Z,1594400373.679 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T16:59:33.679Z,1594400373.679 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-07-10T17:00:49.634Z,1594400449.634 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T17:00:49.634Z,1594400449.634 [Default:CheckIn:C.Wait] Stopped
2020-07-10T17:00:49.634Z,1594400449.634 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T17:00:49.634Z,1594400449.634 [Default:CheckIn:D] Running Loop=1
2020-07-10T17:00:50.024Z,1594400450.024 [Default:CheckIn:D] Stopped
2020-07-10T17:00:50.024Z,1594400450.024 [Default:CheckIn:E] Running Loop=1
2020-07-10T17:00:50.424Z,1594400450.424 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 179.152669 min 
2020-07-10T17:00:50.425Z,1594400450.425 [Default:CheckIn:E] Stopped
2020-07-10T17:00:50.425Z,1594400450.425 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T17:00:50.425Z,1594400450.425 [Default:CheckIn] Stopped
2020-07-10T17:00:50.425Z,1594400450.425 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T17:00:50.425Z,1594400450.425 [Default:CheckIn](INFO): Running loop #18
2020-07-10T17:00:50.425Z,1594400450.425 [Default:CheckIn] Running Loop=18
2020-07-10T17:00:50.425Z,1594400450.425 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T17:00:50.425Z,1594400450.425 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T17:02:34.259Z,1594400554.259 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-07-10T17:02:34.259Z,1594400554.259 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T17:02:34.269Z,1594400554.269 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T17:02:34.673Z,1594400554.673 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T17:02:34.673Z,1594400554.673 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-07-10T17:05:35.240Z,1594400735.240 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T17:05:35.240Z,1594400735.240 [NAL9602] Data Fault, FailCount= 2
2020-07-10T17:05:35.240Z,1594400735.240 [NAL9602](ERROR): Data Fault
2020-07-10T17:05:35.247Z,1594400735.247 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-07-10T17:05:35.247Z,1594400735.247 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T17:05:35.258Z,1594400735.258 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T17:05:35.258Z,1594400735.258 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T17:05:35.640Z,1594400735.640 [NAL9602](INFO): Powering down
2020-07-10T17:05:35.660Z,1594400735.660 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T17:05:35.660Z,1594400735.660 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-07-10T17:05:36.472Z,1594400736.472 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T17:05:36.472Z,1594400736.472 [NAL9602] No Fault, FailCount= 2
2020-07-10T17:05:50.604Z,1594400750.604 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T17:00:50.4Z
2020-07-10T17:05:50.604Z,1594400750.604 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T17:05:50.604Z,1594400750.604 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T17:05:59.173Z,1594400759.173 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0055.lzma
2020-07-10T17:06:00.176Z,1594400760.176 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0055.lzma.bak
2020-07-10T17:06:00.176Z,1594400760.176 [DataOverHttps](INFO): SBD MOMSN=12432918
2020-07-10T17:06:05.940Z,1594400765.940 [NAL9602](INFO): Powering up NAL9602
2020-07-10T17:06:16.852Z,1594400776.852 [NAL9602](INFO): NAL9602 initialized
2020-07-10T17:06:17.490Z,1594400777.490 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20200710T135833/Express0056.lzma
2020-07-10T17:06:18.492Z,1594400778.492 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0056.lzma.bak
2020-07-10T17:06:18.492Z,1594400778.492 [DataOverHttps](INFO): SBD MOMSN=12432920
2020-07-10T17:06:19.688Z,1594400779.688 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T17:06:19.688Z,1594400779.688 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T17:06:19.688Z,1594400779.688 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T17:08:36.245Z,1594400916.245 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-07-10T17:08:36.245Z,1594400916.245 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T17:08:36.255Z,1594400916.255 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T17:08:36.669Z,1594400916.669 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T17:08:36.669Z,1594400916.669 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-07-10T17:11:20.289Z,1594401080.289 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T17:11:20.289Z,1594401080.289 [Default:CheckIn:C.Wait] Stopped
2020-07-10T17:11:20.289Z,1594401080.289 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T17:11:20.289Z,1594401080.289 [Default:CheckIn:D] Running Loop=1
2020-07-10T17:11:20.689Z,1594401080.689 [Default:CheckIn:D] Stopped
2020-07-10T17:11:20.689Z,1594401080.689 [Default:CheckIn:E] Running Loop=1
2020-07-10T17:11:21.097Z,1594401081.097 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 189.663753 min 
2020-07-10T17:11:21.097Z,1594401081.097 [Default:CheckIn:E] Stopped
2020-07-10T17:11:21.097Z,1594401081.097 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T17:11:21.097Z,1594401081.097 [Default:CheckIn] Stopped
2020-07-10T17:11:21.097Z,1594401081.097 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T17:11:21.097Z,1594401081.097 [Default:CheckIn](INFO): Running loop #19
2020-07-10T17:11:21.097Z,1594401081.097 [Default:CheckIn] Running Loop=19
2020-07-10T17:11:21.097Z,1594401081.097 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T17:11:21.098Z,1594401081.098 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T17:11:37.230Z,1594401097.230 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-07-10T17:11:37.230Z,1594401097.230 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T17:11:37.273Z,1594401097.273 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T17:11:37.673Z,1594401097.673 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T17:11:37.673Z,1594401097.673 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-07-10T17:14:38.232Z,1594401278.232 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-07-10T17:14:38.232Z,1594401278.232 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T17:14:38.242Z,1594401278.242 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T17:14:38.655Z,1594401278.655 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T17:14:38.655Z,1594401278.655 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-07-10T17:16:19.620Z,1594401379.620 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T17:16:19.620Z,1594401379.620 [NAL9602] Data Fault, FailCount= 3
2020-07-10T17:16:19.620Z,1594401379.620 [NAL9602](ERROR): Data Fault
2020-07-10T17:16:19.669Z,1594401379.669 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T17:16:20.024Z,1594401380.024 [NAL9602](INFO): Powering down
2020-07-10T17:16:20.847Z,1594401380.847 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T17:16:20.847Z,1594401380.847 [NAL9602] No Fault, FailCount= 3
2020-07-10T17:16:21.267Z,1594401381.267 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T17:11:21.1Z
2020-07-10T17:16:21.267Z,1594401381.267 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T17:16:21.267Z,1594401381.267 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T17:16:29.762Z,1594401389.762 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0058.lzma
2020-07-10T17:16:30.764Z,1594401390.764 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0058.lzma.bak
2020-07-10T17:16:30.764Z,1594401390.764 [DataOverHttps](INFO): SBD MOMSN=12433024
2020-07-10T17:16:47.074Z,1594401407.074 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20200710T135833/Express0059.lzma
2020-07-10T17:16:49.080Z,1594401409.080 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0059.lzma.bak
2020-07-10T17:16:49.080Z,1594401409.080 [DataOverHttps](INFO): SBD MOMSN=12433026
2020-07-10T17:16:50.316Z,1594401410.316 [NAL9602](INFO): Powering up NAL9602
2020-07-10T17:16:50.324Z,1594401410.324 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T17:16:50.324Z,1594401410.324 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T17:16:50.325Z,1594401410.325 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T17:17:01.228Z,1594401421.228 [NAL9602](INFO): NAL9602 initialized
2020-07-10T17:17:39.215Z,1594401459.215 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-07-10T17:17:39.215Z,1594401459.215 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T17:17:39.254Z,1594401459.254 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T17:17:39.628Z,1594401459.628 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T17:17:39.628Z,1594401459.628 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-07-10T17:20:40.211Z,1594401640.211 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-07-10T17:20:40.211Z,1594401640.211 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T17:20:40.221Z,1594401640.221 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T17:20:40.616Z,1594401640.616 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T17:20:40.616Z,1594401640.616 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-07-10T17:21:50.907Z,1594401710.907 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T17:21:50.908Z,1594401710.908 [Default:CheckIn:C.Wait] Stopped
2020-07-10T17:21:50.908Z,1594401710.908 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T17:21:50.908Z,1594401710.908 [Default:CheckIn:D] Running Loop=1
2020-07-10T17:21:51.312Z,1594401711.312 [Default:CheckIn:D] Stopped
2020-07-10T17:21:51.312Z,1594401711.312 [Default:CheckIn:E] Running Loop=1
2020-07-10T17:21:51.729Z,1594401711.729 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 200.174154 min 
2020-07-10T17:21:51.729Z,1594401711.729 [Default:CheckIn:E] Stopped
2020-07-10T17:21:51.729Z,1594401711.729 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T17:21:51.729Z,1594401711.729 [Default:CheckIn] Stopped
2020-07-10T17:21:51.729Z,1594401711.729 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T17:21:51.729Z,1594401711.729 [Default:CheckIn](INFO): Running loop #20
2020-07-10T17:21:51.730Z,1594401711.730 [Default:CheckIn] Running Loop=20
2020-07-10T17:21:51.730Z,1594401711.730 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T17:21:51.730Z,1594401711.730 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T17:22:47.802Z,1594401767.802 [CommandLine](IMPORTANT): got command failComponent
2020-07-10T17:22:47.802Z,1594401767.802 [CommandLine](IMPORTANT): Failed components:
2020-07-10T17:22:47.803Z,1594401767.803 [CommandLine](IMPORTANT): No failed Components.
2020-07-10T17:23:41.288Z,1594401821.288 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-07-10T17:23:41.288Z,1594401821.288 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T17:23:41.315Z,1594401821.315 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T17:23:41.703Z,1594401821.703 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T17:23:41.703Z,1594401821.703 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-07-10T17:26:07.499Z,1594401967.499 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2020-07-10T17:26:33.319Z,1594401993.319 [DataOverHttps](IMPORTANT): SBD MTMSN=20200710T172632
2020-07-10T17:26:40.626Z,1594402000.626 [DataOverHttps](INFO): Received command:Ibit
2020-07-10T17:26:40.635Z,1594402000.635 [CommandLine](IMPORTANT): got command ibit
2020-07-10T17:26:40.675Z,1594402000.675 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-07-10T17:26:40.675Z,1594402000.675 [IBIT](IMPORTANT): Beginning control surface checks.
2020-07-10T17:26:40.695Z,1594402000.695 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-10T17:26:42.254Z,1594402002.254 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-07-10T17:26:42.254Z,1594402002.254 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-10T17:26:42.264Z,1594402002.264 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-10T17:26:42.806Z,1594402002.806 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-10T17:26:42.806Z,1594402002.806 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-07-10T17:26:51.596Z,1594402011.596 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002474
CHAN A1 (24V): -0.009479
CHAN A2 (12V): -0.001989
CHAN A3 (5V): -0.001566
CHAN B0 (3.3V): -0.000078
CHAN B1 (3.15aV): -0.000555
CHAN B2 (3.15bV): -0.000222
CHAN B3 (GND): -0.000141
OPEN: -0.000636
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-10T17:27:04.463Z,1594402024.463 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-10T17:27:04.463Z,1594402024.463 [NAL9602] Data Fault, FailCount= 4
2020-07-10T17:27:04.463Z,1594402024.463 [NAL9602](ERROR): Data Fault
2020-07-10T17:27:04.480Z,1594402024.480 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-10T17:27:04.864Z,1594402024.864 [NAL9602](INFO): Powering down
2020-07-10T17:27:05.264Z,1594402025.264 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-07-10T17:27:05.264Z,1594402025.264 [NAL9602] Hardware Fault, FailCount= 4
2020-07-10T17:27:05.264Z,1594402025.264 [NAL9602](ERROR): Hardware Fault
2020-07-10T17:27:05.689Z,1594402025.689 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-10T17:27:05.689Z,1594402025.689 [NAL9602] No Fault, FailCount= 4
2020-07-10T17:27:35.165Z,1594402055.165 [NAL9602](INFO): Powering up NAL9602
2020-07-10T17:27:46.068Z,1594402066.068 [NAL9602](INFO): NAL9602 initialized
2020-07-10T17:28:31.771Z,1594402111.771 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2020-07-10T17:28:32.178Z,1594402112.178 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 357.119995
Voltage: 16.730000
2020-07-10T17:28:32.179Z,1594402112.179 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-07-10T17:28:32.179Z,1594402112.179 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-07-10T17:28:32.578Z,1594402112.578 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-07-10T17:28:32.579Z,1594402112.579 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-07-10T17:28:32.579Z,1594402112.579 [IBIT](IMPORTANT): Pressure:8.369442 PSI
2020-07-10T17:28:32.579Z,1594402112.579 [IBIT](IMPORTANT): Humidity:10.395556 %
2020-07-10T17:28:32.969Z,1594402112.969 [IBIT](IMPORTANT): Vehicle Pitch:0.152359 degrees
2020-07-10T17:28:32.969Z,1594402112.969 [IBIT](IMPORTANT): Vehicle  Roll:-0.632729 degrees
2020-07-10T17:28:32.969Z,1594402112.969 [IBIT](IMPORTANT): Vehicle  Heading:128.672134 degrees
2020-07-10T17:28:33.373Z,1594402113.373 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-07-10T17:28:33.373Z,1594402113.373 [IBIT](IMPORTANT): buoyancyNeutral: 39.071205 cc
2020-07-10T17:28:33.374Z,1594402113.374 [IBIT](IMPORTANT): massDefault: 0.013271 cm
2020-07-10T17:28:33.374Z,1594402113.374 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2020-07-10T17:28:33.374Z,1594402113.374 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2020-07-10T17:28:33.374Z,1594402113.374 [IBIT](IMPORTANT): IBIT FAILED
2020-07-10T17:28:33.784Z,1594402113.784 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-10T17:21:51.7Z
2020-07-10T17:28:33.784Z,1594402113.784 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T17:28:33.784Z,1594402113.784 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T17:28:34.586Z,1594402114.586 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-07-10T17:28:34.589Z,1594402114.589 [BPC1](INFO): Received data from all battery sticks.
2020-07-10T17:28:40.944Z,1594402120.944 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0061.lzma
2020-07-10T17:28:41.945Z,1594402121.945 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0061.lzma.bak
2020-07-10T17:28:41.945Z,1594402121.945 [DataOverHttps](INFO): SBD MOMSN=12433056
2020-07-10T17:28:57.286Z,1594402137.286 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0064.lzma
2020-07-10T17:28:58.288Z,1594402138.288 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0064.lzma.bak
2020-07-10T17:28:58.288Z,1594402138.288 [DataOverHttps](INFO): SBD MOMSN=12433058
2020-07-10T17:29:01.654Z,1594402141.654 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172900.00,A,3648.17454,N,12147.28372,W,0.739,0.00,100720,,,A*7F
2020-07-10T17:29:01.657Z,1594402141.657 [NAL9602](INFO): GPS fix at 20200710T172900: (36.802909, -121.788062)
2020-07-10T17:29:02.100Z,1594402142.100 [DeadReckonUsingMultipleVelocitySources](INFO): Failure count cleared after critical for DeadReckonUsingMultipleVelocitySources
2020-07-10T17:29:15.075Z,1594402155.075 [DataOverHttps](INFO): Sending 906 bytes from file Logs/20200710T135833/Express0062.lzma
2020-07-10T17:29:16.076Z,1594402156.076 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0062.lzma.bak
2020-07-10T17:29:16.076Z,1594402156.076 [DataOverHttps](INFO): SBD MOMSN=12433060
2020-07-10T17:29:32.641Z,1594402172.641 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Express0065.lzma
2020-07-10T17:29:33.644Z,1594402173.644 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0065.lzma.bak
2020-07-10T17:29:33.644Z,1594402173.644 [DataOverHttps](INFO): SBD MOMSN=12433091
2020-07-10T17:29:34.379Z,1594402174.379 [NAL9602](INFO): Not Powering down - fast GPS
2020-07-10T17:29:34.801Z,1594402174.801 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T17:29:34.801Z,1594402174.801 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T17:29:34.801Z,1594402174.801 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T17:34:35.372Z,1594402475.372 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T17:34:35.372Z,1594402475.372 [Default:CheckIn:C.Wait] Stopped
2020-07-10T17:34:35.373Z,1594402475.373 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T17:34:35.373Z,1594402475.373 [Default:CheckIn:D] Running Loop=1
2020-07-10T17:34:35.773Z,1594402475.773 [Default:CheckIn:D] Stopped
2020-07-10T17:34:35.773Z,1594402475.773 [Default:CheckIn:E] Running Loop=1
2020-07-10T17:34:36.192Z,1594402476.192 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 212.915169 min 
2020-07-10T17:34:36.192Z,1594402476.192 [Default:CheckIn:E] Stopped
2020-07-10T17:34:36.192Z,1594402476.192 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T17:34:36.193Z,1594402476.193 [Default:CheckIn] Stopped
2020-07-10T17:34:36.193Z,1594402476.193 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T17:34:36.193Z,1594402476.193 [Default:CheckIn](INFO): Running loop #21
2020-07-10T17:34:36.193Z,1594402476.193 [Default:CheckIn] Running Loop=21
2020-07-10T17:34:36.193Z,1594402476.193 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T17:34:36.193Z,1594402476.193 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T17:34:38.193Z,1594402478.193 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173436.00,A,3648.14162,N,12147.23003,W,0.253,91.19,100720,,,A*42
2020-07-10T17:34:38.196Z,1594402478.196 [NAL9602](INFO): GPS fix at 20200710T173436: (36.802360, -121.787167)
2020-07-10T17:34:38.206Z,1594402478.206 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T17:34:38.206Z,1594402478.206 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T17:34:45.914Z,1594402485.914 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20200710T135833/Courier0067.lzma
2020-07-10T17:34:46.916Z,1594402486.916 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0067.lzma.bak
2020-07-10T17:34:46.916Z,1594402486.916 [DataOverHttps](INFO): SBD MOMSN=12433102
2020-07-10T17:34:48.719Z,1594402488.719 [NAL9602](INFO): SBD MO Status=0, MOMSN=21847, MT Status=0, MTMSN=0
2020-07-10T17:34:48.719Z,1594402488.719 [NAL9602](INFO): No messages in MT queue
2020-07-10T17:35:02.766Z,1594402502.766 [DataOverHttps](INFO): Sending 277 bytes from file Logs/20200710T135833/Express0068.lzma
2020-07-10T17:35:03.768Z,1594402503.768 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0068.lzma.bak
2020-07-10T17:35:03.768Z,1594402503.768 [DataOverHttps](INFO): SBD MOMSN=12433105
2020-07-10T17:35:04.883Z,1594402504.883 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T17:35:04.883Z,1594402504.883 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T17:35:04.883Z,1594402504.883 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T17:35:19.418Z,1594402519.418 [NAL9602](INFO): Not Powering down - fast GPS
2020-07-10T17:39:08.027Z,1594402748.027 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-10T17:40:05.460Z,1594402805.460 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T17:40:05.460Z,1594402805.460 [Default:CheckIn:C.Wait] Stopped
2020-07-10T17:40:05.460Z,1594402805.460 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T17:40:05.460Z,1594402805.460 [Default:CheckIn:D] Running Loop=1
2020-07-10T17:40:05.875Z,1594402805.875 [Default:CheckIn:D] Stopped
2020-07-10T17:40:05.875Z,1594402805.875 [Default:CheckIn:E] Running Loop=1
2020-07-10T17:40:06.290Z,1594402806.290 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 218.416862 min 
2020-07-10T17:40:06.290Z,1594402806.290 [Default:CheckIn:E] Stopped
2020-07-10T17:40:06.290Z,1594402806.290 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T17:40:06.290Z,1594402806.290 [Default:CheckIn] Stopped
2020-07-10T17:40:06.290Z,1594402806.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T17:40:06.290Z,1594402806.290 [Default:CheckIn](INFO): Running loop #22
2020-07-10T17:40:06.290Z,1594402806.290 [Default:CheckIn] Running Loop=22
2020-07-10T17:40:06.290Z,1594402806.290 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T17:40:06.291Z,1594402806.291 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T17:40:08.281Z,1594402808.281 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174007.00,A,3648.15073,N,12147.22275,W,0.019,340.38,100720,,,D*74
2020-07-10T17:40:08.284Z,1594402808.284 [NAL9602](INFO): GPS fix at 20200710T174007: (36.802512, -121.787046)
2020-07-10T17:40:08.294Z,1594402808.294 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T17:40:08.294Z,1594402808.294 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T17:40:33.329Z,1594402833.329 [NAL9602](INFO): SBD MO Status=0, MOMSN=21848, MT Status=0, MTMSN=0
2020-07-10T17:40:33.329Z,1594402833.329 [NAL9602](INFO): No messages in MT queue
2020-07-10T17:40:33.329Z,1594402833.329 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2020-07-10T17:40:39.959Z,1594402839.959 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-10T17:40:47.434Z,1594402847.434 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200710T135833/Courier0070.lzma
2020-07-10T17:40:48.436Z,1594402848.436 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0070.lzma.bak
2020-07-10T17:40:48.437Z,1594402848.437 [DataOverHttps](INFO): SBD MOMSN=12433116
2020-07-10T17:41:04.039Z,1594402864.039 [NAL9602](INFO): Not Powering down - fast GPS
2020-07-10T17:41:04.174Z,1594402864.174 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20200710T135833/Express0071.lzma
2020-07-10T17:41:05.176Z,1594402865.176 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0071.lzma.bak
2020-07-10T17:41:05.176Z,1594402865.176 [DataOverHttps](INFO): SBD MOMSN=12433119
2020-07-10T17:41:06.470Z,1594402866.470 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T17:41:06.470Z,1594402866.470 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T17:41:06.471Z,1594402866.471 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T17:46:07.061Z,1594403167.061 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T17:46:07.061Z,1594403167.061 [Default:CheckIn:C.Wait] Stopped
2020-07-10T17:46:07.061Z,1594403167.061 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T17:46:07.061Z,1594403167.061 [Default:CheckIn:D] Running Loop=1
2020-07-10T17:46:07.469Z,1594403167.469 [Default:CheckIn:D] Stopped
2020-07-10T17:46:07.469Z,1594403167.469 [Default:CheckIn:E] Running Loop=1
2020-07-10T17:46:07.876Z,1594403167.876 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 224.443424 min 
2020-07-10T17:46:07.876Z,1594403167.876 [Default:CheckIn:E] Stopped
2020-07-10T17:46:07.876Z,1594403167.876 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T17:46:07.876Z,1594403167.876 [Default:CheckIn] Stopped
2020-07-10T17:46:07.876Z,1594403167.876 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T17:46:07.876Z,1594403167.876 [Default:CheckIn](INFO): Running loop #23
2020-07-10T17:46:07.876Z,1594403167.876 [Default:CheckIn] Running Loop=23
2020-07-10T17:46:07.877Z,1594403167.877 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T17:46:07.877Z,1594403167.877 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T17:46:09.882Z,1594403169.882 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174609.00,A,3648.19414,N,12147.15633,W,3.635,40.58,100720,,,D*49
2020-07-10T17:46:09.884Z,1594403169.884 [NAL9602](INFO): GPS fix at 20200710T174609: (36.803236, -121.785939)
2020-07-10T17:46:09.927Z,1594403169.927 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T17:46:09.927Z,1594403169.927 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T17:46:17.050Z,1594403177.050 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200710T135833/Courier0073.lzma
2020-07-10T17:46:18.052Z,1594403178.052 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0073.lzma.bak
2020-07-10T17:46:18.052Z,1594403178.052 [DataOverHttps](INFO): SBD MOMSN=12433193
2020-07-10T17:46:23.233Z,1594403183.233 [NAL9602](INFO): SBD MO Status=0, MOMSN=21849, MT Status=0, MTMSN=0
2020-07-10T17:46:23.233Z,1594403183.233 [NAL9602](INFO): No messages in MT queue
2020-07-10T17:46:28.144Z,1594403188.144 [DataOverHttps](IMPORTANT): SBD MTMSN=20200710T174627
2020-07-10T17:46:35.502Z,1594403195.502 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20200710T135833/Express0074.lzma
2020-07-10T17:46:35.504Z,1594403195.504 [DataOverHttps](INFO): Received command:Ibit
2020-07-10T17:46:35.506Z,1594403195.506 [CommandLine](IMPORTANT): got command ibit
2020-07-10T17:46:35.767Z,1594403195.767 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-07-10T17:46:35.767Z,1594403195.767 [IBIT](IMPORTANT): Beginning control surface checks.
2020-07-10T17:46:35.771Z,1594403195.771 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-10T17:46:36.749Z,1594403196.749 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0074.lzma.bak
2020-07-10T17:46:36.749Z,1594403196.749 [DataOverHttps](INFO): SBD MOMSN=12433197
2020-07-10T17:46:37.373Z,1594403197.373 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174636.00,A,3648.21338,N,12147.13800,W,2.566,30.44,100720,,,D*41
2020-07-10T17:46:37.375Z,1594403197.375 [NAL9602](INFO): GPS fix at 20200710T174636: (36.803556, -121.785633)
2020-07-10T17:46:39.797Z,1594403199.797 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174639.00,A,3648.21510,N,12147.13680,W,2.391,29.04,100720,,,D*46
2020-07-10T17:46:39.799Z,1594403199.799 [NAL9602](INFO): GPS fix at 20200710T174639: (36.803585, -121.785613)
2020-07-10T17:46:43.025Z,1594403203.025 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174642.00,A,3648.21697,N,12147.13563,W,2.371,26.45,100720,,,D*4C
2020-07-10T17:46:43.032Z,1594403203.032 [NAL9602](INFO): GPS fix at 20200710T174642: (36.803616, -121.785594)
2020-07-10T17:46:45.853Z,1594403205.853 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174645.00,A,3648.21881,N,12147.13445,W,2.294,25.24,100720,,,D*49
2020-07-10T17:46:45.855Z,1594403205.855 [NAL9602](INFO): GPS fix at 20200710T174645: (36.803647, -121.785574)
2020-07-10T17:46:46.679Z,1594403206.679 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002922
CHAN A1 (24V): -0.010077
CHAN A2 (12V): -0.001818
CHAN A3 (5V): -0.001513
CHAN B0 (3.3V): -0.000246
CHAN B1 (3.15aV): 0.000384
CHAN B2 (3.15bV): 0.000040
CHAN B3 (GND): 0.000193
OPEN: -0.000136
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-10T17:46:49.085Z,1594403209.085 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174648.00,A,3648.22059,N,12147.13347,W,2.235,22.41,100720,,,D*40
2020-07-10T17:46:49.092Z,1594403209.092 [NAL9602](INFO): GPS fix at 20200710T174648: (36.803677, -121.785558)
2020-07-10T17:46:51.913Z,1594403211.913 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174651.00,A,3648.22219,N,12147.13268,W,2.041,19.89,100720,,,D*4F
2020-07-10T17:46:51.925Z,1594403211.925 [NAL9602](INFO): GPS fix at 20200710T174651: (36.803703, -121.785545)
2020-07-10T17:46:52.886Z,1594403212.886 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200710T135833/Courier0076.lzma
2020-07-10T17:46:53.888Z,1594403213.888 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0076.lzma.bak
2020-07-10T17:46:53.888Z,1594403213.888 [DataOverHttps](INFO): SBD MOMSN=12433201
2020-07-10T17:46:54.743Z,1594403214.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174654.00,A,3648.22378,N,12147.13195,W,1.963,18.79,100720,,,D*49
2020-07-10T17:46:54.745Z,1594403214.745 [NAL9602](INFO): GPS fix at 20200710T174654: (36.803730, -121.785533)
2020-07-10T17:46:57.985Z,1594403217.985 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174657.00,A,3648.22528,N,12147.13130,W,1.847,17.24,100720,,,D*46
2020-07-10T17:46:57.987Z,1594403217.987 [NAL9602](INFO): GPS fix at 20200710T174657: (36.803755, -121.785522)
2020-07-10T17:47:00.801Z,1594403220.801 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174700.00,A,3648.22673,N,12147.13064,W,1.749,17.58,100720,,,D*42
2020-07-10T17:47:00.804Z,1594403220.804 [NAL9602](INFO): GPS fix at 20200710T174700: (36.803779, -121.785511)
2020-07-10T17:47:04.037Z,1594403224.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174703.00,A,3648.22804,N,12147.13008,W,1.594,19.10,100720,,,D*45
2020-07-10T17:47:04.039Z,1594403224.039 [NAL9602](INFO): GPS fix at 20200710T174703: (36.803801, -121.785501)
2020-07-10T17:47:06.861Z,1594403226.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174706.00,A,3648.22936,N,12147.12955,W,1.613,17.91,100720,,,D*4B
2020-07-10T17:47:06.875Z,1594403226.875 [NAL9602](INFO): GPS fix at 20200710T174706: (36.803823, -121.785493)
2020-07-10T17:47:10.091Z,1594403230.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174709.00,A,3648.23088,N,12147.12921,W,1.924,14.43,100720,,,D*4D
2020-07-10T17:47:10.094Z,1594403230.094 [NAL9602](INFO): GPS fix at 20200710T174709: (36.803848, -121.785487)
2020-07-10T17:47:10.275Z,1594403230.275 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20200710T135833/Express0077.lzma
2020-07-10T17:47:11.272Z,1594403231.272 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0077.lzma.bak
2020-07-10T17:47:11.273Z,1594403231.273 [DataOverHttps](INFO): SBD MOMSN=12433204
2020-07-10T17:47:12.929Z,1594403232.929 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174712.00,A,3648.23252,N,12147.12891,W,2.138,12.45,100720,,,D*4E
2020-07-10T17:47:12.932Z,1594403232.932 [NAL9602](INFO): GPS fix at 20200710T174712: (36.803875, -121.785482)
2020-07-10T17:47:15.749Z,1594403235.749 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174715.00,A,3648.23447,N,12147.12848,W,2.235,11.93,100720,,,D*49
2020-07-10T17:47:15.756Z,1594403235.756 [NAL9602](INFO): GPS fix at 20200710T174715: (36.803908, -121.785475)
2020-07-10T17:47:18.985Z,1594403238.985 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174718.00,A,3648.23663,N,12147.12806,W,2.469,10.26,100720,,,D*4A
2020-07-10T17:47:18.987Z,1594403238.987 [NAL9602](INFO): GPS fix at 20200710T174718: (36.803944, -121.785468)
2020-07-10T17:47:21.813Z,1594403241.813 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174721.00,A,3648.23888,N,12147.12758,W,2.585,9.76,100720,,,D*71
2020-07-10T17:47:21.816Z,1594403241.816 [NAL9602](INFO): GPS fix at 20200710T174721: (36.803981, -121.785460)
2020-07-10T17:47:21.871Z,1594403241.871 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 36.803982 Longitude: -121.785461
2020-07-10T17:47:22.264Z,1594403242.264 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.730000
2020-07-10T17:47:22.265Z,1594403242.265 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-07-10T17:47:22.265Z,1594403242.265 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2020-07-10T17:47:22.640Z,1594403242.640 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-07-10T17:47:22.641Z,1594403242.641 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-07-10T17:47:22.641Z,1594403242.641 [IBIT](IMPORTANT): Pressure:8.418277 PSI
2020-07-10T17:47:22.641Z,1594403242.641 [IBIT](IMPORTANT): Humidity:10.084239 %
2020-07-10T17:47:23.067Z,1594403243.067 [IBIT](IMPORTANT): Vehicle Pitch:2.648436 degrees
2020-07-10T17:47:23.067Z,1594403243.067 [IBIT](IMPORTANT): Vehicle  Roll:-0.797606 degrees
2020-07-10T17:47:23.068Z,1594403243.068 [IBIT](IMPORTANT): Vehicle  Heading:274.189880 degrees
2020-07-10T17:47:23.480Z,1594403243.480 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-07-10T17:47:23.481Z,1594403243.481 [IBIT](IMPORTANT): buoyancyNeutral: 39.071205 cc
2020-07-10T17:47:23.481Z,1594403243.481 [IBIT](IMPORTANT): massDefault: 0.013271 cm
2020-07-10T17:47:23.481Z,1594403243.481 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2020-07-10T17:47:23.481Z,1594403243.481 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2020-07-10T17:47:23.482Z,1594403243.482 [IBIT](IMPORTANT): IBIT PASSED
2020-07-10T17:47:23.908Z,1594403243.908 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T17:47:23.908Z,1594403243.908 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T17:47:23.908Z,1594403243.908 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T17:47:54.531Z,1594403274.531 [NAL9602](INFO): Not Powering down - fast GPS
2020-07-10T17:52:24.435Z,1594403544.435 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-10T17:52:24.435Z,1594403544.435 [Default:CheckIn:C.Wait] Stopped
2020-07-10T17:52:24.435Z,1594403544.435 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T17:52:24.435Z,1594403544.435 [Default:CheckIn:D] Running Loop=1
2020-07-10T17:52:24.833Z,1594403544.833 [Default:CheckIn:D] Stopped
2020-07-10T17:52:24.833Z,1594403544.833 [Default:CheckIn:E] Running Loop=1
2020-07-10T17:52:25.250Z,1594403545.250 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 230.732829 min 
2020-07-10T17:52:25.250Z,1594403545.250 [Default:CheckIn:E] Stopped
2020-07-10T17:52:25.250Z,1594403545.250 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-10T17:52:25.250Z,1594403545.250 [Default:CheckIn] Stopped
2020-07-10T17:52:25.250Z,1594403545.250 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T17:52:25.250Z,1594403545.250 [Default:CheckIn](INFO): Running loop #24
2020-07-10T17:52:25.251Z,1594403545.251 [Default:CheckIn] Running Loop=24
2020-07-10T17:52:25.251Z,1594403545.251 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T17:52:25.251Z,1594403545.251 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T17:52:27.253Z,1594403547.253 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175226.00,A,3648.45784,N,12147.31995,W,8.164,240.61,100720,,,D*77
2020-07-10T17:52:27.256Z,1594403547.256 [NAL9602](INFO): GPS fix at 20200710T175226: (36.807631, -121.788666)
2020-07-10T17:52:27.266Z,1594403547.266 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T17:52:27.266Z,1594403547.266 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T17:52:28.077Z,1594403548.077 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T17:52:28.077Z,1594403548.077 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T17:52:28.078Z,1594403548.078 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T17:53:39.169Z,1594403619.169 [NAL9602](INFO): SBD MO Status=2, MOMSN=21850, MT Status=2, MTMSN=0
2020-07-10T17:53:39.169Z,1594403619.169 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-07-10T17:53:56.935Z,1594403636.935 [NAL9602](INFO): SBD MO Status=0, MOMSN=21850, MT Status=0, MTMSN=0
2020-07-10T17:53:56.935Z,1594403636.935 [NAL9602](INFO): No messages in MT queue
2020-07-10T17:54:27.655Z,1594403667.655 [NAL9602](INFO): Not Powering down - fast GPS
2020-07-10T17:56:39.477Z,1594403799.477 [DataOverHttps](IMPORTANT): SBD MTMSN=20200710T175638
2020-07-10T17:56:46.754Z,1594403806.754 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree;set profile_station.YoYoMaxDepth 30 meter" 2rj5t 1 2
2020-07-10T17:56:46.840Z,1594403806.840 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree;set profile_station.YoYoMaxDepth 30 meter" 2rj5t 1 2.000000
2020-07-10T17:56:46.840Z,1594403806.840 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=2rj5t
2020-07-10T17:56:46.841Z,1594403806.841 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='2rj5t'): "load Science/profile_station.xml;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree;set profile_station.YoYoMaxDepth 30 meter" ASAP
2020-07-10T17:56:47.756Z,1594403807.756 [DataOverHttps](IMPORTANT): SBD MTMSN=20200710T175646
2020-07-10T17:56:55.130Z,1594403815.130 [DataOverHttps](INFO): Received command:sched asap "set profile_station.MaxDepth 40 meter;run " 2rj5t 2 2
2020-07-10T17:56:55.191Z,1594403815.191 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.MaxDepth 40 meter;run " 2rj5t 2 2.000000
2020-07-10T17:56:55.192Z,1594403815.192 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=2rj5t
2020-07-10T17:56:55.193Z,1594403815.193 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='2rj5t'): "set profile_station.MaxDepth 40 meter;run " ASAP
2020-07-10T17:56:55.261Z,1594403815.261 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2020-07-10T17:56:55.261Z,1594403815.261 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2020-07-10T17:56:55.341Z,1594403815.341 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2020-07-10T17:56:55.344Z,1594403815.344 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2020-07-10T17:56:55.347Z,1594403815.347 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2020-07-10T17:56:55.380Z,1594403815.380 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2020-07-10T17:56:55.383Z,1594403815.383 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2020-07-10T17:56:55.386Z,1594403815.386 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2020-07-10T17:56:55.389Z,1594403815.389 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2020-07-10T17:56:55.408Z,1594403815.408 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2020-07-10T17:56:55.411Z,1594403815.411 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2020-07-10T17:56:55.414Z,1594403815.414 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2020-07-10T17:56:55.417Z,1594403815.417 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2020-07-10T17:56:55.440Z,1594403815.440 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2020-07-10T17:56:55.443Z,1594403815.443 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2020-07-10T17:56:55.446Z,1594403815.446 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2020-07-10T17:56:55.449Z,1594403815.449 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2020-07-10T17:56:55.466Z,1594403815.466 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2020-07-10T17:56:55.466Z,1594403815.466 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2020-07-10T17:56:55.770Z,1594403815.770 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUS = 0 bool
2020-07-10T17:56:55.773Z,1594403815.773 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2020-07-10T17:56:55.776Z,1594403815.776 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSminPitch = -60.000000 arcdeg
2020-07-10T17:56:55.779Z,1594403815.779 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2020-07-10T17:56:55.782Z,1594403815.782 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min
2020-07-10T17:56:55.785Z,1594403815.785 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2020-07-10T17:56:55.787Z,1594403815.787 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2020-07-10T17:56:55.790Z,1594403815.790 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2020-07-10T17:56:55.793Z,1594403815.793 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2020-07-10T17:56:55.796Z,1594403815.796 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 %
2020-07-10T17:56:55.801Z,1594403815.801 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2020-07-10T17:56:55.804Z,1594403815.804 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2020-07-10T17:56:55.807Z,1594403815.807 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool
2020-07-10T17:56:55.810Z,1594403815.810 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-07-10T17:56:55.813Z,1594403815.813 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool
2020-07-10T17:56:55.837Z,1594403815.837 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2020-07-10T17:56:55.848Z,1594403815.848 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool
2020-07-10T17:56:55.853Z,1594403815.853 [MissionManager](INFO): DefineArg profile_station:Science.EnabledTurbulence_NPS = 0.000000 bool
2020-07-10T17:56:55.869Z,1594403815.869 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2020-07-10T17:56:55.876Z,1594403815.876 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2020-07-10T17:56:55.880Z,1594403815.880 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool
2020-07-10T17:56:55.896Z,1594403815.896 [MissionManager](INFO): DefineArg profile_station:Science.EnabledISUS = 0.000000 bool
2020-07-10T17:56:55.903Z,1594403815.903 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2020-07-10T17:56:55.908Z,1594403815.908 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count
2020-07-10T17:56:55.911Z,1594403815.911 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2020-07-10T17:56:55.955Z,1594403815.955 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2020-07-10T17:56:55.958Z,1594403815.958 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m
2020-07-10T17:56:55.960Z,1594403815.960 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m
2020-07-10T17:56:55.986Z,1594403815.986 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2020-07-10T17:56:55.989Z,1594403815.989 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2020-07-10T17:56:55.992Z,1594403815.992 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2020-07-10T17:56:56.006Z,1594403816.006 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2020-07-10T17:56:56.009Z,1594403816.009 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2020-07-10T17:56:56.012Z,1594403816.012 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2020-07-10T17:56:56.035Z,1594403816.035 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2020-07-10T17:56:56.038Z,1594403816.038 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2020-07-10T17:56:56.041Z,1594403816.041 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2020-07-10T17:56:56.044Z,1594403816.044 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2020-07-10T17:56:56.057Z,1594403816.057 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2020-07-10T17:56:56.060Z,1594403816.060 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2020-07-10T17:56:56.063Z,1594403816.063 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2020-07-10T17:56:56.086Z,1594403816.086 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2020-07-10T17:56:56.089Z,1594403816.089 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2020-07-10T17:56:56.092Z,1594403816.092 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2020-07-10T17:56:56.106Z,1594403816.106 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2020-07-10T17:56:56.109Z,1594403816.109 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2020-07-10T17:56:56.112Z,1594403816.112 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb
2020-07-10T17:56:56.129Z,1594403816.129 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m
2020-07-10T17:56:56.148Z,1594403816.148 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg
2020-07-10T17:56:56.151Z,1594403816.151 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg
2020-07-10T17:56:56.154Z,1594403816.154 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m
2020-07-10T17:56:56.196Z,1594403816.196 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-07-10T17:56:56.225Z,1594403816.225 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-07-10T17:56:56.267Z,1594403816.267 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-07-10T17:56:56.277Z,1594403816.277 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-07-10T17:56:56.300Z,1594403816.300 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-07-10T17:56:56.361Z,1594403816.361 [MissionManager](INFO): RedefineArg profile_station:Science.SampleISUS = value:1 bool
2020-07-10T17:56:56.375Z,1594403816.375 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2020-07-10T17:56:56.375Z,1594403816.375 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-07-10T17:56:56.454Z,1594403816.454 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2020-07-10T17:56:56.461Z,1594403816.461 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2020-07-10T17:56:56.464Z,1594403816.464 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-07-10T17:56:56.479Z,1594403816.479 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2020-07-10T17:56:56.482Z,1594403816.482 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-07-10T17:56:56.489Z,1594403816.489 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2020-07-10T17:56:56.504Z,1594403816.504 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2020-07-10T17:56:56.519Z,1594403816.519 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-10T17:56:56.553Z,1594403816.553 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-07-10T17:56:56.580Z,1594403816.580 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2020-07-10T17:56:56.583Z,1594403816.583 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2020-07-10T17:56:56.585Z,1594403816.585 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-07-10T17:56:56.608Z,1594403816.608 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-07-10T17:56:56.615Z,1594403816.615 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-07-10T17:56:56.644Z,1594403816.644 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-07-10T17:56:56.661Z,1594403816.661 [profile_station:I.Pitch](DEBUG): Construct.
2020-07-10T17:56:56.687Z,1594403816.687 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2020-07-10T17:56:56.690Z,1594403816.690 [profile_station:K.SetSpeed](DEBUG): Construct.
2020-07-10T17:56:56.693Z,1594403816.693 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-07-10T17:56:56.713Z,1594403816.713 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-07-10T17:56:56.777Z,1594403816.777 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2020-07-10T17:56:56.802Z,1594403816.802 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2020-07-10T17:56:56.873Z,1594403816.873 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Estimation="Tethys/Estimation"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Sensor="Tethys/Sensor"
       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/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="profile_station">

    <Description>
        This mission yoyos in a circle around a specified location.
    </Description>

<!-- Mission-wide settings -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>4</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications
    </Description><Units:minute/><Value>60</Value></DefineArg>

<!-- You probably need to change these. -->

    <DefineArg Name="Lat"><Description>
        The latitude of the center of the circle.
    </Description><Units:degree/><Value>36.806966</Value></DefineArg>

    <DefineArg Name="Lon"><Description>
        The longitude of the center of the circle.
    </Description><Units:degree/><Value>-121.824326</Value></DefineArg>

    <DefineArg Name="Radius"><Description>
        Radius to circle at
    </Description><Units:meter/><Value>300</Value></DefineArg>

<!-- You probably do not need to change these. -->

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>200</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="YoYoPitch"><Description>
        Pitch (plus and minus) for yo-yo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle speed.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

<!-- You are even less likely to need to change these. -->

    <DefineArg Name="CircleMaxError"><Description>
        If this distance away from the circle, drive straight towards (or away
        from the center). Otherwise, try to reduce distance from the ideal
        circle.
    </Description><Units:meter/><Value>100</Value></DefineArg>

    <DefineArg Name="CircleTurnToPort"><Description>
        If true, vehicle turns to the left around the center point. If false,
        vehicle turns to the right.
    </Description><False/></DefineArg>

    <DefineArg Name="KwpHeading"><Description>
        Used to relax waypoint cross-track error constant that is adjusted for
        docking. (You can override this setting by passing an argument.)
    </Description><Units:radian_per_meter/><Value>0.010</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>205</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

<!-- Missions should almost always start with a timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

<!-- Most missions should use the NeedComms aggregate. -->

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

<!-- Missions should almost always start with standard safety envelopes; most missions should not expose the parameters of these envelopes. -->

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

<!-- Check for additional instructions first. -->

    <Call Id="StartingMission" RefId="NeedComms"/>

<!-- Many missions will keep mass position and buoyancy volume fixed at defaults. -->

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyNeutral/></Setting>
    </Guidance:Buoyancy>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
    </Guidance:SetSpeed>

    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="CircleWrapper">

        <Sequence Repeat="4096"/>

        <Assign><Parallel/><Control:HorizontalControl.kwpHeading/><Arg Name="KwpHeading"/></Assign>

        <Guidance:Circle>
            <Sequence/>
            <Setting><Guidance:Circle.latitude/><Arg Name="Lat"/></Setting>
            <Setting><Guidance:Circle.longitude/><Arg Name="Lon"/></Setting>
            <Setting><Guidance:Circle.radius/><Arg Name="Radius"/></Setting>
            <Setting><Guidance:Circle.maxError/><Arg Name="CircleMaxError"/></Setting>
            <Setting><Guidance:Circle.turnToPort/><Arg Name="CircleTurnToPort"/></Setting>
        </Guidance:Circle>

    </Aggregate>

</Mission>


2020-07-10T17:56:56.873Z,1594403816.873 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2020-07-10T17:57:04.827Z,1594403824.827 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 20.000000 minute
2020-07-10T17:57:04.828Z,1594403824.828 [CommandLine](IMPORTANT): got command set profile_station.Lat 36.797001 degree
2020-07-10T17:57:04.829Z,1594403824.829 [CommandLine](IMPORTANT): got command set profile_station.Lon -121.847000 degree
2020-07-10T17:57:04.830Z,1594403824.830 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 30.000000 meter
2020-07-10T17:57:07.088Z,1594403827.088 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 40.000000 meter
2020-07-10T17:57:07.089Z,1594403827.089 [CommandLine](IMPORTANT): got command run 
2020-07-10T17:57:07.098Z,1594403827.098 [CommandLine](IMPORTANT): Running 
2020-07-10T17:57:07.262Z,1594403827.262 [Default] Stopped
2020-07-10T17:57:07.262Z,1594403827.262 [Default](DEBUG): Aggregate::uninitialize Default
2020-07-10T17:57:07.262Z,1594403827.262 [Default:B.GoToSurface] Stopped
2020-07-10T17:57:07.262Z,1594403827.262 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-10T17:57:07.262Z,1594403827.262 [Default:CheckIn] Stopped
2020-07-10T17:57:07.262Z,1594403827.262 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-10T17:57:07.262Z,1594403827.262 [Default:CheckIn:C.Wait] Stopped
2020-07-10T17:57:07.262Z,1594403827.262 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T17:57:07.263Z,1594403827.263 [MissionManager](IMPORTANT): Started mission profile_station
2020-07-10T17:57:07.263Z,1594403827.263 [profile_station] Running Loop=1
2020-07-10T17:57:07.263Z,1594403827.263 [profile_station](DEBUG): Aggregate::initialize profile_station
2020-07-10T17:57:07.263Z,1594403827.263 [profile_station:Science] Running Loop=1
2020-07-10T17:57:07.263Z,1594403827.263 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2020-07-10T17:57:07.263Z,1594403827.263 [profile_station:Science:A] Running Loop=1
2020-07-10T17:57:07.263Z,1594403827.263 [profile_station:Science:B] Running Loop=1
2020-07-10T17:57:07.263Z,1594403827.263 [profile_station:Science:C] Running Loop=1
2020-07-10T17:57:07.264Z,1594403827.264 [profile_station:Science:D] Running Loop=1
2020-07-10T17:57:07.264Z,1594403827.264 [profile_station:Science:E] Running Loop=1
2020-07-10T17:57:07.264Z,1594403827.264 [profile_station:Science:F] Running Loop=1
2020-07-10T17:57:07.264Z,1594403827.264 [profile_station:Science:G] Running Loop=1
2020-07-10T17:57:07.264Z,1594403827.264 [profile_station:Science:H] Running Loop=1
2020-07-10T17:57:07.264Z,1594403827.264 [profile_station:Science:Read_Oil] Running Loop=1
2020-07-10T17:57:07.264Z,1594403827.264 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-07-10T17:57:07.264Z,1594403827.264 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2020-07-10T17:57:07.264Z,1594403827.264 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-07-10T17:57:07.264Z,1594403827.264 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-07-10T17:57:07.266Z,1594403827.266 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2020-07-10T17:57:07.267Z,1594403827.267 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2020-07-10T17:57:07.267Z,1594403827.267 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-07-10T17:57:07.267Z,1594403827.267 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-07-10T17:57:07.267Z,1594403827.267 [profile_station:Science:PeakDetectNO3] Running Loop=1
2020-07-10T17:57:07.267Z,1594403827.267 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2020-07-10T17:57:07.268Z,1594403827.268 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-07-10T17:57:07.268Z,1594403827.268 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-07-10T17:57:07.270Z,1594403827.270 [profile_station:Science:PeakDetectOil] Running Loop=1
2020-07-10T17:57:07.270Z,1594403827.270 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2020-07-10T17:57:07.270Z,1594403827.270 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-07-10T17:57:07.270Z,1594403827.270 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-07-10T17:57:07.272Z,1594403827.272 [profile_station:Science:PeakDetectFDOM] Running Loop=1
2020-07-10T17:57:07.272Z,1594403827.272 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM
2020-07-10T17:57:07.273Z,1594403827.273 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-07-10T17:57:07.273Z,1594403827.273 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-07-10T17:57:07.275Z,1594403827.275 [profile_station:StandardEnvelopes] Running Loop=1
2020-07-10T17:57:07.275Z,1594403827.275 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2020-07-10T17:57:07.275Z,1594403827.275 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-07-10T17:57:07.275Z,1594403827.275 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-07-10T17:57:07.276Z,1594403827.276 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-07-10T17:57:07.276Z,1594403827.276 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-07-10T17:57:07.276Z,1594403827.276 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-07-10T17:57:07.276Z,1594403827.276 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-07-10T17:57:07.276Z,1594403827.276 [profile_station:I.Pitch] Running Loop=1
2020-07-10T17:57:07.276Z,1594403827.276 [profile_station:I.Pitch](DEBUG): Initialize.
2020-07-10T17:57:07.276Z,1594403827.276 [profile_station:J.Buoyancy] Running Loop=1
2020-07-10T17:57:07.277Z,1594403827.277 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-07-10T17:57:07.277Z,1594403827.277 [profile_station:K.SetSpeed] Running Loop=1
2020-07-10T17:57:07.277Z,1594403827.277 [profile_station:K.SetSpeed](DEBUG): Initialize.
2020-07-10T17:57:07.277Z,1594403827.277 [profile_station:L.DepthEnvelope] Running Loop=1
2020-07-10T17:57:07.277Z,1594403827.277 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-07-10T17:57:07.277Z,1594403827.277 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-07-10T17:57:07.277Z,1594403827.277 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-07-10T17:57:07.277Z,1594403827.277 [profile_station:N.YoYo] Running Loop=1
2020-07-10T17:57:07.278Z,1594403827.278 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2020-07-10T17:57:07.278Z,1594403827.278 [profile_station:C] Running Loop=1
2020-07-10T17:57:07.299Z,1594403827.299 [profile_station:N.YoYo] Running Loop=1
2020-07-10T17:57:07.300Z,1594403827.300 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-07-10T17:57:07.300Z,1594403827.300 [profile_station:L.DepthEnvelope] Running Loop=1
2020-07-10T17:57:07.301Z,1594403827.301 [profile_station:K.SetSpeed] Running Loop=1
2020-07-10T17:57:07.302Z,1594403827.302 [profile_station:J.Buoyancy] Running Loop=1
2020-07-10T17:57:07.302Z,1594403827.302 [profile_station:I.Pitch] Running Loop=1
2020-07-10T17:57:07.307Z,1594403827.307 [profile_station:StandardEnvelopes] Running Loop=1
2020-07-10T17:57:07.307Z,1594403827.307 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-07-10T17:57:07.323Z,1594403827.323 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-07-10T17:57:07.324Z,1594403827.324 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-07-10T17:57:07.324Z,1594403827.324 [profile_station:C] Stopped
2020-07-10T17:57:07.324Z,1594403827.324 [profile_station:E] Running Loop=1
2020-07-10T17:57:07.324Z,1594403827.324 [profile_station:Science] Running Loop=1
2020-07-10T17:57:07.326Z,1594403827.326 [profile_station:Science:PeakDetectFDOM] Stopped
2020-07-10T17:57:07.326Z,1594403827.326 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM
2020-07-10T17:57:07.326Z,1594403827.326 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-07-10T17:57:07.330Z,1594403827.330 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-07-10T17:57:07.330Z,1594403827.330 [profile_station:Science:PeakDetectOil] Stopped
2020-07-10T17:57:07.330Z,1594403827.330 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2020-07-10T17:57:07.331Z,1594403827.331 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-07-10T17:57:07.331Z,1594403827.331 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-07-10T17:57:07.331Z,1594403827.331 [profile_station:Science:PeakDetectNO3] Stopped
2020-07-10T17:57:07.331Z,1594403827.331 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2020-07-10T17:57:07.331Z,1594403827.331 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-07-10T17:57:07.331Z,1594403827.331 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-07-10T17:57:07.331Z,1594403827.331 [profile_station:Science:HighestChlPeakReport] Stopped
2020-07-10T17:57:07.331Z,1594403827.331 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2020-07-10T17:57:07.331Z,1594403827.331 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-07-10T17:57:07.331Z,1594403827.331 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-07-10T17:57:07.331Z,1594403827.331 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-07-10T17:57:07.332Z,1594403827.332 [profile_station:Science:Read_Oil] Stopped
2020-07-10T17:57:07.332Z,1594403827.332 [profile_station:Science:H] Stopped
2020-07-10T17:57:07.332Z,1594403827.332 [profile_station:Science:G] Running Loop=1
2020-07-10T17:57:07.332Z,1594403827.332 [profile_station:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-07-10T17:57:07.333Z,1594403827.333 [profile_station:Science:F] Stopped
2020-07-10T17:57:07.333Z,1594403827.333 [profile_station:Science:E] Stopped
2020-07-10T17:57:07.333Z,1594403827.333 [profile_station:Science:D] Running Loop=1
2020-07-10T17:57:07.333Z,1594403827.333 [profile_station:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2020-07-10T17:57:07.336Z,1594403827.336 [WetLabsBB2FL](INFO): Powering up
2020-07-10T17:57:07.338Z,1594403827.338 [profile_station:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2020-07-10T17:57:07.338Z,1594403827.338 [profile_station:Science:C] Running Loop=1
2020-07-10T17:57:07.343Z,1594403827.343 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2020-07-10T17:57:07.344Z,1594403827.344 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2020-07-10T17:57:07.344Z,1594403827.344 [profile_station:Science:B] Stopped
2020-07-10T17:57:07.344Z,1594403827.344 [profile_station:Science:A] Stopped
2020-07-10T17:57:07.665Z,1594403827.665 [profile_station:E] Stopped
2020-07-10T17:57:07.666Z,1594403827.666 [profile_station:F] Running Loop=1
2020-07-10T17:57:08.081Z,1594403828.081 [profile_station:F] Stopped
2020-07-10T17:57:08.082Z,1594403828.082 [profile_station:G] Running Loop=1
2020-07-10T17:57:08.466Z,1594403828.466 [profile_station:G] Stopped
2020-07-10T17:57:08.466Z,1594403828.466 [profile_station:StartingMission] Running Loop=1
2020-07-10T17:57:08.466Z,1594403828.466 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2020-07-10T17:57:08.885Z,1594403828.885 [profile_station:M.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2020-07-10T17:57:08.929Z,1594403828.929 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2020-07-10T17:57:08.929Z,1594403828.929 [profile_station:NeedComms] Running Loop=1
2020-07-10T17:57:08.929Z,1594403828.929 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2020-07-10T17:57:08.929Z,1594403828.929 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2020-07-10T17:57:08.929Z,1594403828.929 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-10T17:57:08.929Z,1594403828.929 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-07-10T17:57:08.930Z,1594403828.930 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-07-10T17:57:08.930Z,1594403828.930 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-07-10T17:57:08.931Z,1594403828.931 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-10T17:57:08.931Z,1594403828.931 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-10T17:57:08.931Z,1594403828.931 [profile_station:NeedComms:A] Running Loop=1
2020-07-10T17:57:08.933Z,1594403828.933 [profile_station:NeedComms:A](INFO): last time_fix was: 1594403546.000000 second since 1970/01/01T00:00:00Z 
2020-07-10T17:57:08.933Z,1594403828.933 [profile_station:NeedComms:A] Stopped
2020-07-10T17:57:09.298Z,1594403829.298 [profile_station:NeedComms:C] Running Loop=1
2020-07-10T17:57:09.708Z,1594403829.708 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-07-10T17:57:10.865Z,1594403830.865 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2020-07-10T17:57:11.289Z,1594403831.289 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175710.00,A,3648.18125,N,12148.60453,W,13.918,256.51,100720,,,D*43
2020-07-10T17:57:11.292Z,1594403831.292 [NAL9602](INFO): GPS fix at 20200710T175710: (36.803021, -121.810075)
2020-07-10T17:57:11.345Z,1594403831.345 [profile_station:NeedComms:C] Stopped
2020-07-10T17:57:11.345Z,1594403831.345 [profile_station:NeedComms:D] Running Loop=1
2020-07-10T17:57:11.744Z,1594403831.744 [profile_station:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-07-10T17:57:19.418Z,1594403839.418 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200710T135833/Courier0079.lzma
2020-07-10T17:57:20.421Z,1594403840.421 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0079.lzma.bak
2020-07-10T17:57:20.421Z,1594403840.421 [DataOverHttps](IMPORTANT): SBD MOMSN=12433278, MTMSN=20200710T175719
2020-07-10T17:57:28.254Z,1594403848.254 [DataOverHttps](INFO): Received command:Stop
2020-07-10T17:57:28.347Z,1594403848.347 [CommandLine](IMPORTANT): got command stop
2020-07-10T17:57:28.347Z,1594403848.347 [CommandLine](IMPORTANT): Scheduling is paused
2020-07-10T17:57:28.347Z,1594403848.347 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-07-10T17:57:28.744Z,1594403848.744 [MissionManager](INFO): MissionManager is completed.
2020-07-10T17:57:28.744Z,1594403848.744 [MissionManager](INFO): Uninitializing Mission profile_station
2020-07-10T17:57:28.744Z,1594403848.744 [profile_station] Stopped
2020-07-10T17:57:28.744Z,1594403848.744 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2020-07-10T17:57:28.744Z,1594403848.744 [profile_station:Science] Stopped
2020-07-10T17:57:28.744Z,1594403848.744 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2020-07-10T17:57:28.744Z,1594403848.744 [profile_station:Science:C] Stopped
2020-07-10T17:57:28.744Z,1594403848.744 [profile_station:Science:D] Stopped
2020-07-10T17:57:28.744Z,1594403848.744 [profile_station:Science:G] Stopped
2020-07-10T17:57:28.744Z,1594403848.744 [profile_station:Science:PeakDetectChl] Stopped
2020-07-10T17:57:28.744Z,1594403848.744 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:NeedComms] Stopped
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:NeedComms:B.GoToSurface] Stopped
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:NeedComms:D] Stopped
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:StandardEnvelopes] Stopped
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-07-10T17:57:28.745Z,1594403848.745 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-07-10T17:57:28.746Z,1594403848.746 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-07-10T17:57:28.746Z,1594403848.746 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-07-10T17:57:28.746Z,1594403848.746 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-07-10T17:57:28.746Z,1594403848.746 [profile_station:StartingMission] Stopped
2020-07-10T17:57:28.746Z,1594403848.746 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission
2020-07-10T17:57:28.746Z,1594403848.746 [profile_station:I.Pitch] Stopped
2020-07-10T17:57:28.750Z,1594403848.750 [profile_station:J.Buoyancy] Stopped
2020-07-10T17:57:28.750Z,1594403848.750 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2020-07-10T17:57:28.750Z,1594403848.750 [profile_station:K.SetSpeed] Stopped
2020-07-10T17:57:28.750Z,1594403848.750 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2020-07-10T17:57:28.751Z,1594403848.751 [profile_station:L.DepthEnvelope] Stopped
2020-07-10T17:57:28.751Z,1594403848.751 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2020-07-10T17:57:28.751Z,1594403848.751 [profile_station:M.AltitudeEnvelope] Stopped
2020-07-10T17:57:28.751Z,1594403848.751 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-07-10T17:57:28.751Z,1594403848.751 [profile_station:N.YoYo] Stopped
2020-07-10T17:57:28.751Z,1594403848.751 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2020-07-10T17:57:29.111Z,1594403849.111 [WetLabsBB2FL](INFO): Powering down
2020-07-10T17:57:29.116Z,1594403849.116 [MissionManager](IMPORTANT): Started mission Default
2020-07-10T17:57:29.116Z,1594403849.116 [Default] Running Loop=1
2020-07-10T17:57:29.116Z,1594403849.116 [Default](DEBUG): Aggregate::initialize Default
2020-07-10T17:57:29.116Z,1594403849.116 [Default:B.GoToSurface] Running Loop=1
2020-07-10T17:57:29.116Z,1594403849.116 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-10T17:57:29.116Z,1594403849.116 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-10T17:57:29.117Z,1594403849.117 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-10T17:57:29.117Z,1594403849.117 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-10T17:57:29.117Z,1594403849.117 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-10T17:57:29.118Z,1594403849.118 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-10T17:57:29.118Z,1594403849.118 [Default:A.Wait] Running Loop=1
2020-07-10T17:57:29.118Z,1594403849.118 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-07-10T17:57:36.794Z,1594403856.794 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200710T135833/Courier0082.lzma
2020-07-10T17:57:37.584Z,1594403857.584 [NAL9602](INFO): SBD MO Status=2, MOMSN=21851, MT Status=2, MTMSN=0
2020-07-10T17:57:37.584Z,1594403857.584 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-07-10T17:57:37.796Z,1594403857.796 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0082.lzma.bak
2020-07-10T17:57:37.796Z,1594403857.796 [DataOverHttps](INFO): SBD MOMSN=12433282
2020-07-10T17:57:42.456Z,1594403862.456 [Default:A.Wait](INFO): Done Waiting.
2020-07-10T17:57:42.457Z,1594403862.457 [Default:A.Wait] Stopped
2020-07-10T17:57:42.457Z,1594403862.457 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-07-10T17:57:42.850Z,1594403862.850 [Default:CheckIn] Running Loop=1
2020-07-10T17:57:42.850Z,1594403862.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-10T17:57:42.850Z,1594403862.850 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-10T17:57:47.281Z,1594403867.281 [NAL9602](INFO): SBD MO Status=0, MOMSN=21851, MT Status=0, MTMSN=0
2020-07-10T17:57:47.281Z,1594403867.281 [NAL9602](INFO): No messages in MT queue
2020-07-10T17:57:48.493Z,1594403868.493 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175748.00,A,3648.13696,N,12148.78365,W,14.287,248.73,100720,,,D*44
2020-07-10T17:57:48.496Z,1594403868.496 [NAL9602](INFO): GPS fix at 20200710T175748: (36.802283, -121.813061)
2020-07-10T17:57:48.506Z,1594403868.506 [Default:CheckIn:Read_GPS] Stopped
2020-07-10T17:57:48.506Z,1594403868.506 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-10T17:58:06.298Z,1594403886.298 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20200710T135833/Courier0088.lzma
2020-07-10T17:58:07.301Z,1594403887.301 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Courier0088.lzma.bak
2020-07-10T17:58:07.301Z,1594403887.301 [DataOverHttps](INFO): SBD MOMSN=12433285
2020-07-10T17:58:20.427Z,1594403900.427 [NAL9602](INFO): Not Powering down - fast GPS
2020-07-10T17:58:23.831Z,1594403903.831 [DataOverHttps](INFO): Sending 738 bytes from file Logs/20200710T135833/Express0080.lzma
2020-07-10T17:58:24.832Z,1594403904.832 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0080.lzma.bak
2020-07-10T17:58:24.832Z,1594403904.832 [DataOverHttps](INFO): SBD MOMSN=12433288
2020-07-10T17:58:40.679Z,1594403920.679 [DataOverHttps](INFO): Sending 525 bytes from file Logs/20200710T135833/Express0083.lzma
2020-07-10T17:58:41.680Z,1594403921.680 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0083.lzma.bak
2020-07-10T17:58:41.680Z,1594403921.680 [DataOverHttps](INFO): SBD MOMSN=12433309
2020-07-10T17:58:57.611Z,1594403937.611 [DataOverHttps](INFO): Sending 482 bytes from file Logs/20200710T135833/Express0089.lzma
2020-07-10T17:58:58.612Z,1594403938.612 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0089.lzma.bak
2020-07-10T17:58:58.612Z,1594403938.612 [DataOverHttps](INFO): SBD MOMSN=12433329
2020-07-10T17:59:14.370Z,1594403954.370 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200710T135833/Express0092.lzma
2020-07-10T17:59:15.372Z,1594403955.372 [DataOverHttps](INFO): Moved sent file to Logs/20200710T135833/Express0092.lzma.bak
2020-07-10T17:59:15.373Z,1594403955.373 [DataOverHttps](INFO): SBD MOMSN=12433335
2020-07-10T17:59:16.643Z,1594403956.643 [Default:CheckIn:Read_Iridium] Stopped
2020-07-10T17:59:16.644Z,1594403956.644 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-10T17:59:16.644Z,1594403956.644 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-10T18:00:59.660Z,1594404059.660 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -35.00 mm (1 active estimators).
2020-07-10T18:01:56.308Z,1594404116.308 [DataOverHttps](IMPORTANT): SBD MTMSN=20200710T180155
2020-07-10T18:02:04.259Z,1594404124.259 [DataOverHttps](INFO): Received command:restart logs