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.