2022-04-20T20:36:59.400Z,1650487019.400 [Supervisor](DEBUG): Initializing supervisor.
2022-04-20T20:36:59.405Z,1650487019.405 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-04-20T20:36:59.406Z,1650487019.406 [SyncHandler](INFO): Protected caller Thread ID is 1257
2022-04-20T20:36:59.407Z,1650487019.407 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-04-20T20:36:59.407Z,1650487019.407 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-04-20T20:36:59.408Z,1650487019.408 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1258
2022-04-20T20:36:59.412Z,1650487019.412 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-04-20T20:36:59.432Z,1650487019.432 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-04-20T20:36:59.433Z,1650487019.433 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-04-20T20:36:59.433Z,1650487019.433 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1259
2022-04-20T20:36:59.437Z,1650487019.437 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-04-20T20:36:59.438Z,1650487019.438 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-04-20T20:36:59.439Z,1650487019.439 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1260
2022-04-20T20:36:59.441Z,1650487019.441 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-04-20T20:36:59.443Z,1650487019.443 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-04-20T20:36:59.443Z,1650487019.443 [logger ThreadHandler](INFO): Protected caller Thread ID is 1261
2022-04-20T20:36:59.447Z,1650487019.447 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-04-20T20:36:59.447Z,1650487019.447 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-04-20T20:36:59.449Z,1650487019.449 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-04-20T20:37:00.022Z,1650487020.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-04-20T20:37:00.022Z,1650487020.022 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-04-20T20:37:00.233Z,1650487020.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-04-20T20:37:00.234Z,1650487020.234 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-04-20T20:37:00.313Z,1650487020.313 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-04-20T20:37:00.535Z,1650487020.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-04-20T20:37:00.536Z,1650487020.536 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-04-20T20:37:00.613Z,1650487020.613 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-04-20T20:37:00.708Z,1650487020.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-04-20T20:37:00.708Z,1650487020.708 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-04-20T20:37:01.048Z,1650487021.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-04-20T20:37:01.049Z,1650487021.049 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-04-20T20:37:01.173Z,1650487021.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-04-20T20:37:01.174Z,1650487021.174 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-04-20T20:37:01.733Z,1650487021.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-04-20T20:37:01.734Z,1650487021.734 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-04-20T20:37:01.930Z,1650487021.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-04-20T20:37:01.930Z,1650487021.930 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-04-20T20:37:02.314Z,1650487022.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-04-20T20:37:02.315Z,1650487022.315 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-04-20T20:37:02.588Z,1650487022.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-04-20T20:37:02.589Z,1650487022.589 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-04-20T20:37:02.699Z,1650487022.699 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-04-20T20:37:02.700Z,1650487022.700 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-04-20T20:37:03.884Z,1650487023.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-04-20T20:37:03.885Z,1650487023.885 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-04-20T20:37:04.231Z,1650487024.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-04-20T20:37:04.231Z,1650487024.231 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-04-20T20:37:04.450Z,1650487024.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-04-20T20:37:04.452Z,1650487024.452 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2022-04-20T20:37:04.453Z,1650487024.453 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2022-04-20T20:37:04.643Z,1650487024.643 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2022-04-20T20:37:04.724Z,1650487024.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2022-04-20T20:37:04.860Z,1650487024.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2022-04-20T20:37:04.944Z,1650487024.944 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2022-04-20T20:37:05.204Z,1650487025.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-04-20T20:37:05.205Z,1650487025.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2022-04-20T20:37:05.308Z,1650487025.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2022-04-20T20:37:05.419Z,1650487025.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2022-04-20T20:37:05.512Z,1650487025.512 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2022-04-20T20:37:05.656Z,1650487025.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2022-04-20T20:37:05.753Z,1650487025.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2022-04-20T20:37:05.846Z,1650487025.846 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2022-04-20T20:37:05.846Z,1650487025.846 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-04-20T20:37:05.868Z,1650487025.868 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-04-20T20:37:05.879Z,1650487025.879 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-04-20T20:37:05.880Z,1650487025.880 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-04-20T20:37:05.977Z,1650487025.977 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-04-20T20:37:05.977Z,1650487025.977 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-04-20T20:37:06.032Z,1650487026.032 [VerticalControl](DEBUG): Construct VerticalControl.
2022-04-20T20:37:06.093Z,1650487026.093 [VerticalControl] Loaded
2022-04-20T20:37:06.093Z,1650487026.093 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-04-20T20:37:06.096Z,1650487026.096 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-04-20T20:37:06.136Z,1650487026.136 [HorizontalControl] Loaded
2022-04-20T20:37:06.136Z,1650487026.136 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-04-20T20:37:06.139Z,1650487026.139 [SpeedControl](DEBUG): Construct SpeedControl.
2022-04-20T20:37:06.142Z,1650487026.142 [SpeedControl] Loaded
2022-04-20T20:37:06.142Z,1650487026.142 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-04-20T20:37:06.145Z,1650487026.145 [LoopControl](DEBUG): Construct LoopControl.
2022-04-20T20:37:06.145Z,1650487026.145 [LoopControl] Loaded
2022-04-20T20:37:06.146Z,1650487026.146 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-04-20T20:37:06.146Z,1650487026.146 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-04-20T20:37:06.147Z,1650487026.147 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-04-20T20:37:06.274Z,1650487026.274 [BuoyancyServo] Loaded
2022-04-20T20:37:06.274Z,1650487026.274 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-04-20T20:37:06.293Z,1650487026.293 [ElevatorServo] Loaded
2022-04-20T20:37:06.294Z,1650487026.294 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-04-20T20:37:06.312Z,1650487026.312 [MassServo] Loaded
2022-04-20T20:37:06.312Z,1650487026.312 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-04-20T20:37:06.330Z,1650487026.330 [RudderServo] Loaded
2022-04-20T20:37:06.331Z,1650487026.331 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-04-20T20:37:06.346Z,1650487026.346 [ThrusterHE] Loaded
2022-04-20T20:37:06.346Z,1650487026.346 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-04-20T20:37:06.346Z,1650487026.346 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-04-20T20:37:06.347Z,1650487026.347 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-04-20T20:37:06.401Z,1650487026.401 [DepthRateCalculator] Loaded
2022-04-20T20:37:06.401Z,1650487026.401 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-04-20T20:37:06.406Z,1650487026.406 [PitchRateCalculator] Loaded
2022-04-20T20:37:06.407Z,1650487026.407 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-04-20T20:37:06.417Z,1650487026.417 [SpeedCalculator] Loaded
2022-04-20T20:37:06.417Z,1650487026.417 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-04-20T20:37:06.433Z,1650487026.433 [VerticalHomogeneityIndexCalculator] Loaded
2022-04-20T20:37:06.433Z,1650487026.433 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2022-04-20T20:37:06.437Z,1650487026.437 [YawRateCalculator] Loaded
2022-04-20T20:37:06.438Z,1650487026.438 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-04-20T20:37:06.455Z,1650487026.455 [ElevatorOffsetCalculator] Loaded
2022-04-20T20:37:06.456Z,1650487026.456 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-04-20T20:37:06.456Z,1650487026.456 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-04-20T20:37:06.457Z,1650487026.457 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-04-20T20:37:06.570Z,1650487026.570 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-04-20T20:37:06.570Z,1650487026.570 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-04-20T20:37:06.584Z,1650487026.584 [NavChart] Loaded
2022-04-20T20:37:06.584Z,1650487026.584 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-04-20T20:37:06.590Z,1650487026.590 [UniversalFixResidualReporter] Loaded
2022-04-20T20:37:06.590Z,1650487026.590 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-04-20T20:37:06.591Z,1650487026.591 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-04-20T20:37:06.591Z,1650487026.591 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-04-20T20:37:07.138Z,1650487027.138 [AHRS_M2] Loaded
2022-04-20T20:37:07.138Z,1650487027.138 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-04-20T20:37:07.388Z,1650487027.388 [BackseatComponent] Loaded
2022-04-20T20:37:07.389Z,1650487027.389 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-04-20T20:37:07.390Z,1650487027.390 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2022-04-20T20:37:07.390Z,1650487027.390 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1344
2022-04-20T20:37:07.393Z,1650487027.393 [LcmUniversalReporter] Loaded
2022-04-20T20:37:07.393Z,1650487027.393 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-04-20T20:37:08.221Z,1650487028.221 [BPC1] Loaded
2022-04-20T20:37:08.221Z,1650487028.221 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-04-20T20:37:08.316Z,1650487028.316 [DataOverHttps] Loaded
2022-04-20T20:37:08.316Z,1650487028.316 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-04-20T20:37:08.318Z,1650487028.318 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0
2022-04-20T20:37:08.318Z,1650487028.318 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1345
2022-04-20T20:37:08.338Z,1650487028.338 [Depth_Keller] Loaded
2022-04-20T20:37:08.338Z,1650487028.338 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-04-20T20:37:08.343Z,1650487028.343 [DropWeight] Loaded
2022-04-20T20:37:08.343Z,1650487028.343 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-04-20T20:37:08.403Z,1650487028.403 [NAL9602] Loaded
2022-04-20T20:37:08.404Z,1650487028.404 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-04-20T20:37:08.434Z,1650487028.434 [Onboard] Loaded
2022-04-20T20:37:08.434Z,1650487028.434 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-04-20T20:37:08.435Z,1650487028.435 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0
2022-04-20T20:37:08.436Z,1650487028.436 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1346
2022-04-20T20:37:08.448Z,1650487028.448 [Power24vConverter] Loaded
2022-04-20T20:37:08.448Z,1650487028.448 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-04-20T20:37:08.461Z,1650487028.461 [Radio_Surface] Loaded
2022-04-20T20:37:08.461Z,1650487028.461 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-04-20T20:37:08.463Z,1650487028.463 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095E4E0
2022-04-20T20:37:08.463Z,1650487028.463 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1347
2022-04-20T20:37:08.464Z,1650487028.464 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-04-20T20:37:08.464Z,1650487028.464 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-04-20T20:37:09.036Z,1650487029.036 [StratificationFrontDetector](INFO): thresholdVTHI set to: 0.399988 degC
2022-04-20T20:37:09.036Z,1650487029.036 [StratificationFrontDetector](DEBUG): (re)initializing
2022-04-20T20:37:09.036Z,1650487029.036 [StratificationFrontDetector] Loaded
2022-04-20T20:37:09.036Z,1650487029.036 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2022-04-20T20:37:09.037Z,1650487029.037 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-04-20T20:37:09.037Z,1650487029.037 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-04-20T20:37:09.229Z,1650487029.229 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-04-20T20:37:09.229Z,1650487029.229 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-04-20T20:37:09.290Z,1650487029.290 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-04-20T20:37:09.290Z,1650487029.290 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-04-20T20:37:09.470Z,1650487029.470 [CTD_Seabird] Loaded
2022-04-20T20:37:09.471Z,1650487029.471 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-04-20T20:37:09.472Z,1650487029.472 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A8D4E0
2022-04-20T20:37:09.472Z,1650487029.472 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1348
2022-04-20T20:37:09.492Z,1650487029.492 [PAR_Licor] Loaded
2022-04-20T20:37:09.493Z,1650487029.493 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-04-20T20:37:09.524Z,1650487029.524 [WetLabsBB2FL] Loaded
2022-04-20T20:37:09.525Z,1650487029.525 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-04-20T20:37:09.526Z,1650487029.526 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ABD4E0
2022-04-20T20:37:09.526Z,1650487029.526 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1349
2022-04-20T20:37:09.527Z,1650487029.527 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-04-20T20:37:09.527Z,1650487029.527 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-04-20T20:37:09.852Z,1650487029.852 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-04-20T20:37:09.853Z,1650487029.853 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-04-20T20:37:09.000Z,1650487030.000 [SBIT](DEBUG): Construct Startup Built In Test.
2022-04-20T20:37:10.009Z,1650487030.009 [SBIT] Loaded
2022-04-20T20:37:10.009Z,1650487030.009 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-04-20T20:37:10.012Z,1650487030.012 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-04-20T20:37:10.025Z,1650487030.025 [IBIT] Loaded
2022-04-20T20:37:10.025Z,1650487030.025 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-04-20T20:37:10.031Z,1650487030.031 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-04-20T20:37:10.136Z,1650487030.136 [CBIT] Loaded
2022-04-20T20:37:10.136Z,1650487030.136 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-04-20T20:37:10.137Z,1650487030.137 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-04-20T20:37:10.143Z,1650487030.143 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-04-20T20:37:10.146Z,1650487030.146 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-04-20T20:37:10.157Z,1650487030.157 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-04-20T20:37:10.160Z,1650487030.160 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA74E0
2022-04-20T20:37:10.160Z,1650487030.160 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1350
2022-04-20T20:37:10.165Z,1650487030.165 [Supervisor](INFO): Main Thread ID is 1256
2022-04-20T20:37:10.165Z,1650487030.165 [Supervisor](DEBUG): Running supervisor.
2022-04-20T20:37:10.166Z,1650487030.166 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1351
2022-04-20T20:37:10.166Z,1650487030.166 [CommandExec](INFO): Initializing the command executive.
2022-04-20T20:37:10.168Z,1650487030.168 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1352
2022-04-20T20:37:10.170Z,1650487030.170 [controlThread ThreadHandler](INFO): Handler Thread ID is 1353
2022-04-20T20:37:10.171Z,1650487030.171 [controlThread](DEBUG): Initializing ControlThread
2022-04-20T20:37:10.172Z,1650487030.172 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-04-20T20:37:10.174Z,1650487030.174 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-04-20T20:37:10.174Z,1650487030.174 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-04-20T20:37:10.175Z,1650487030.175 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-04-20T20:37:10.176Z,1650487030.176 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-04-20T20:37:10.177Z,1650487030.177 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-04-20T20:37:10.177Z,1650487030.177 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-04-20T20:37:10.177Z,1650487030.177 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2022-04-20T20:37:10.178Z,1650487030.178 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-04-20T20:37:10.178Z,1650487030.178 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-04-20T20:37:10.180Z,1650487030.180 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-04-20T20:37:10.180Z,1650487030.180 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-04-20T20:37:10.190Z,1650487030.190 [SBIT](INFO): Initialize SBIT Component.
2022-04-20T20:37:10.190Z,1650487030.190 [SBIT](IMPORTANT): git: 2022-04-20
2022-04-20T20:37:10.190Z,1650487030.190 [SBIT](INFO): git hash: 17b9e0401aa943d1c74ec99193b02e6257598627
2022-04-20T20:37:10.191Z,1650487030.191 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-04-20T20:37:10.192Z,1650487030.192 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-04-20T20:37:10.193Z,1650487030.193 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2022-04-20T20:37:10.193Z,1650487030.193 [IBIT](INFO): Initialize IBIT Component.
2022-04-20T20:37:10.194Z,1650487030.194 [CBIT](DEBUG): Initialize CBIT Component.
2022-04-20T20:37:10.195Z,1650487030.195 [logger ThreadHandler](INFO): Handler Thread ID is 1354
2022-04-20T20:37:10.205Z,1650487030.205 [CBIT](DEBUG): Initialized mux pins.
2022-04-20T20:37:10.205Z,1650487030.205 [CBIT](DEBUG): Initializing the watchdog timer.
2022-04-20T20:37:10.214Z,1650487030.214 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1355
2022-04-20T20:37:10.226Z,1650487030.226 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1356
2022-04-20T20:37:10.227Z,1650487030.227 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-04-20T20:37:10.229Z,1650487030.229 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-04-20T20:37:10.230Z,1650487030.230 [CBIT](DEBUG): Initializing heartbeat.
2022-04-20T20:37:10.238Z,1650487030.238 [Onboard ThreadHandler](INFO): Handler Thread ID is 1357
2022-04-20T20:37:10.255Z,1650487030.255 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1358
2022-04-20T20:37:10.274Z,1650487030.274 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1359
2022-04-20T20:37:10.274Z,1650487030.274 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-04-20T20:37:10.279Z,1650487030.279 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1361
2022-04-20T20:37:10.282Z,1650487030.282 [WetLabsBB2FL](INFO): Powering up
2022-04-20T20:37:10.283Z,1650487030.283 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1363
2022-04-20T20:37:10.291Z,1650487030.291 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-04-20T20:37:10.291Z,1650487030.291 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-04-20T20:37:10.291Z,1650487030.291 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-04-20T20:37:10.292Z,1650487030.292 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-04-20T20:37:10.292Z,1650487030.292 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-04-20T20:37:10.292Z,1650487030.292 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-04-20T20:37:10.292Z,1650487030.292 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-04-20T20:37:10.292Z,1650487030.292 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-04-20T20:37:10.292Z,1650487030.292 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-04-20T20:37:10.292Z,1650487030.292 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-04-20T20:37:10.293Z,1650487030.293 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-04-20T20:37:10.293Z,1650487030.293 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-04-20T20:37:10.293Z,1650487030.293 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-04-20T20:37:10.294Z,1650487030.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-04-20T20:37:10.294Z,1650487030.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-04-20T20:37:10.294Z,1650487030.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-04-20T20:37:10.301Z,1650487030.301 [CBIT](DEBUG): Deactivating GF circuits.
2022-04-20T20:37:10.301Z,1650487030.301 [CBIT](DEBUG): Deactivating emergency mode.
2022-04-20T20:37:10.337Z,1650487030.337 [CBIT](DEBUG): Backplane powered.
2022-04-20T20:37:10.338Z,1650487030.338 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-04-20T20:37:10.352Z,1650487030.352 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-20T20:37:10.368Z,1650487030.368 [MissionManager](DEBUG):
2022-04-20T20:37:10.369Z,1650487030.369 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-04-20T20:37:10.453Z,1650487030.453 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-04-20T20:37:10.455Z,1650487030.455 [Default:A.Wait](DEBUG): Construct Wait.
2022-04-20T20:37:10.457Z,1650487030.457 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-20T20:37:10.511Z,1650487030.511 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-04-20T20:37:10.533Z,1650487030.533 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-04-20T20:37:10.539Z,1650487030.539 [Default:E.Execute](DEBUG): Construct Execute.
2022-04-20T20:37:10.559Z,1650487030.559 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2022-04-20T20:37:10.563Z,1650487030.563 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-04-20T20:37:10.577Z,1650487030.577 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-04-20T20:37:10.640Z,1650487030.640 [Power24vConverter](INFO): Powering up.
2022-04-20T20:37:10.665Z,1650487030.665 [Radio_Surface](INFO): Powering up
2022-04-20T20:37:10.695Z,1650487030.695 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-04-20T20:37:10.702Z,1650487030.702 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-04-20T20:37:10.703Z,1650487030.703 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-04-20T20:37:10.710Z,1650487030.710 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-04-20T20:37:10.711Z,1650487030.711 [MassServo](DEBUG): Initializing EZServoServo.
2022-04-20T20:37:10.718Z,1650487030.718 [MassServo](DEBUG): Initializing MassServo.
2022-04-20T20:37:10.718Z,1650487030.718 [RudderServo](DEBUG): Initializing EZServoServo.
2022-04-20T20:37:10.729Z,1650487030.729 [RudderServo](DEBUG): Initializing RudderServo.
2022-04-20T20:37:10.730Z,1650487030.730 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-04-20T20:37:10.738Z,1650487030.738 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-04-20T20:37:10.994Z,1650487030.994 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2022-04-20T20:37:10.995Z,1650487030.995 [DropWeight] Hardware Fault, FailCount= 1
2022-04-20T20:37:10.995Z,1650487030.995 [DropWeight](ERROR): Hardware Fault
2022-04-20T20:37:11.029Z,1650487031.029 [CommandExec](FAULT): Scheduling is paused
2022-04-20T20:37:11.037Z,1650487031.037 [CBIT](INFO): Critical error at 20220420T203710
2022-04-20T20:37:11.038Z,1650487031.038 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-04-20T20:37:11.040Z,1650487031.040 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-04-20T20:37:11.040Z,1650487031.040 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-04-20T20:37:11.737Z,1650487031.737 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-04-20T20:37:11.737Z,1650487031.737 [RudderServo](FAULT): Rudder failed to initialize
2022-04-20T20:37:11.738Z,1650487031.738 [RudderServo] Communications Fault, FailCount= 1
2022-04-20T20:37:11.738Z,1650487031.738 [RudderServo](ERROR): Communications Fault
2022-04-20T20:37:11.739Z,1650487031.739 [CBIT](INFO): Critical error at 20220420T203711
2022-04-20T20:37:11.742Z,1650487031.742 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-04-20T20:37:11.938Z,1650487031.938 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-04-20T20:37:11.938Z,1650487031.938 [RudderServo](INFO): Powering down
2022-04-20T20:37:12.389Z,1650487032.389 [WetLabsBB2FL](INFO): Powering down
2022-04-20T20:37:12.640Z,1650487032.640 [RudderServo](DEBUG): Initializing EZServoServo.
2022-04-20T20:37:12.758Z,1650487032.758 [RudderServo](DEBUG): Initializing RudderServo.
2022-04-20T20:37:12.764Z,1650487032.764 [CBIT](INFO): Clearing failed state for component RudderServo
2022-04-20T20:37:12.764Z,1650487032.764 [RudderServo] No Fault, FailCount= 1
2022-04-20T20:37:16.530Z,1650487036.530 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-04-20T20:37:24.284Z,1650487044.284 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004903
2022-04-20T20:37:38.447Z,1650487058.447 [NAL9602](INFO): Powering up NAL9602
2022-04-20T20:37:49.359Z,1650487069.359 [NAL9602](INFO): NAL9602 initialized
2022-04-20T20:38:02.791Z,1650487082.791 [SBIT](IMPORTANT): Beginning Startup BIT
2022-04-20T20:38:02.795Z,1650487082.795 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-20T20:38:13.681Z,1650487093.681 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.005145
CHAN A1 (24V): 0.169262
CHAN A2 (12V): -0.004066
CHAN A3 (5V): -0.003222
CHAN B0 (3.3V): -0.000763
CHAN B1 (3.15aV): -0.001322
CHAN B2 (3.15bV): -0.001644
CHAN B3 (GND): -0.000265
OPEN: 0.004609
Full Scale: +/- 1 mA
2022-04-20T20:38:54.005Z,1650487134.005 [NAL9602](INFO): SBD MO Status=2, MOMSN=7647, MT Status=2, MTMSN=0
2022-04-20T20:38:54.005Z,1650487134.005 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-04-20T20:38:56.947Z,1650487136.947 [SBIT](IMPORTANT): SBIT PASSED
2022-04-20T20:38:56.947Z,1650487136.947 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-04-20T20:38:56.948Z,1650487136.948 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=32 count;
2022-04-20T20:38:56.948Z,1650487136.948 [SBIT](IMPORTANT): BPC1.batterySamplingInterval=1 hour;
2022-04-20T20:38:56.948Z,1650487136.948 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;
2022-04-20T20:38:56.948Z,1650487136.948 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;
2022-04-20T20:38:56.948Z,1650487136.948 [SBIT](IMPORTANT): CBIT.gf24Offset=-18.106 microampere;
2022-04-20T20:38:56.948Z,1650487136.948 [SBIT](IMPORTANT): CBIT.stopDepth=295 meter;
2022-04-20T20:38:56.949Z,1650487136.949 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool;
2022-04-20T20:38:56.949Z,1650487136.949 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2022-04-20T20:38:56.949Z,1650487136.949 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.050000 celsius;
2022-04-20T20:38:56.949Z,1650487136.949 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;
2022-04-20T20:38:56.949Z,1650487136.949 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;
2022-04-20T20:38:56.949Z,1650487136.949 [SBIT](IMPORTANT): HorizontalControl.rudDeadband=0.001 degree;
2022-04-20T20:38:56.949Z,1650487136.949 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;
2022-04-20T20:38:56.950Z,1650487136.950 [SBIT](IMPORTANT): PAR_Licor.parCal=1.38207 none;
2022-04-20T20:38:56.950Z,1650487136.950 [SBIT](IMPORTANT): RudderServo.deviationAngle=0.1 degree;
2022-04-20T20:38:56.950Z,1650487136.950 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool;
2022-04-20T20:38:56.950Z,1650487136.950 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=255.152614 cubic_centimeter;
2022-04-20T20:38:56.950Z,1650487136.950 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.001 degree;
2022-04-20T20:38:56.950Z,1650487136.950 [SBIT](IMPORTANT): VerticalControl.massDefault=8.108730 millimeter;
2022-04-20T20:38:56.950Z,1650487136.950 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool;
2022-04-20T20:38:56.950Z,1650487136.950 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=1 bool;
2022-04-20T20:38:57.252Z,1650487137.252 [MissionManager](IMPORTANT): Started mission Startup
2022-04-20T20:38:57.252Z,1650487137.252 [Startup] Running Loop=1
2022-04-20T20:38:57.252Z,1650487137.252 [Startup](DEBUG): Aggregate::initialize Startup
2022-04-20T20:38:57.252Z,1650487137.252 [Startup:A.GoToSurface] Running Loop=1
2022-04-20T20:38:57.252Z,1650487137.252 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-20T20:38:57.253Z,1650487137.253 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-20T20:38:57.254Z,1650487137.254 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-20T20:38:57.254Z,1650487137.254 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-20T20:38:57.254Z,1650487137.254 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-20T20:38:57.255Z,1650487137.255 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-20T20:38:57.256Z,1650487137.256 [Startup:StartupSatComms] Running Loop=1
2022-04-20T20:38:57.256Z,1650487137.256 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-04-20T20:38:57.256Z,1650487137.256 [Startup:StartupSatComms:A] Running Loop=1
2022-04-20T20:38:57.711Z,1650487137.711 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-04-20T20:39:38.440Z,1650487178.440 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T20:39:51.767Z,1650487191.767 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T20:39:57.438Z,1650487197.438 [Startup:StartupSatComms:A](INFO): Timed out from 2022-04-20T20:38:57.3Z
2022-04-20T20:39:57.439Z,1650487197.439 [Startup:StartupSatComms:A] Stopped
2022-04-20T20:39:57.439Z,1650487197.439 [Startup:StartupSatComms:B] Running Loop=1
2022-04-20T20:39:57.843Z,1650487197.843 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-20T20:40:04.322Z,1650487204.322 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220330T162015/Courier0520.lzma
2022-04-20T20:40:05.308Z,1650487205.308 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T20:40:05.324Z,1650487205.324 [DataOverHttps](INFO): Moved sent file to Logs/20220330T162015/Courier0520.lzma.bak
2022-04-20T20:40:05.324Z,1650487205.324 [DataOverHttps](INFO): SBD MOMSN=16651162
2022-04-20T20:40:10.182Z,1650487210.182 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-04-20T20:40:10.182Z,1650487210.182 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T20:40:10.273Z,1650487210.273 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T20:40:10.653Z,1650487210.653 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T20:40:10.653Z,1650487210.653 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-04-20T20:40:18.635Z,1650487218.635 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T20:40:29.505Z,1650487229.505 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20220420T203659/Courier0000.lzma
2022-04-20T20:40:30.507Z,1650487230.507 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0000.lzma.bak
2022-04-20T20:40:30.507Z,1650487230.507 [DataOverHttps](IMPORTANT): SBD MOMSN=16651170, MTMSN=20220420T204029
2022-04-20T20:40:32.775Z,1650487232.775 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T20:40:39.386Z,1650487239.386 [DataOverHttps](INFO): Received command: strobe off
2022-04-20T20:40:39.471Z,1650487239.471 [CommandExec](IMPORTANT): got command strobe off
2022-04-20T20:40:39.471Z,1650487239.471 [CommandExec](IMPORTANT): Deactivating strobe
2022-04-20T20:40:46.511Z,1650487246.511 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T20:40:47.960Z,1650487247.960 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20220330T162015/Express0521.lzma
2022-04-20T20:40:48.959Z,1650487248.959 [DataOverHttps](INFO): Moved sent file to Logs/20220330T162015/Express0521.lzma.bak
2022-04-20T20:40:48.960Z,1650487248.960 [DataOverHttps](INFO): SBD MOMSN=16651181
2022-04-20T20:40:57.439Z,1650487257.439 [Startup:StartupSatComms:B](INFO): Timed out from 2022-04-20T20:39:57.4Z
2022-04-20T20:40:57.439Z,1650487257.439 [Startup:StartupSatComms:B] Stopped
2022-04-20T20:40:57.440Z,1650487257.440 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-04-20T20:40:57.440Z,1650487257.440 [Startup:StartupSatComms] Stopped
2022-04-20T20:40:57.440Z,1650487257.440 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-04-20T20:40:57.440Z,1650487257.440 [Startup](INFO): Completed Startup
2022-04-20T20:40:57.441Z,1650487257.441 [MissionManager](INFO): Startup is completed.
2022-04-20T20:40:57.441Z,1650487257.441 [MissionManager](INFO): Uninitializing Mission Startup
2022-04-20T20:40:57.441Z,1650487257.441 [Startup] Stopped
2022-04-20T20:40:57.441Z,1650487257.441 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-04-20T20:40:57.441Z,1650487257.441 [Startup:A.GoToSurface] Stopped
2022-04-20T20:40:57.441Z,1650487257.441 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-04-20T20:40:57.854Z,1650487257.854 [MissionManager](IMPORTANT): Started mission Default
2022-04-20T20:40:57.854Z,1650487257.854 [Default] Running Loop=1
2022-04-20T20:40:57.854Z,1650487257.854 [Default](DEBUG): Aggregate::initialize Default
2022-04-20T20:40:57.855Z,1650487257.855 [Default:B.GoToSurface] Running Loop=1
2022-04-20T20:40:57.855Z,1650487257.855 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-20T20:40:57.855Z,1650487257.855 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-20T20:40:57.855Z,1650487257.855 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-20T20:40:57.855Z,1650487257.855 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-20T20:40:57.856Z,1650487257.856 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-20T20:40:57.856Z,1650487257.856 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-20T20:40:57.856Z,1650487257.856 [Default:A.Wait] Running Loop=1
2022-04-20T20:40:57.856Z,1650487257.856 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-04-20T20:40:59.843Z,1650487259.843 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T20:41:05.825Z,1650487265.825 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220420T203659/Courier0004.lzma
2022-04-20T20:41:06.827Z,1650487266.827 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0004.lzma.bak
2022-04-20T20:41:06.827Z,1650487266.827 [DataOverHttps](INFO): SBD MOMSN=16651186
2022-04-20T20:41:11.198Z,1650487271.198 [Default:A.Wait](INFO): Done Waiting.
2022-04-20T20:41:11.198Z,1650487271.198 [Default:A.Wait] Stopped
2022-04-20T20:41:11.199Z,1650487271.199 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T20:41:11.603Z,1650487271.603 [Default:CheckIn] Running Loop=1
2022-04-20T20:41:11.603Z,1650487271.603 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T20:41:11.603Z,1650487271.603 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T20:41:11.996Z,1650487271.996 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-04-20T20:41:13.175Z,1650487273.175 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T20:41:26.507Z,1650487286.507 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T20:41:40.260Z,1650487300.260 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T20:41:53.603Z,1650487313.603 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T20:42:07.361Z,1650487327.361 [BPC1](INFO): Calculating totals. Valid battery stick count: 30. Valid reserve battery stick count: 4.
2022-04-20T20:42:07.365Z,1650487327.365 [BPC1](INFO): Received data from all battery sticks.
2022-04-20T20:42:52.595Z,1650487372.595 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-20T20:43:11.199Z,1650487391.199 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-04-20T20:43:11.199Z,1650487391.199 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T20:43:11.210Z,1650487391.210 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T20:43:11.626Z,1650487391.626 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T20:43:11.626Z,1650487391.626 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-04-20T20:46:11.771Z,1650487571.771 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T20:41:11.6Z
2022-04-20T20:46:11.771Z,1650487571.771 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T20:46:11.771Z,1650487571.771 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T20:46:12.205Z,1650487572.205 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-04-20T20:46:12.206Z,1650487572.206 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T20:46:12.207Z,1650487572.207 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-20T20:46:12.217Z,1650487572.217 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T20:46:12.623Z,1650487572.623 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T20:46:12.623Z,1650487572.623 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-04-20T20:46:19.413Z,1650487579.413 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20220420T203659/Courier0007.lzma
2022-04-20T20:46:20.415Z,1650487580.415 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0007.lzma.bak
2022-04-20T20:46:20.415Z,1650487580.415 [DataOverHttps](INFO): SBD MOMSN=16651191
2022-04-20T20:46:37.231Z,1650487597.231 [DataOverHttps](INFO): Sending 1204 bytes from file Logs/20220420T203659/Express0001.lzma
2022-04-20T20:46:38.231Z,1650487598.231 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0001.lzma.bak
2022-04-20T20:46:38.231Z,1650487598.231 [DataOverHttps](INFO): SBD MOMSN=16651199
2022-04-20T20:46:54.977Z,1650487614.977 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20220420T203659/Express0005.lzma
2022-04-20T20:46:55.979Z,1650487615.979 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0005.lzma.bak
2022-04-20T20:46:55.979Z,1650487615.979 [DataOverHttps](INFO): SBD MOMSN=16651235
2022-04-20T20:47:11.567Z,1650487631.567 [Power24vConverter](INFO): Powering down.
2022-04-20T20:47:12.885Z,1650487632.885 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20220420T203659/Express0008.lzma
2022-04-20T20:47:13.887Z,1650487633.887 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0008.lzma.bak
2022-04-20T20:47:13.887Z,1650487633.887 [DataOverHttps](INFO): SBD MOMSN=16651240
2022-04-20T20:47:16.436Z,1650487636.436 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T20:47:16.436Z,1650487636.436 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T20:47:16.436Z,1650487636.436 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T20:47:50.339Z,1650487670.339 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T20:47:50.339Z,1650487670.339 [NAL9602] Data Fault, FailCount= 1
2022-04-20T20:47:50.339Z,1650487670.339 [NAL9602](ERROR): Data Fault
2022-04-20T20:47:50.380Z,1650487670.380 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T20:47:50.742Z,1650487670.742 [NAL9602](INFO): Powering down
2022-04-20T20:47:51.571Z,1650487671.571 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T20:47:51.571Z,1650487671.571 [NAL9602] No Fault, FailCount= 1
2022-04-20T20:48:21.039Z,1650487701.039 [NAL9602](INFO): Powering up NAL9602
2022-04-20T20:48:31.951Z,1650487711.951 [NAL9602](INFO): NAL9602 initialized
2022-04-20T20:49:13.174Z,1650487753.174 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-04-20T20:49:13.174Z,1650487753.174 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T20:49:13.189Z,1650487753.189 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T20:49:13.607Z,1650487753.607 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T20:49:13.607Z,1650487753.607 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-04-20T20:52:11.363Z,1650487931.363 [CBIT](INFO): Clearing failed state for component DropWeight
2022-04-20T20:52:11.363Z,1650487931.363 [DropWeight] No Fault, FailCount= 1
2022-04-20T20:52:14.161Z,1650487934.161 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-04-20T20:52:14.161Z,1650487934.161 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T20:52:14.171Z,1650487934.171 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T20:52:14.575Z,1650487934.575 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T20:52:14.575Z,1650487934.575 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-04-20T20:52:16.987Z,1650487936.987 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T20:52:16.987Z,1650487936.987 [Default:CheckIn:C.Wait] Stopped
2022-04-20T20:52:16.987Z,1650487936.987 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T20:52:16.987Z,1650487936.987 [Default:CheckIn:D] Running Loop=1
2022-04-20T20:52:17.398Z,1650487937.398 [Default:CheckIn:D] Stopped
2022-04-20T20:52:17.398Z,1650487937.398 [Default:CheckIn:E] Running Loop=1
2022-04-20T20:52:17.806Z,1650487937.806 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.325730 min
2022-04-20T20:52:17.806Z,1650487937.806 [Default:CheckIn:E] Stopped
2022-04-20T20:52:17.806Z,1650487937.806 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T20:52:17.806Z,1650487937.806 [Default:CheckIn] Stopped
2022-04-20T20:52:17.806Z,1650487937.806 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T20:52:17.806Z,1650487937.806 [Default:CheckIn](INFO): Running loop #2
2022-04-20T20:52:17.806Z,1650487937.806 [Default:CheckIn] Running Loop=2
2022-04-20T20:52:17.806Z,1650487937.806 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T20:52:17.806Z,1650487937.806 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T20:55:15.150Z,1650488115.150 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-04-20T20:55:15.150Z,1650488115.150 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T20:55:15.180Z,1650488115.180 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T20:55:15.591Z,1650488115.591 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T20:55:15.591Z,1650488115.591 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-04-20T20:57:17.968Z,1650488237.968 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T20:52:17.8Z
2022-04-20T20:57:17.968Z,1650488237.968 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T20:57:17.968Z,1650488237.968 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T20:57:24.821Z,1650488244.821 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220420T203659/Courier0010.lzma
2022-04-20T20:57:25.823Z,1650488245.823 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0010.lzma.bak
2022-04-20T20:57:25.823Z,1650488245.823 [DataOverHttps](INFO): SBD MOMSN=16651279
2022-04-20T20:57:42.557Z,1650488262.557 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20220420T203659/Express0011.lzma
2022-04-20T20:57:43.559Z,1650488263.559 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0011.lzma.bak
2022-04-20T20:57:43.559Z,1650488263.559 [DataOverHttps](INFO): SBD MOMSN=16651281
2022-04-20T20:57:46.287Z,1650488266.287 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T20:57:46.287Z,1650488266.287 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T20:57:46.287Z,1650488266.287 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T20:58:16.143Z,1650488296.143 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-04-20T20:58:16.143Z,1650488296.143 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T20:58:16.153Z,1650488296.153 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T20:58:16.568Z,1650488296.568 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T20:58:16.568Z,1650488296.568 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-04-20T20:58:33.103Z,1650488313.103 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T20:58:33.103Z,1650488313.103 [NAL9602] Data Fault, FailCount= 2
2022-04-20T20:58:33.103Z,1650488313.103 [NAL9602](ERROR): Data Fault
2022-04-20T20:58:33.162Z,1650488313.162 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T20:58:33.507Z,1650488313.507 [NAL9602](INFO): Powering down
2022-04-20T20:58:34.352Z,1650488314.352 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T20:58:34.352Z,1650488314.352 [NAL9602] No Fault, FailCount= 2
2022-04-20T20:59:03.803Z,1650488343.803 [NAL9602](INFO): Powering up NAL9602
2022-04-20T20:59:14.719Z,1650488354.719 [NAL9602](INFO): NAL9602 initialized
2022-04-20T21:01:17.134Z,1650488477.134 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-04-20T21:01:17.134Z,1650488477.134 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:01:17.144Z,1650488477.144 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:01:17.572Z,1650488477.572 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:01:17.572Z,1650488477.572 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-04-20T21:02:46.839Z,1650488566.839 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T21:02:46.839Z,1650488566.839 [Default:CheckIn:C.Wait] Stopped
2022-04-20T21:02:46.839Z,1650488566.839 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T21:02:46.839Z,1650488566.839 [Default:CheckIn:D] Running Loop=1
2022-04-20T21:02:47.319Z,1650488567.319 [Default:CheckIn:D] Stopped
2022-04-20T21:02:47.320Z,1650488567.320 [Default:CheckIn:E] Running Loop=1
2022-04-20T21:02:47.634Z,1650488567.634 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.824418 min
2022-04-20T21:02:47.635Z,1650488567.635 [Default:CheckIn:E] Stopped
2022-04-20T21:02:47.635Z,1650488567.635 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T21:02:47.635Z,1650488567.635 [Default:CheckIn] Stopped
2022-04-20T21:02:47.635Z,1650488567.635 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T21:02:47.635Z,1650488567.635 [Default:CheckIn](INFO): Running loop #3
2022-04-20T21:02:47.635Z,1650488567.635 [Default:CheckIn] Running Loop=3
2022-04-20T21:02:47.635Z,1650488567.635 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T21:02:47.635Z,1650488567.635 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T21:04:18.137Z,1650488658.137 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-04-20T21:04:18.137Z,1650488658.137 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:04:18.147Z,1650488658.147 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:04:18.562Z,1650488658.562 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:04:18.562Z,1650488658.562 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-04-20T21:07:19.117Z,1650488839.117 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-04-20T21:07:19.118Z,1650488839.118 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:07:19.128Z,1650488839.128 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:07:19.548Z,1650488839.548 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:07:19.548Z,1650488839.548 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-04-20T21:07:47.809Z,1650488867.809 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T21:02:47.6Z
2022-04-20T21:07:47.809Z,1650488867.809 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T21:07:47.809Z,1650488867.809 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T21:07:54.708Z,1650488874.708 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220420T203659/Courier0013.lzma
2022-04-20T21:07:55.711Z,1650488875.711 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0013.lzma.bak
2022-04-20T21:07:55.711Z,1650488875.711 [DataOverHttps](INFO): SBD MOMSN=16651346
2022-04-20T21:08:12.381Z,1650488892.381 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20220420T203659/Express0014.lzma
2022-04-20T21:08:13.383Z,1650488893.383 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0014.lzma.bak
2022-04-20T21:08:13.383Z,1650488893.383 [DataOverHttps](INFO): SBD MOMSN=16651348
2022-04-20T21:08:16.118Z,1650488896.118 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T21:08:16.118Z,1650488896.118 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T21:08:16.118Z,1650488896.118 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T21:09:15.867Z,1650488955.867 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T21:09:15.867Z,1650488955.867 [NAL9602] Data Fault, FailCount= 3
2022-04-20T21:09:15.867Z,1650488955.867 [NAL9602](ERROR): Data Fault
2022-04-20T21:09:15.883Z,1650488955.883 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T21:09:16.267Z,1650488956.267 [NAL9602](INFO): Powering down
2022-04-20T21:09:17.104Z,1650488957.104 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T21:09:17.104Z,1650488957.104 [NAL9602] No Fault, FailCount= 3
2022-04-20T21:09:46.575Z,1650488986.575 [NAL9602](INFO): Powering up NAL9602
2022-04-20T21:09:57.484Z,1650488997.484 [NAL9602](INFO): NAL9602 initialized
2022-04-20T21:10:20.120Z,1650489020.120 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-04-20T21:10:20.120Z,1650489020.120 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:10:20.130Z,1650489020.130 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:10:20.539Z,1650489020.539 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:10:20.539Z,1650489020.539 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-04-20T21:13:16.687Z,1650489196.687 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T21:13:16.687Z,1650489196.687 [Default:CheckIn:C.Wait] Stopped
2022-04-20T21:13:16.687Z,1650489196.687 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T21:13:16.687Z,1650489196.687 [Default:CheckIn:D] Running Loop=1
2022-04-20T21:13:17.092Z,1650489197.092 [Default:CheckIn:D] Stopped
2022-04-20T21:13:17.092Z,1650489197.092 [Default:CheckIn:E] Running Loop=1
2022-04-20T21:13:17.463Z,1650489197.463 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.320630 min
2022-04-20T21:13:17.463Z,1650489197.463 [Default:CheckIn:E] Stopped
2022-04-20T21:13:17.463Z,1650489197.463 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T21:13:17.463Z,1650489197.463 [Default:CheckIn] Stopped
2022-04-20T21:13:17.463Z,1650489197.463 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T21:13:17.464Z,1650489197.464 [Default:CheckIn](INFO): Running loop #4
2022-04-20T21:13:17.464Z,1650489197.464 [Default:CheckIn] Running Loop=4
2022-04-20T21:13:17.464Z,1650489197.464 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T21:13:17.464Z,1650489197.464 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T21:13:21.110Z,1650489201.110 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2022-04-20T21:13:21.110Z,1650489201.110 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:13:21.120Z,1650489201.120 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:13:21.524Z,1650489201.524 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:13:21.524Z,1650489201.524 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2022-04-20T21:16:22.107Z,1650489382.107 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2022-04-20T21:16:22.107Z,1650489382.107 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:16:22.118Z,1650489382.118 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:16:22.529Z,1650489382.529 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:16:22.529Z,1650489382.529 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2022-04-20T21:18:17.639Z,1650489497.639 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T21:13:17.5Z
2022-04-20T21:18:17.639Z,1650489497.639 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T21:18:17.639Z,1650489497.639 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T21:18:24.788Z,1650489504.788 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220420T203659/Courier0016.lzma
2022-04-20T21:18:25.791Z,1650489505.791 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0016.lzma.bak
2022-04-20T21:18:25.791Z,1650489505.791 [DataOverHttps](INFO): SBD MOMSN=16651368
2022-04-20T21:18:42.377Z,1650489522.377 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20220420T203659/Express0017.lzma
2022-04-20T21:18:43.379Z,1650489523.379 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0017.lzma.bak
2022-04-20T21:18:43.379Z,1650489523.379 [DataOverHttps](INFO): SBD MOMSN=16651370
2022-04-20T21:18:45.932Z,1650489525.932 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T21:18:45.932Z,1650489525.932 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T21:18:45.933Z,1650489525.933 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T21:19:23.103Z,1650489563.103 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2022-04-20T21:19:23.104Z,1650489563.104 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:19:23.114Z,1650489563.114 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:19:23.516Z,1650489563.516 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:19:23.516Z,1650489563.516 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2022-04-20T21:19:58.631Z,1650489598.631 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T21:19:58.631Z,1650489598.631 [NAL9602] Data Fault, FailCount= 4
2022-04-20T21:19:58.631Z,1650489598.631 [NAL9602](ERROR): Data Fault
2022-04-20T21:19:58.652Z,1650489598.652 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T21:19:59.038Z,1650489599.038 [NAL9602](INFO): Powering down
2022-04-20T21:19:59.882Z,1650489599.882 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T21:19:59.882Z,1650489599.882 [NAL9602] No Fault, FailCount= 4
2022-04-20T21:20:29.335Z,1650489629.335 [NAL9602](INFO): Powering up NAL9602
2022-04-20T21:20:40.243Z,1650489640.243 [NAL9602](INFO): NAL9602 initialized
2022-04-20T21:22:12.759Z,1650489732.759 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2022-04-20T21:22:12.759Z,1650489732.759 [DropWeight] Hardware Fault, FailCount= 1
2022-04-20T21:22:12.759Z,1650489732.759 [DropWeight](ERROR): Hardware Fault
2022-04-20T21:22:12.859Z,1650489732.859 [CBIT](INFO): Critical error at 20220420T212212
2022-04-20T21:22:12.870Z,1650489732.870 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-04-20T21:22:12.870Z,1650489732.870 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-04-20T21:22:13.207Z,1650489733.207 [CBIT](INFO): Critical error at 20220420T212212
2022-04-20T21:22:24.113Z,1650489744.113 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2022-04-20T21:22:24.113Z,1650489744.113 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:22:24.124Z,1650489744.124 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:22:24.509Z,1650489744.509 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:22:24.509Z,1650489744.509 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2022-04-20T21:23:46.502Z,1650489826.502 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T21:23:46.502Z,1650489826.502 [Default:CheckIn:C.Wait] Stopped
2022-04-20T21:23:46.502Z,1650489826.502 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T21:23:46.502Z,1650489826.502 [Default:CheckIn:D] Running Loop=1
2022-04-20T21:23:46.924Z,1650489826.924 [Default:CheckIn:D] Stopped
2022-04-20T21:23:46.924Z,1650489826.924 [Default:CheckIn:E] Running Loop=1
2022-04-20T21:23:47.329Z,1650489827.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.817826 min
2022-04-20T21:23:47.329Z,1650489827.329 [Default:CheckIn:E] Stopped
2022-04-20T21:23:47.329Z,1650489827.329 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T21:23:47.329Z,1650489827.329 [Default:CheckIn] Stopped
2022-04-20T21:23:47.329Z,1650489827.329 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T21:23:47.329Z,1650489827.329 [Default:CheckIn](INFO): Running loop #5
2022-04-20T21:23:47.329Z,1650489827.329 [Default:CheckIn] Running Loop=5
2022-04-20T21:23:47.330Z,1650489827.330 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T21:23:47.330Z,1650489827.330 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T21:25:25.098Z,1650489925.098 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2022-04-20T21:25:25.098Z,1650489925.098 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:25:25.146Z,1650489925.146 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:25:25.523Z,1650489925.523 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:25:25.524Z,1650489925.524 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2022-04-20T21:28:26.080Z,1650490106.080 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2022-04-20T21:28:26.080Z,1650490106.080 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:28:26.103Z,1650490106.103 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:28:26.506Z,1650490106.506 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:28:26.506Z,1650490106.506 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2022-04-20T21:28:47.496Z,1650490127.496 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T21:23:47.3Z
2022-04-20T21:28:47.497Z,1650490127.497 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T21:28:47.497Z,1650490127.497 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T21:28:54.381Z,1650490134.381 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20220420T203659/Courier0019.lzma
2022-04-20T21:28:55.383Z,1650490135.383 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0019.lzma.bak
2022-04-20T21:28:55.383Z,1650490135.383 [DataOverHttps](INFO): SBD MOMSN=16651409
2022-04-20T21:29:12.117Z,1650490152.117 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20220420T203659/Express0020.lzma
2022-04-20T21:29:13.119Z,1650490153.119 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0020.lzma.bak
2022-04-20T21:29:13.119Z,1650490153.119 [DataOverHttps](INFO): SBD MOMSN=16651413
2022-04-20T21:29:15.803Z,1650490155.803 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T21:29:15.803Z,1650490155.803 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T21:29:15.803Z,1650490155.803 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T21:30:41.395Z,1650490241.395 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T21:30:41.395Z,1650490241.395 [NAL9602] Data Fault, FailCount= 5
2022-04-20T21:30:41.395Z,1650490241.395 [NAL9602](ERROR): Data Fault
2022-04-20T21:30:41.434Z,1650490241.434 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T21:30:41.434Z,1650490241.434 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-04-20T21:30:41.798Z,1650490241.798 [NAL9602](INFO): Powering down
2022-04-20T21:30:41.842Z,1650490241.842 [CBIT](INFO): Critical error at 20220420T213041
2022-04-20T21:31:27.061Z,1650490287.061 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2022-04-20T21:31:27.061Z,1650490287.061 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:31:27.071Z,1650490287.071 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:31:27.481Z,1650490287.481 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:31:27.481Z,1650490287.481 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2022-04-20T21:32:41.446Z,1650490361.446 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T21:32:41.446Z,1650490361.446 [NAL9602] No Fault, FailCount= 5
2022-04-20T21:32:41.827Z,1650490361.827 [NAL9602](INFO): Powering up NAL9602
2022-04-20T21:32:52.739Z,1650490372.739 [NAL9602](INFO): NAL9602 initialized
2022-04-20T21:34:16.388Z,1650490456.388 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T21:34:16.389Z,1650490456.389 [Default:CheckIn:C.Wait] Stopped
2022-04-20T21:34:16.389Z,1650490456.389 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T21:34:16.389Z,1650490456.389 [Default:CheckIn:D] Running Loop=1
2022-04-20T21:34:16.776Z,1650490456.776 [Default:CheckIn:D] Stopped
2022-04-20T21:34:16.776Z,1650490456.776 [Default:CheckIn:E] Running Loop=1
2022-04-20T21:34:17.185Z,1650490457.185 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.315365 min
2022-04-20T21:34:17.185Z,1650490457.185 [Default:CheckIn:E] Stopped
2022-04-20T21:34:17.185Z,1650490457.185 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T21:34:17.185Z,1650490457.185 [Default:CheckIn] Stopped
2022-04-20T21:34:17.185Z,1650490457.185 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T21:34:17.185Z,1650490457.185 [Default:CheckIn](INFO): Running loop #6
2022-04-20T21:34:17.186Z,1650490457.186 [Default:CheckIn] Running Loop=6
2022-04-20T21:34:17.186Z,1650490457.186 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T21:34:17.186Z,1650490457.186 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T21:34:28.093Z,1650490468.093 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2022-04-20T21:34:28.093Z,1650490468.093 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:34:28.103Z,1650490468.103 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:34:28.527Z,1650490468.527 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:34:28.527Z,1650490468.527 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2022-04-20T21:37:12.935Z,1650490632.935 [CBIT](INFO): Clearing failed state for component DropWeight
2022-04-20T21:37:12.935Z,1650490632.935 [DropWeight] No Fault, FailCount= 1
2022-04-20T21:37:29.094Z,1650490649.094 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2022-04-20T21:37:29.094Z,1650490649.094 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:37:29.104Z,1650490649.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:37:29.491Z,1650490649.491 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:37:29.491Z,1650490649.491 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2022-04-20T21:39:17.355Z,1650490757.355 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T21:34:17.2Z
2022-04-20T21:39:17.355Z,1650490757.355 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T21:39:17.355Z,1650490757.355 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T21:39:24.281Z,1650490764.281 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20220420T203659/Courier0022.lzma
2022-04-20T21:39:25.283Z,1650490765.283 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0022.lzma.bak
2022-04-20T21:39:25.283Z,1650490765.283 [DataOverHttps](INFO): SBD MOMSN=16651494
2022-04-20T21:39:42.389Z,1650490782.389 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20220420T203659/Express0023.lzma
2022-04-20T21:39:43.391Z,1650490783.391 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0023.lzma.bak
2022-04-20T21:39:43.391Z,1650490783.391 [DataOverHttps](INFO): SBD MOMSN=16651497
2022-04-20T21:39:46.075Z,1650490786.075 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T21:39:46.075Z,1650490786.075 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T21:39:46.075Z,1650490786.075 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T21:40:30.095Z,1650490830.095 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2022-04-20T21:40:30.095Z,1650490830.095 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:40:30.125Z,1650490830.125 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:40:30.494Z,1650490830.494 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:40:30.494Z,1650490830.494 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2022-04-20T21:42:54.331Z,1650490974.331 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T21:42:54.331Z,1650490974.331 [NAL9602] Data Fault, FailCount= 1
2022-04-20T21:42:54.331Z,1650490974.331 [NAL9602](ERROR): Data Fault
2022-04-20T21:42:54.355Z,1650490974.355 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T21:42:54.739Z,1650490974.739 [NAL9602](INFO): Powering down
2022-04-20T21:42:55.595Z,1650490975.595 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T21:42:55.595Z,1650490975.595 [NAL9602] No Fault, FailCount= 1
2022-04-20T21:43:25.043Z,1650491005.043 [NAL9602](INFO): Powering up NAL9602
2022-04-20T21:43:31.126Z,1650491011.126 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2022-04-20T21:43:31.126Z,1650491011.126 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:43:31.154Z,1650491011.154 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:43:31.536Z,1650491011.536 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:43:31.536Z,1650491011.536 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2022-04-20T21:43:35.947Z,1650491015.947 [NAL9602](INFO): NAL9602 initialized
2022-04-20T21:44:35.344Z,1650491075.344 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:44:46.652Z,1650491086.652 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T21:44:46.652Z,1650491086.652 [Default:CheckIn:C.Wait] Stopped
2022-04-20T21:44:46.652Z,1650491086.652 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T21:44:46.652Z,1650491086.652 [Default:CheckIn:D] Running Loop=1
2022-04-20T21:44:47.059Z,1650491087.059 [Default:CheckIn:D] Stopped
2022-04-20T21:44:47.059Z,1650491087.059 [Default:CheckIn:E] Running Loop=1
2022-04-20T21:44:47.484Z,1650491087.484 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.820081 min
2022-04-20T21:44:47.484Z,1650491087.484 [Default:CheckIn:E] Stopped
2022-04-20T21:44:47.485Z,1650491087.485 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T21:44:47.485Z,1650491087.485 [Default:CheckIn] Stopped
2022-04-20T21:44:47.485Z,1650491087.485 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T21:44:47.485Z,1650491087.485 [Default:CheckIn](INFO): Running loop #7
2022-04-20T21:44:47.485Z,1650491087.485 [Default:CheckIn] Running Loop=7
2022-04-20T21:44:47.485Z,1650491087.485 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T21:44:47.486Z,1650491087.486 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T21:44:48.686Z,1650491088.686 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:45:01.996Z,1650491101.996 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:45:15.327Z,1650491115.327 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:45:28.659Z,1650491128.659 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:45:42.395Z,1650491142.395 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:45:55.727Z,1650491155.727 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:46:09.465Z,1650491169.465 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:46:22.795Z,1650491182.795 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:46:32.101Z,1650491192.101 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2022-04-20T21:46:32.101Z,1650491192.101 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:46:32.111Z,1650491192.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:46:32.520Z,1650491192.520 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:46:32.520Z,1650491192.520 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2022-04-20T21:46:36.127Z,1650491196.127 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:46:49.459Z,1650491209.459 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:47:02.791Z,1650491222.791 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T21:47:05.221Z,1650491225.221 [BPC1](INFO): Calculating totals. Valid battery stick count: 28. Valid reserve battery stick count: 4.
2022-04-20T21:47:05.224Z,1650491225.224 [BPC1](INFO): Received data from all battery sticks.
2022-04-20T21:49:33.090Z,1650491373.090 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2022-04-20T21:49:33.090Z,1650491373.090 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:49:33.100Z,1650491373.100 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:49:33.520Z,1650491373.520 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:49:33.520Z,1650491373.520 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2022-04-20T21:49:47.632Z,1650491387.632 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T21:44:47.5Z
2022-04-20T21:49:47.632Z,1650491387.632 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T21:49:47.632Z,1650491387.632 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T21:49:54.562Z,1650491394.562 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20220420T203659/Courier0025.lzma
2022-04-20T21:49:55.555Z,1650491395.555 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0025.lzma.bak
2022-04-20T21:49:55.555Z,1650491395.555 [DataOverHttps](INFO): SBD MOMSN=16651514
2022-04-20T21:50:12.209Z,1650491412.209 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20220420T203659/Express0026.lzma
2022-04-20T21:50:13.211Z,1650491413.211 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0026.lzma.bak
2022-04-20T21:50:13.211Z,1650491413.211 [DataOverHttps](INFO): SBD MOMSN=16651516
2022-04-20T21:50:15.931Z,1650491415.931 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T21:50:15.931Z,1650491415.931 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T21:50:15.931Z,1650491415.931 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T21:52:34.086Z,1650491554.086 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2022-04-20T21:52:34.086Z,1650491554.086 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:52:34.096Z,1650491554.096 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:52:34.512Z,1650491554.512 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:52:34.512Z,1650491554.512 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2022-04-20T21:53:38.719Z,1650491618.719 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T21:53:38.719Z,1650491618.719 [NAL9602] Data Fault, FailCount= 2
2022-04-20T21:53:38.719Z,1650491618.719 [NAL9602](ERROR): Data Fault
2022-04-20T21:53:38.735Z,1650491618.735 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T21:53:39.127Z,1650491619.127 [NAL9602](INFO): Powering down
2022-04-20T21:53:39.963Z,1650491619.963 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T21:53:39.963Z,1650491619.963 [NAL9602] No Fault, FailCount= 2
2022-04-20T21:54:09.420Z,1650491649.420 [NAL9602](INFO): Powering up NAL9602
2022-04-20T21:54:20.327Z,1650491660.327 [NAL9602](INFO): NAL9602 initialized
2022-04-20T21:55:16.490Z,1650491716.490 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T21:55:16.491Z,1650491716.491 [Default:CheckIn:C.Wait] Stopped
2022-04-20T21:55:16.491Z,1650491716.491 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T21:55:16.491Z,1650491716.491 [Default:CheckIn:D] Running Loop=1
2022-04-20T21:55:16.896Z,1650491716.896 [Default:CheckIn:D] Stopped
2022-04-20T21:55:16.896Z,1650491716.896 [Default:CheckIn:E] Running Loop=1
2022-04-20T21:55:17.304Z,1650491717.304 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.317358 min
2022-04-20T21:55:17.304Z,1650491717.304 [Default:CheckIn:E] Stopped
2022-04-20T21:55:17.304Z,1650491717.304 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T21:55:17.304Z,1650491717.304 [Default:CheckIn] Stopped
2022-04-20T21:55:17.304Z,1650491717.304 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T21:55:17.305Z,1650491717.305 [Default:CheckIn](INFO): Running loop #8
2022-04-20T21:55:17.305Z,1650491717.305 [Default:CheckIn] Running Loop=8
2022-04-20T21:55:17.305Z,1650491717.305 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T21:55:17.305Z,1650491717.305 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T21:55:35.074Z,1650491735.074 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2022-04-20T21:55:35.074Z,1650491735.074 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:55:35.103Z,1650491735.103 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:55:35.483Z,1650491735.483 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:55:35.483Z,1650491735.483 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2022-04-20T21:58:36.066Z,1650491916.066 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27
2022-04-20T21:58:36.066Z,1650491916.066 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T21:58:36.076Z,1650491916.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T21:58:36.478Z,1650491916.478 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T21:58:36.479Z,1650491916.479 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27
2022-04-20T22:00:17.485Z,1650492017.485 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T21:55:17.3Z
2022-04-20T22:00:17.485Z,1650492017.485 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T22:00:17.485Z,1650492017.485 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T22:00:25.649Z,1650492025.649 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220420T203659/Courier0028.lzma
2022-04-20T22:00:26.653Z,1650492026.653 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0028.lzma.bak
2022-04-20T22:00:26.653Z,1650492026.653 [DataOverHttps](INFO): SBD MOMSN=16651526
2022-04-20T22:00:43.361Z,1650492043.361 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20220420T203659/Express0029.lzma
2022-04-20T22:00:44.363Z,1650492044.363 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0029.lzma.bak
2022-04-20T22:00:44.363Z,1650492044.363 [DataOverHttps](INFO): SBD MOMSN=16651528
2022-04-20T22:00:46.975Z,1650492046.975 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T22:00:46.975Z,1650492046.975 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T22:00:46.975Z,1650492046.975 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T22:01:37.063Z,1650492097.063 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28
2022-04-20T22:01:37.063Z,1650492097.063 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:01:37.074Z,1650492097.074 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:01:37.486Z,1650492097.486 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:01:37.486Z,1650492097.486 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28
2022-04-20T22:04:23.091Z,1650492263.091 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T22:04:23.091Z,1650492263.091 [NAL9602] Data Fault, FailCount= 3
2022-04-20T22:04:23.091Z,1650492263.091 [NAL9602](ERROR): Data Fault
2022-04-20T22:04:23.113Z,1650492263.113 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T22:04:23.508Z,1650492263.508 [NAL9602](INFO): Powering down
2022-04-20T22:04:24.322Z,1650492264.322 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T22:04:24.322Z,1650492264.322 [NAL9602] No Fault, FailCount= 3
2022-04-20T22:04:38.049Z,1650492278.049 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29
2022-04-20T22:04:38.049Z,1650492278.049 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:04:38.059Z,1650492278.059 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:04:38.460Z,1650492278.460 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:04:38.460Z,1650492278.460 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29
2022-04-20T22:04:53.800Z,1650492293.800 [NAL9602](INFO): Powering up NAL9602
2022-04-20T22:05:04.703Z,1650492304.703 [NAL9602](INFO): NAL9602 initialized
2022-04-20T22:05:47.566Z,1650492347.566 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T22:05:47.566Z,1650492347.566 [Default:CheckIn:C.Wait] Stopped
2022-04-20T22:05:47.566Z,1650492347.566 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T22:05:47.567Z,1650492347.567 [Default:CheckIn:D] Running Loop=1
2022-04-20T22:05:47.960Z,1650492347.960 [Default:CheckIn:D] Stopped
2022-04-20T22:05:47.960Z,1650492347.960 [Default:CheckIn:E] Running Loop=1
2022-04-20T22:05:48.372Z,1650492348.372 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.835091 min
2022-04-20T22:05:48.372Z,1650492348.372 [Default:CheckIn:E] Stopped
2022-04-20T22:05:48.373Z,1650492348.373 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T22:05:48.373Z,1650492348.373 [Default:CheckIn] Stopped
2022-04-20T22:05:48.373Z,1650492348.373 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T22:05:48.373Z,1650492348.373 [Default:CheckIn](INFO): Running loop #9
2022-04-20T22:05:48.373Z,1650492348.373 [Default:CheckIn] Running Loop=9
2022-04-20T22:05:48.373Z,1650492348.373 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T22:05:48.373Z,1650492348.373 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T22:07:14.396Z,1650492434.396 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2022-04-20T22:07:14.396Z,1650492434.396 [DropWeight] Hardware Fault, FailCount= 1
2022-04-20T22:07:14.396Z,1650492434.396 [DropWeight](ERROR): Hardware Fault
2022-04-20T22:07:14.431Z,1650492434.431 [CBIT](INFO): Critical error at 20220420T220714
2022-04-20T22:07:14.433Z,1650492434.433 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-04-20T22:07:14.433Z,1650492434.433 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-04-20T22:07:14.814Z,1650492434.814 [CBIT](INFO): Critical error at 20220420T220714
2022-04-20T22:07:39.041Z,1650492459.041 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 30
2022-04-20T22:07:39.041Z,1650492459.041 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:07:39.052Z,1650492459.052 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:07:39.460Z,1650492459.460 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:07:39.460Z,1650492459.460 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 30
2022-04-20T22:10:40.033Z,1650492640.033 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 31
2022-04-20T22:10:40.033Z,1650492640.033 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:10:40.044Z,1650492640.044 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:10:40.454Z,1650492640.454 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:10:40.454Z,1650492640.454 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 31
2022-04-20T22:10:48.523Z,1650492648.523 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T22:05:48.4Z
2022-04-20T22:10:48.523Z,1650492648.523 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T22:10:48.523Z,1650492648.523 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T22:10:55.405Z,1650492655.405 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20220420T203659/Courier0031.lzma
2022-04-20T22:10:56.407Z,1650492656.407 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0031.lzma.bak
2022-04-20T22:10:56.408Z,1650492656.408 [DataOverHttps](INFO): SBD MOMSN=16651572
2022-04-20T22:11:13.130Z,1650492673.130 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20220420T203659/Express0032.lzma
2022-04-20T22:11:14.015Z,1650492674.015 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0032.lzma.bak
2022-04-20T22:11:14.015Z,1650492674.015 [DataOverHttps](INFO): SBD MOMSN=16651580
2022-04-20T22:11:16.418Z,1650492676.418 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T22:11:16.418Z,1650492676.418 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T22:11:16.418Z,1650492676.418 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T22:13:41.043Z,1650492821.043 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 32
2022-04-20T22:13:41.044Z,1650492821.044 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:13:41.054Z,1650492821.054 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:13:41.455Z,1650492821.455 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:13:41.455Z,1650492821.455 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 32
2022-04-20T22:15:07.471Z,1650492907.471 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T22:15:07.471Z,1650492907.471 [NAL9602] Data Fault, FailCount= 4
2022-04-20T22:15:07.471Z,1650492907.471 [NAL9602](ERROR): Data Fault
2022-04-20T22:15:07.488Z,1650492907.488 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T22:15:07.879Z,1650492907.879 [NAL9602](INFO): Powering down
2022-04-20T22:15:08.699Z,1650492908.699 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T22:15:08.699Z,1650492908.699 [NAL9602] No Fault, FailCount= 4
2022-04-20T22:15:38.178Z,1650492938.178 [NAL9602](INFO): Powering up NAL9602
2022-04-20T22:15:49.087Z,1650492949.087 [NAL9602](INFO): NAL9602 initialized
2022-04-20T22:16:16.984Z,1650492976.984 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T22:16:16.984Z,1650492976.984 [Default:CheckIn:C.Wait] Stopped
2022-04-20T22:16:16.984Z,1650492976.984 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T22:16:16.984Z,1650492976.984 [Default:CheckIn:D] Running Loop=1
2022-04-20T22:16:17.396Z,1650492977.396 [Default:CheckIn:D] Stopped
2022-04-20T22:16:17.396Z,1650492977.396 [Default:CheckIn:E] Running Loop=1
2022-04-20T22:16:17.803Z,1650492977.803 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.325692 min
2022-04-20T22:16:17.803Z,1650492977.803 [Default:CheckIn:E] Stopped
2022-04-20T22:16:17.803Z,1650492977.803 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T22:16:17.803Z,1650492977.803 [Default:CheckIn] Stopped
2022-04-20T22:16:17.803Z,1650492977.803 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T22:16:17.803Z,1650492977.803 [Default:CheckIn](INFO): Running loop #10
2022-04-20T22:16:17.803Z,1650492977.803 [Default:CheckIn] Running Loop=10
2022-04-20T22:16:17.803Z,1650492977.803 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T22:16:17.804Z,1650492977.804 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T22:16:42.048Z,1650493002.048 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 33
2022-04-20T22:16:42.049Z,1650493002.049 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:16:42.059Z,1650493002.059 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:16:42.471Z,1650493002.471 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:16:42.471Z,1650493002.471 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 33
2022-04-20T22:19:43.057Z,1650493183.057 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 34
2022-04-20T22:19:43.057Z,1650493183.057 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:19:43.076Z,1650493183.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:19:43.429Z,1650493183.429 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:19:43.429Z,1650493183.429 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 34
2022-04-20T22:21:17.956Z,1650493277.956 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T22:16:17.8Z
2022-04-20T22:21:17.956Z,1650493277.956 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T22:21:17.956Z,1650493277.956 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T22:21:25.080Z,1650493285.080 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220420T203659/Courier0034.lzma
2022-04-20T22:21:26.085Z,1650493286.085 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0034.lzma.bak
2022-04-20T22:21:26.085Z,1650493286.085 [DataOverHttps](INFO): SBD MOMSN=16651614
2022-04-20T22:21:42.816Z,1650493302.816 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20220420T203659/Express0035.lzma
2022-04-20T22:21:43.815Z,1650493303.815 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0035.lzma.bak
2022-04-20T22:21:43.815Z,1650493303.815 [DataOverHttps](INFO): SBD MOMSN=16651616
2022-04-20T22:21:46.236Z,1650493306.236 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T22:21:46.236Z,1650493306.236 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T22:21:46.236Z,1650493306.236 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T22:22:14.586Z,1650493334.586 [CBIT](INFO): Clearing failed state for component DropWeight
2022-04-20T22:22:14.586Z,1650493334.586 [DropWeight] No Fault, FailCount= 1
2022-04-20T22:22:44.047Z,1650493364.047 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 35
2022-04-20T22:22:44.047Z,1650493364.047 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:22:44.057Z,1650493364.057 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:22:44.460Z,1650493364.460 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:22:44.460Z,1650493364.460 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 35
2022-04-20T22:25:45.055Z,1650493545.055 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 36
2022-04-20T22:25:45.055Z,1650493545.055 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:25:45.065Z,1650493545.065 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:25:45.474Z,1650493545.474 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:25:45.474Z,1650493545.474 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 36
2022-04-20T22:25:50.692Z,1650493550.692 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T22:25:50.692Z,1650493550.692 [NAL9602] Data Fault, FailCount= 5
2022-04-20T22:25:50.692Z,1650493550.692 [NAL9602](ERROR): Data Fault
2022-04-20T22:25:50.709Z,1650493550.709 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T22:25:50.737Z,1650493550.737 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-04-20T22:25:51.083Z,1650493551.083 [NAL9602](INFO): Powering down
2022-04-20T22:25:51.099Z,1650493551.099 [CBIT](INFO): Critical error at 20220420T222550
2022-04-20T22:26:46.843Z,1650493606.843 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T22:26:46.843Z,1650493606.843 [Default:CheckIn:C.Wait] Stopped
2022-04-20T22:26:46.843Z,1650493606.843 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T22:26:46.843Z,1650493606.843 [Default:CheckIn:D] Running Loop=1
2022-04-20T22:26:47.248Z,1650493607.248 [Default:CheckIn:D] Stopped
2022-04-20T22:26:47.248Z,1650493607.248 [Default:CheckIn:E] Running Loop=1
2022-04-20T22:26:47.650Z,1650493607.650 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.823226 min
2022-04-20T22:26:47.650Z,1650493607.650 [Default:CheckIn:E] Stopped
2022-04-20T22:26:47.651Z,1650493607.651 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T22:26:47.651Z,1650493607.651 [Default:CheckIn] Stopped
2022-04-20T22:26:47.651Z,1650493607.651 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T22:26:47.651Z,1650493607.651 [Default:CheckIn](INFO): Running loop #11
2022-04-20T22:26:47.651Z,1650493607.651 [Default:CheckIn] Running Loop=11
2022-04-20T22:26:47.651Z,1650493607.651 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T22:26:47.651Z,1650493607.651 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T22:27:51.087Z,1650493671.087 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T22:27:51.087Z,1650493671.087 [NAL9602] No Fault, FailCount= 5
2022-04-20T22:27:51.479Z,1650493671.479 [NAL9602](INFO): Powering up NAL9602
2022-04-20T22:28:02.391Z,1650493682.391 [NAL9602](INFO): NAL9602 initialized
2022-04-20T22:28:46.043Z,1650493726.043 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 37
2022-04-20T22:28:46.044Z,1650493726.044 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:28:46.054Z,1650493726.054 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:28:46.459Z,1650493726.459 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:28:46.459Z,1650493726.459 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 37
2022-04-20T22:31:47.035Z,1650493907.035 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 38
2022-04-20T22:31:47.035Z,1650493907.035 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:31:47.045Z,1650493907.045 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:31:47.461Z,1650493907.461 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:31:47.461Z,1650493907.461 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 38
2022-04-20T22:31:47.848Z,1650493907.848 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T22:26:47.7Z
2022-04-20T22:31:47.848Z,1650493907.848 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T22:31:47.848Z,1650493907.848 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T22:31:56.535Z,1650493916.535 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20220420T203659/Courier0037.lzma
2022-04-20T22:31:57.539Z,1650493917.539 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0037.lzma.bak
2022-04-20T22:31:57.539Z,1650493917.539 [DataOverHttps](INFO): SBD MOMSN=16651626
2022-04-20T22:32:19.013Z,1650493939.013 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20220420T203659/Express0038.lzma
2022-04-20T22:32:20.015Z,1650493940.015 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0038.lzma.bak
2022-04-20T22:32:20.015Z,1650493940.015 [DataOverHttps](INFO): SBD MOMSN=16651629
2022-04-20T22:32:22.798Z,1650493942.798 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T22:32:22.798Z,1650493942.798 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T22:32:22.798Z,1650493942.798 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T22:34:48.235Z,1650494088.235 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 39
2022-04-20T22:34:48.235Z,1650494088.235 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:34:48.246Z,1650494088.246 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:34:48.648Z,1650494088.648 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:34:48.649Z,1650494088.649 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 39
2022-04-20T22:37:23.360Z,1650494243.360 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T22:37:23.360Z,1650494243.360 [Default:CheckIn:C.Wait] Stopped
2022-04-20T22:37:23.361Z,1650494243.361 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T22:37:23.361Z,1650494243.361 [Default:CheckIn:D] Running Loop=1
2022-04-20T22:37:23.774Z,1650494243.774 [Default:CheckIn:D] Stopped
2022-04-20T22:37:23.774Z,1650494243.774 [Default:CheckIn:E] Running Loop=1
2022-04-20T22:37:24.172Z,1650494244.172 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.431991 min
2022-04-20T22:37:24.173Z,1650494244.173 [Default:CheckIn:E] Stopped
2022-04-20T22:37:24.173Z,1650494244.173 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T22:37:24.173Z,1650494244.173 [Default:CheckIn] Stopped
2022-04-20T22:37:24.173Z,1650494244.173 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T22:37:24.173Z,1650494244.173 [Default:CheckIn](INFO): Running loop #12
2022-04-20T22:37:24.173Z,1650494244.173 [Default:CheckIn] Running Loop=12
2022-04-20T22:37:24.173Z,1650494244.173 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T22:37:24.173Z,1650494244.173 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T22:37:49.227Z,1650494269.227 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 40
2022-04-20T22:37:49.227Z,1650494269.227 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:37:49.238Z,1650494269.238 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:37:49.646Z,1650494269.646 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:37:49.646Z,1650494269.646 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 40
2022-04-20T22:38:04.151Z,1650494284.151 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T22:38:04.151Z,1650494284.151 [NAL9602] Data Fault, FailCount= 1
2022-04-20T22:38:04.151Z,1650494284.151 [NAL9602](ERROR): Data Fault
2022-04-20T22:38:04.168Z,1650494284.168 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T22:38:04.559Z,1650494284.559 [NAL9602](INFO): Powering down
2022-04-20T22:38:05.374Z,1650494285.374 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T22:38:05.374Z,1650494285.374 [NAL9602] No Fault, FailCount= 1
2022-04-20T22:38:34.851Z,1650494314.851 [NAL9602](INFO): Powering up NAL9602
2022-04-20T22:38:45.768Z,1650494325.768 [NAL9602](INFO): NAL9602 initialized
2022-04-20T22:40:50.207Z,1650494450.207 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 41
2022-04-20T22:40:50.207Z,1650494450.207 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:40:50.238Z,1650494450.238 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:40:50.616Z,1650494450.616 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:40:50.616Z,1650494450.616 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 41
2022-04-20T22:42:24.378Z,1650494544.378 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T22:37:24.2Z
2022-04-20T22:42:24.378Z,1650494544.378 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T22:42:24.378Z,1650494544.378 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T22:42:31.268Z,1650494551.268 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220420T203659/Courier0040.lzma
2022-04-20T22:42:32.271Z,1650494552.271 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0040.lzma.bak
2022-04-20T22:42:32.271Z,1650494552.271 [DataOverHttps](INFO): SBD MOMSN=16651639
2022-04-20T22:42:47.905Z,1650494567.905 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20220420T203659/Express0041.lzma
2022-04-20T22:42:48.895Z,1650494568.895 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0041.lzma.bak
2022-04-20T22:42:48.895Z,1650494568.895 [DataOverHttps](INFO): SBD MOMSN=16651641
2022-04-20T22:42:51.565Z,1650494571.565 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T22:42:51.566Z,1650494571.566 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T22:42:51.566Z,1650494571.566 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T22:43:51.317Z,1650494631.317 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 42
2022-04-20T22:43:51.317Z,1650494631.317 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:43:51.328Z,1650494631.328 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:43:51.734Z,1650494631.734 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:43:51.734Z,1650494631.734 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 42
2022-04-20T22:46:52.314Z,1650494812.314 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 43
2022-04-20T22:46:52.314Z,1650494812.314 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:46:52.324Z,1650494812.324 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:46:52.728Z,1650494812.728 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:46:52.728Z,1650494812.728 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 43
2022-04-20T22:47:52.107Z,1650494872.107 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T22:47:52.107Z,1650494872.107 [Default:CheckIn:C.Wait] Stopped
2022-04-20T22:47:52.107Z,1650494872.107 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T22:47:52.107Z,1650494872.107 [Default:CheckIn:D] Running Loop=1
2022-04-20T22:47:52.517Z,1650494872.517 [Default:CheckIn:D] Stopped
2022-04-20T22:47:52.518Z,1650494872.518 [Default:CheckIn:E] Running Loop=1
2022-04-20T22:47:52.907Z,1650494872.907 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.911051 min
2022-04-20T22:47:52.907Z,1650494872.907 [Default:CheckIn:E] Stopped
2022-04-20T22:47:52.907Z,1650494872.907 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T22:47:52.907Z,1650494872.907 [Default:CheckIn] Stopped
2022-04-20T22:47:52.907Z,1650494872.907 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T22:47:52.907Z,1650494872.907 [Default:CheckIn](INFO): Running loop #13
2022-04-20T22:47:52.907Z,1650494872.907 [Default:CheckIn] Running Loop=13
2022-04-20T22:47:52.907Z,1650494872.907 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T22:47:52.908Z,1650494872.908 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T22:48:48.244Z,1650494928.244 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T22:48:48.244Z,1650494928.244 [NAL9602] Data Fault, FailCount= 2
2022-04-20T22:48:48.244Z,1650494928.244 [NAL9602](ERROR): Data Fault
2022-04-20T22:48:48.281Z,1650494928.281 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T22:48:48.651Z,1650494928.651 [NAL9602](INFO): Powering down
2022-04-20T22:48:49.479Z,1650494929.479 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T22:48:49.479Z,1650494929.479 [NAL9602] No Fault, FailCount= 2
2022-04-20T22:49:18.951Z,1650494958.951 [NAL9602](INFO): Powering up NAL9602
2022-04-20T22:49:29.859Z,1650494969.859 [NAL9602](INFO): NAL9602 initialized
2022-04-20T22:49:34.304Z,1650494974.304 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T22:49:47.631Z,1650494987.631 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T22:49:53.308Z,1650494993.308 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 44
2022-04-20T22:49:53.308Z,1650494993.308 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:49:53.318Z,1650494993.318 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:49:53.730Z,1650494993.730 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:49:53.730Z,1650494993.730 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 44
2022-04-20T22:50:00.963Z,1650495000.963 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T22:50:15.103Z,1650495015.103 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T22:50:28.839Z,1650495028.839 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T22:50:42.575Z,1650495042.575 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T22:50:55.915Z,1650495055.915 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T22:51:09.255Z,1650495069.255 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T22:51:23.047Z,1650495083.047 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T22:51:36.726Z,1650495096.726 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T22:51:50.058Z,1650495110.059 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-04-20T22:52:03.486Z,1650495123.486 [BPC1](INFO): Calculating totals. Valid battery stick count: 31. Valid reserve battery stick count: 4.
2022-04-20T22:52:03.488Z,1650495123.488 [BPC1](INFO): Received data from all battery sticks.
2022-04-20T22:52:15.939Z,1650495135.939 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2022-04-20T22:52:15.939Z,1650495135.939 [DropWeight] Hardware Fault, FailCount= 1
2022-04-20T22:52:15.939Z,1650495135.939 [DropWeight](ERROR): Hardware Fault
2022-04-20T22:52:15.955Z,1650495135.955 [CBIT](INFO): Critical error at 20220420T225215
2022-04-20T22:52:15.957Z,1650495135.957 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-04-20T22:52:15.958Z,1650495135.958 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-04-20T22:52:16.376Z,1650495136.376 [CBIT](INFO): Critical error at 20220420T225215
2022-04-20T22:52:53.119Z,1650495173.119 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-20T22:47:52.9Z
2022-04-20T22:52:53.119Z,1650495173.119 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T22:52:53.119Z,1650495173.119 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-20T22:52:54.367Z,1650495174.367 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 45
2022-04-20T22:52:54.367Z,1650495174.367 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:52:54.398Z,1650495174.398 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:52:54.757Z,1650495174.757 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:52:54.757Z,1650495174.757 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 45
2022-04-20T22:53:00.549Z,1650495180.549 [DataOverHttps](INFO): Sending 126 bytes from file Logs/20220420T203659/Courier0043.lzma
2022-04-20T22:53:01.551Z,1650495181.551 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0043.lzma.bak
2022-04-20T22:53:01.551Z,1650495181.551 [DataOverHttps](INFO): SBD MOMSN=16651653
2022-04-20T22:53:18.221Z,1650495198.221 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20220420T203659/Express0044.lzma
2022-04-20T22:53:19.223Z,1650495199.223 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0044.lzma.bak
2022-04-20T22:53:19.224Z,1650495199.224 [DataOverHttps](INFO): SBD MOMSN=16651657
2022-04-20T22:53:21.846Z,1650495201.846 [Default:CheckIn:Read_Iridium] Stopped
2022-04-20T22:53:21.846Z,1650495201.846 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-20T22:53:21.846Z,1650495201.846 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-20T22:55:55.350Z,1650495355.350 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 46
2022-04-20T22:55:55.350Z,1650495355.350 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:55:55.365Z,1650495355.365 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:55:55.765Z,1650495355.765 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:55:55.766Z,1650495355.766 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 46
2022-04-20T22:58:22.396Z,1650495502.396 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-04-20T22:58:22.396Z,1650495502.396 [Default:CheckIn:C.Wait] Stopped
2022-04-20T22:58:22.396Z,1650495502.396 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-20T22:58:22.396Z,1650495502.396 [Default:CheckIn:D] Running Loop=1
2022-04-20T22:58:22.779Z,1650495502.779 [Default:CheckIn:D] Stopped
2022-04-20T22:58:22.779Z,1650495502.779 [Default:CheckIn:E] Running Loop=1
2022-04-20T22:58:23.187Z,1650495503.187 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.415413 min
2022-04-20T22:58:23.187Z,1650495503.187 [Default:CheckIn:E] Stopped
2022-04-20T22:58:23.187Z,1650495503.187 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-04-20T22:58:23.187Z,1650495503.187 [Default:CheckIn] Stopped
2022-04-20T22:58:23.187Z,1650495503.187 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T22:58:23.188Z,1650495503.188 [Default:CheckIn](INFO): Running loop #14
2022-04-20T22:58:23.188Z,1650495503.188 [Default:CheckIn] Running Loop=14
2022-04-20T22:58:23.188Z,1650495503.188 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-20T22:58:23.188Z,1650495503.188 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-20T22:58:56.320Z,1650495536.320 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 47
2022-04-20T22:58:56.320Z,1650495536.320 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-20T22:58:56.347Z,1650495536.347 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-20T22:58:56.723Z,1650495536.723 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-20T22:58:56.723Z,1650495536.723 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 47
2022-04-20T22:59:31.447Z,1650495571.447 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-20T22:59:31.447Z,1650495571.447 [NAL9602] Data Fault, FailCount= 3
2022-04-20T22:59:31.447Z,1650495571.447 [NAL9602](ERROR): Data Fault
2022-04-20T22:59:31.465Z,1650495571.465 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-20T22:59:31.855Z,1650495571.855 [NAL9602](INFO): Powering down
2022-04-20T22:59:32.678Z,1650495572.678 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-20T22:59:32.678Z,1650495572.678 [NAL9602] No Fault, FailCount= 3
2022-04-20T23:00:02.154Z,1650495602.154 [NAL9602](INFO): Powering up NAL9602
2022-04-20T23:00:13.063Z,1650495613.063 [NAL9602](INFO): NAL9602 initialized
2022-04-20T23:01:48.042Z,1650495708.042 [CommandExec](IMPORTANT): got command quit
2022-04-20T23:01:49.053Z,1650495709.053 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-20T23:01:49.054Z,1650495709.054 [CommandExec](INFO): Uninitializing the command executive.
2022-04-20T23:01:49.054Z,1650495709.054 [CommandExec](INFO): Uninitializing the command scheduler.
2022-04-20T23:01:49.054Z,1650495709.054 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:49.077Z,1650495709.077 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2022-04-20T23:01:49.077Z,1650495709.077 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-04-20T23:01:49.078Z,1650495709.078 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:49.078Z,1650495709.078 [NavChartDb](INFO): Join timeout helper Thread ID is 1457
2022-04-20T23:01:49.341Z,1650495709.341 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-20T23:01:49.342Z,1650495709.342 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:49.365Z,1650495709.365 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-04-20T23:01:49.365Z,1650495709.365 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:49.366Z,1650495709.366 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1458
2022-04-20T23:01:49.589Z,1650495709.589 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-20T23:01:49.590Z,1650495709.590 [WetLabsBB2FL](INFO): Powering down
2022-04-20T23:01:49.590Z,1650495709.590 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:49.592Z,1650495709.592 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-04-20T23:01:49.592Z,1650495709.592 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:49.592Z,1650495709.592 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1459
2022-04-20T23:01:50.282Z,1650495710.282 [CTD_Seabird](INFO): Powering down
2022-04-20T23:01:50.293Z,1650495710.293 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-20T23:01:50.294Z,1650495710.294 [CTD_Seabird](INFO): Powering down
2022-04-20T23:01:50.306Z,1650495710.306 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:50.321Z,1650495710.321 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-04-20T23:01:50.321Z,1650495710.321 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:50.322Z,1650495710.322 [Radio_Surface](INFO): Join timeout helper Thread ID is 1460
2022-04-20T23:01:50.398Z,1650495710.398 [Radio_Surface](INFO): Powering down
2022-04-20T23:01:50.399Z,1650495710.399 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-20T23:01:50.399Z,1650495710.399 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:50.401Z,1650495710.401 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-04-20T23:01:50.402Z,1650495710.402 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:50.402Z,1650495710.402 [Onboard](INFO): Join timeout helper Thread ID is 1461
2022-04-20T23:01:52.414Z,1650495712.414 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-20T23:01:52.414Z,1650495712.414 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:52.434Z,1650495712.434 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-04-20T23:01:52.434Z,1650495712.434 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:52.435Z,1650495712.435 [DataOverHttps](INFO): Join timeout helper Thread ID is 1462
2022-04-20T23:01:53.006Z,1650495713.006 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-20T23:01:53.009Z,1650495713.009 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.026Z,1650495713.026 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-04-20T23:01:53.026Z,1650495713.026 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.027Z,1650495713.027 [BackseatComponent](INFO): Join timeout helper Thread ID is 1463
2022-04-20T23:01:53.126Z,1650495713.126 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-20T23:01:53.126Z,1650495713.126 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.134Z,1650495713.134 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-04-20T23:01:53.134Z,1650495713.134 [logger ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.135Z,1650495713.135 [logger](INFO): Join timeout helper Thread ID is 1464
2022-04-20T23:01:53.141Z,1650495713.141 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-20T23:01:53.142Z,1650495713.142 [logger ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.154Z,1650495713.154 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-04-20T23:01:53.154Z,1650495713.154 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.155Z,1650495713.155 [CommandLine](INFO): Join timeout helper Thread ID is 1465
2022-04-20T23:01:53.221Z,1650495713.221 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-20T23:01:53.222Z,1650495713.222 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.234Z,1650495713.234 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-04-20T23:01:53.234Z,1650495713.234 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.238Z,1650495713.238 [CommandExec](INFO): Join timeout helper Thread ID is 1466
2022-04-20T23:01:53.239Z,1650495713.239 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-04-20T23:01:53.239Z,1650495713.239 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.240Z,1650495713.240 [controlThread](INFO): Join timeout helper Thread ID is 1467
2022-04-20T23:01:53.242Z,1650495713.242 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-20T23:01:53.242Z,1650495713.242 [controlThread](DEBUG): Uninitializing ControlThread
2022-04-20T23:01:53.242Z,1650495713.242 [AHRS_M2](INFO): Powering down
2022-04-20T23:01:53.314Z,1650495713.314 [NAL9602](INFO): Powering down
2022-04-20T23:01:53.316Z,1650495713.316 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-04-20T23:01:53.317Z,1650495713.317 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-04-20T23:01:53.318Z,1650495713.318 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-04-20T23:01:53.318Z,1650495713.318 [MissionManager](INFO): Uninitializing Mission Default
2022-04-20T23:01:53.319Z,1650495713.319 [Default] Stopped
2022-04-20T23:01:53.319Z,1650495713.319 [Default](DEBUG): Aggregate::uninitialize Default
2022-04-20T23:01:53.319Z,1650495713.319 [Default:B.GoToSurface] Stopped
2022-04-20T23:01:53.319Z,1650495713.319 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-04-20T23:01:53.319Z,1650495713.319 [Default:CheckIn] Stopped
2022-04-20T23:01:53.319Z,1650495713.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-20T23:01:53.319Z,1650495713.319 [Default:CheckIn:Read_GPS] Stopped
2022-04-20T23:01:53.322Z,1650495713.322 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-04-20T23:01:53.322Z,1650495713.322 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-04-20T23:01:53.323Z,1650495713.323 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-04-20T23:01:53.323Z,1650495713.323 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-04-20T23:01:53.323Z,1650495713.323 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-04-20T23:01:53.323Z,1650495713.323 [BuoyancyServo](INFO): Powering down
2022-04-20T23:01:53.337Z,1650495713.337 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-04-20T23:01:53.338Z,1650495713.338 [ElevatorServo](INFO): Powering down
2022-04-20T23:01:53.338Z,1650495713.338 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-04-20T23:01:53.339Z,1650495713.339 [MassServo](INFO): Powering down
2022-04-20T23:01:53.339Z,1650495713.339 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-04-20T23:01:53.340Z,1650495713.340 [RudderServo](INFO): Powering down
2022-04-20T23:01:53.340Z,1650495713.340 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-04-20T23:01:53.340Z,1650495713.340 [ThrusterHE](INFO): Powering down
2022-04-20T23:01:53.342Z,1650495713.342 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-04-20T23:01:53.342Z,1650495713.342 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-04-20T23:01:53.342Z,1650495713.342 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-04-20T23:01:53.342Z,1650495713.342 [CBIT](DEBUG): Powering off loads.
2022-04-20T23:01:53.353Z,1650495713.353 [CBIT](DEBUG): Disabling WDT.
2022-04-20T23:01:53.365Z,1650495713.365 [CBIT](DEBUG): Opening all GF detection circuits.
2022-04-20T23:01:53.366Z,1650495713.366 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.430Z,1650495713.430 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.433Z,1650495713.433 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.447Z,1650495713.447 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.508Z,1650495713.508 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.565Z,1650495713.565 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.568Z,1650495713.568 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.617Z,1650495713.617 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-20T23:01:53.717Z,1650495713.717 [logger ThreadHandler](INFO): Thread cancelled.