2023-02-10T17:43:17.886Z,1676050997.886 [Supervisor](DEBUG): Initializing supervisor.
2023-02-10T17:43:17.891Z,1676050997.891 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-02-10T17:43:17.891Z,1676050997.891 [SyncHandler](INFO): Protected caller Thread ID is 1188
2023-02-10T17:43:17.892Z,1676050997.892 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-02-10T17:43:17.892Z,1676050997.892 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-02-10T17:43:17.893Z,1676050997.893 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1189
2023-02-10T17:43:17.897Z,1676050997.897 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-02-10T17:43:17.914Z,1676050997.914 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-02-10T17:43:17.915Z,1676050997.915 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-02-10T17:43:17.915Z,1676050997.915 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1190
2023-02-10T17:43:17.920Z,1676050997.920 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-02-10T17:43:17.921Z,1676050997.921 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-02-10T17:43:17.921Z,1676050997.921 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1191
2023-02-10T17:43:17.923Z,1676050997.923 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-02-10T17:43:17.924Z,1676050997.924 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-02-10T17:43:17.924Z,1676050997.924 [logger ThreadHandler](INFO): Protected caller Thread ID is 1192
2023-02-10T17:43:17.928Z,1676050997.928 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-02-10T17:43:17.928Z,1676050997.928 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-02-10T17:43:17.930Z,1676050997.930 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-02-10T17:43:18.296Z,1676050998.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-02-10T17:43:18.297Z,1676050998.297 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-02-10T17:43:18.388Z,1676050998.388 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-02-10T17:43:18.839Z,1676050998.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-02-10T17:43:18.840Z,1676050998.840 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-02-10T17:43:19.146Z,1676050999.146 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-02-10T17:43:19.147Z,1676050999.147 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-02-10T17:43:19.241Z,1676050999.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-02-10T17:43:19.241Z,1676050999.241 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-02-10T17:43:19.612Z,1676050999.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-02-10T17:43:19.612Z,1676050999.612 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-02-10T17:43:19.811Z,1676050999.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-02-10T17:43:19.812Z,1676050999.812 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-02-10T17:43:20.298Z,1676051000.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-02-10T17:43:20.299Z,1676051000.299 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-02-10T17:43:20.432Z,1676051000.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-02-10T17:43:20.432Z,1676051000.432 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-02-10T17:43:20.509Z,1676051000.509 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-02-10T17:43:21.218Z,1676051001.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-02-10T17:43:21.219Z,1676051001.219 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-02-10T17:43:21.569Z,1676051001.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-02-10T17:43:21.569Z,1676051001.569 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-02-10T17:43:21.744Z,1676051001.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-02-10T17:43:21.745Z,1676051001.745 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-02-10T17:43:21.852Z,1676051001.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-02-10T17:43:21.853Z,1676051001.853 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-02-10T17:43:22.067Z,1676051002.067 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-02-10T17:43:22.067Z,1676051002.067 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-02-10T17:43:22.293Z,1676051002.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-02-10T17:43:22.295Z,1676051002.295 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-02-10T17:43:22.296Z,1676051002.296 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-02-10T17:43:22.384Z,1676051002.384 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-02-10T17:43:22.466Z,1676051002.466 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-02-10T17:43:22.563Z,1676051002.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-02-10T17:43:22.644Z,1676051002.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-02-10T17:43:22.737Z,1676051002.737 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-02-10T17:43:22.834Z,1676051002.834 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-02-10T17:43:23.005Z,1676051003.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-02-10T17:43:23.079Z,1676051003.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-02-10T17:43:23.218Z,1676051003.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-02-10T17:43:23.407Z,1676051003.407 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-02-10T17:43:23.767Z,1676051003.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-02-10T17:43:24.328Z,1676051004.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-02-10T17:43:24.329Z,1676051004.329 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-02-10T17:43:24.340Z,1676051004.340 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-02-10T17:43:24.390Z,1676051004.390 [VerticalControl](DEBUG): Construct VerticalControl.
2023-02-10T17:43:24.449Z,1676051004.449 [VerticalControl] Loaded
2023-02-10T17:43:24.449Z,1676051004.449 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-02-10T17:43:24.452Z,1676051004.452 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-02-10T17:43:24.493Z,1676051004.493 [HorizontalControl] Loaded
2023-02-10T17:43:24.493Z,1676051004.493 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-02-10T17:43:24.495Z,1676051004.495 [SpeedControl](DEBUG): Construct SpeedControl.
2023-02-10T17:43:24.498Z,1676051004.498 [SpeedControl] Loaded
2023-02-10T17:43:24.499Z,1676051004.499 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-02-10T17:43:24.501Z,1676051004.501 [LoopControl](DEBUG): Construct LoopControl.
2023-02-10T17:43:24.502Z,1676051004.502 [LoopControl] Loaded
2023-02-10T17:43:24.502Z,1676051004.502 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-02-10T17:43:24.502Z,1676051004.502 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-02-10T17:43:24.503Z,1676051004.503 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-02-10T17:43:24.513Z,1676051004.513 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-02-10T17:43:24.513Z,1676051004.513 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-02-10T17:43:24.663Z,1676051004.663 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-02-10T17:43:24.663Z,1676051004.663 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-02-10T17:43:24.817Z,1676051004.817 [BuoyancyServo] Loaded
2023-02-10T17:43:24.818Z,1676051004.818 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-02-10T17:43:24.838Z,1676051004.838 [ElevatorServo] Loaded
2023-02-10T17:43:24.838Z,1676051004.838 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-02-10T17:43:24.856Z,1676051004.856 [MassServo] Loaded
2023-02-10T17:43:24.857Z,1676051004.857 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-02-10T17:43:24.875Z,1676051004.875 [RudderServo] Loaded
2023-02-10T17:43:24.875Z,1676051004.875 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-02-10T17:43:24.892Z,1676051004.892 [ThrusterServo] Loaded
2023-02-10T17:43:24.892Z,1676051004.892 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-02-10T17:43:24.893Z,1676051004.893 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-02-10T17:43:24.893Z,1676051004.893 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-02-10T17:43:25.005Z,1676051005.005 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-02-10T17:43:25.006Z,1676051005.006 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-02-10T17:43:25.019Z,1676051005.019 [NavChart] Loaded
2023-02-10T17:43:25.020Z,1676051005.020 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-02-10T17:43:25.025Z,1676051005.025 [UniversalFixResidualReporter] Loaded
2023-02-10T17:43:25.026Z,1676051005.026 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-02-10T17:43:25.026Z,1676051005.026 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-02-10T17:43:25.027Z,1676051005.027 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-02-10T17:43:25.083Z,1676051005.083 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-02-10T17:43:25.083Z,1676051005.083 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-02-10T17:43:25.504Z,1676051005.504 [AHRS_M2] Loaded
2023-02-10T17:43:25.504Z,1676051005.504 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-02-10T17:43:26.315Z,1676051006.315 [BPC1] Loaded
2023-02-10T17:43:26.315Z,1676051006.315 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-02-10T17:43:26.387Z,1676051006.387 [DataOverHttps] Loaded
2023-02-10T17:43:26.387Z,1676051006.387 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-02-10T17:43:26.388Z,1676051006.388 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2023-02-10T17:43:26.389Z,1676051006.389 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1277
2023-02-10T17:43:26.409Z,1676051006.409 [Depth_Keller] Loaded
2023-02-10T17:43:26.410Z,1676051006.410 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-02-10T17:43:26.415Z,1676051006.415 [DropWeight] Loaded
2023-02-10T17:43:26.415Z,1676051006.415 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-02-10T17:43:26.433Z,1676051006.433 [MultiRay] Loaded
2023-02-10T17:43:26.433Z,1676051006.433 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2023-02-10T17:43:26.495Z,1676051006.495 [NAL9602] Loaded
2023-02-10T17:43:26.495Z,1676051006.495 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-02-10T17:43:26.524Z,1676051006.524 [Onboard] Loaded
2023-02-10T17:43:26.525Z,1676051006.525 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-02-10T17:43:26.526Z,1676051006.526 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4092D4E0
2023-02-10T17:43:26.526Z,1676051006.526 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1278
2023-02-10T17:43:26.539Z,1676051006.539 [Power24vConverter] Loaded
2023-02-10T17:43:26.540Z,1676051006.540 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-02-10T17:43:26.553Z,1676051006.553 [Radio_Surface] Loaded
2023-02-10T17:43:26.553Z,1676051006.553 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-02-10T17:43:26.554Z,1676051006.554 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095D4E0
2023-02-10T17:43:26.554Z,1676051006.554 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1279
2023-02-10T17:43:26.556Z,1676051006.556 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-02-10T17:43:26.556Z,1676051006.556 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-02-10T17:43:26.608Z,1676051006.608 [DepthRateCalculator] Loaded
2023-02-10T17:43:26.608Z,1676051006.608 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-02-10T17:43:26.613Z,1676051006.613 [PitchRateCalculator] Loaded
2023-02-10T17:43:26.613Z,1676051006.613 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-02-10T17:43:26.622Z,1676051006.622 [SpeedCalculator] Loaded
2023-02-10T17:43:26.622Z,1676051006.622 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-02-10T17:43:26.626Z,1676051006.626 [YawRateCalculator] Loaded
2023-02-10T17:43:26.627Z,1676051006.627 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-02-10T17:43:26.645Z,1676051006.645 [ElevatorOffsetCalculator] Loaded
2023-02-10T17:43:26.645Z,1676051006.645 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-02-10T17:43:26.646Z,1676051006.646 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-02-10T17:43:26.647Z,1676051006.647 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-02-10T17:43:26.777Z,1676051006.777 [CANONSampler] Loaded
2023-02-10T17:43:26.777Z,1676051006.777 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-02-10T17:43:26.836Z,1676051006.836 [CTD_Seabird] Loaded
2023-02-10T17:43:26.836Z,1676051006.836 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-02-10T17:43:26.838Z,1676051006.838 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2023-02-10T17:43:26.838Z,1676051006.838 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1280
2023-02-10T17:43:26.858Z,1676051006.858 [PAR_Licor] Loaded
2023-02-10T17:43:26.858Z,1676051006.858 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-02-10T17:43:26.889Z,1676051006.889 [WetLabsBB2FL] Loaded
2023-02-10T17:43:26.889Z,1676051006.889 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-02-10T17:43:26.890Z,1676051006.890 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2023-02-10T17:43:26.891Z,1676051006.891 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1281
2023-02-10T17:43:26.891Z,1676051006.891 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-02-10T17:43:26.892Z,1676051006.892 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-02-10T17:43:26.918Z,1676051006.918 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-02-10T17:43:26.919Z,1676051006.919 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-02-10T17:43:27.232Z,1676051007.232 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-02-10T17:43:27.232Z,1676051007.232 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-02-10T17:43:27.372Z,1676051007.372 [SBIT](DEBUG): Construct Startup Built In Test.
2023-02-10T17:43:27.381Z,1676051007.381 [SBIT] Loaded
2023-02-10T17:43:27.382Z,1676051007.382 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-02-10T17:43:27.384Z,1676051007.384 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-02-10T17:43:27.397Z,1676051007.397 [IBIT] Loaded
2023-02-10T17:43:27.397Z,1676051007.397 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-02-10T17:43:27.403Z,1676051007.403 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-02-10T17:43:27.508Z,1676051007.508 [CBIT] Loaded
2023-02-10T17:43:27.508Z,1676051007.508 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-02-10T17:43:27.509Z,1676051007.509 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-02-10T17:43:27.509Z,1676051007.509 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-02-10T17:43:27.609Z,1676051007.609 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-02-10T17:43:27.616Z,1676051007.616 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-02-10T17:43:27.619Z,1676051007.619 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-02-10T17:43:27.630Z,1676051007.630 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-02-10T17:43:27.631Z,1676051007.631 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B7E4E0
2023-02-10T17:43:27.631Z,1676051007.631 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1282
2023-02-10T17:43:27.636Z,1676051007.636 [Supervisor](INFO): Main Thread ID is 1036
2023-02-10T17:43:27.636Z,1676051007.636 [Supervisor](DEBUG): Running supervisor.
2023-02-10T17:43:27.636Z,1676051007.636 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1283
2023-02-10T17:43:27.637Z,1676051007.637 [CommandExec](INFO): Initializing the command executive.
2023-02-10T17:43:27.639Z,1676051007.639 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1284
2023-02-10T17:43:27.641Z,1676051007.641 [controlThread ThreadHandler](INFO): Handler Thread ID is 1285
2023-02-10T17:43:27.641Z,1676051007.641 [controlThread](DEBUG): Initializing ControlThread
2023-02-10T17:43:27.642Z,1676051007.642 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-02-10T17:43:27.644Z,1676051007.644 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-02-10T17:43:27.644Z,1676051007.644 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-02-10T17:43:27.645Z,1676051007.645 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-02-10T17:43:27.647Z,1676051007.647 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-02-10T17:43:27.647Z,1676051007.647 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-02-10T17:43:27.652Z,1676051007.652 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-02-10T17:43:27.653Z,1676051007.653 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-02-10T17:43:27.653Z,1676051007.653 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-02-10T17:43:27.653Z,1676051007.653 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-02-10T17:43:27.654Z,1676051007.654 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-02-10T17:43:27.658Z,1676051007.658 [SBIT](INFO): Initialize SBIT Component.
2023-02-10T17:43:27.659Z,1676051007.659 [SBIT](IMPORTANT): git: 2023-02-10
2023-02-10T17:43:27.659Z,1676051007.659 [SBIT](INFO): git hash: c406eb0a7e3d06ad2f4805df3abb847da8df30bc
2023-02-10T17:43:27.659Z,1676051007.659 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-02-10T17:43:27.660Z,1676051007.660 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-02-10T17:43:27.661Z,1676051007.661 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2023-02-10T17:43:27.662Z,1676051007.662 [IBIT](INFO): Initialize IBIT Component.
2023-02-10T17:43:27.663Z,1676051007.663 [CBIT](DEBUG): Initialize CBIT Component.
2023-02-10T17:43:27.664Z,1676051007.664 [logger ThreadHandler](INFO): Handler Thread ID is 1286
2023-02-10T17:43:27.674Z,1676051007.674 [CBIT](DEBUG): Initialized mux pins.
2023-02-10T17:43:27.674Z,1676051007.674 [CBIT](DEBUG): Initializing the watchdog timer.
2023-02-10T17:43:27.682Z,1676051007.682 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1287
2023-02-10T17:43:27.683Z,1676051007.683 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-02-10T17:43:27.694Z,1676051007.694 [Onboard ThreadHandler](INFO): Handler Thread ID is 1288
2023-02-10T17:43:27.699Z,1676051007.699 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-02-10T17:43:27.699Z,1676051007.699 [CBIT](DEBUG): Initializing heartbeat.
2023-02-10T17:43:27.715Z,1676051007.715 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1289
2023-02-10T17:43:27.734Z,1676051007.734 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1290
2023-02-10T17:43:27.735Z,1676051007.735 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-02-10T17:43:27.740Z,1676051007.740 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1292
2023-02-10T17:43:27.742Z,1676051007.742 [WetLabsBB2FL](INFO): Powering up
2023-02-10T17:43:27.744Z,1676051007.744 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1294
2023-02-10T17:43:27.746Z,1676051007.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-02-10T17:43:27.746Z,1676051007.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-02-10T17:43:27.747Z,1676051007.747 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-02-10T17:43:27.747Z,1676051007.747 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-02-10T17:43:27.747Z,1676051007.747 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-02-10T17:43:27.747Z,1676051007.747 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-02-10T17:43:27.747Z,1676051007.747 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-02-10T17:43:27.747Z,1676051007.747 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-02-10T17:43:27.770Z,1676051007.770 [CBIT](DEBUG): Deactivating GF circuits.
2023-02-10T17:43:27.770Z,1676051007.770 [CBIT](DEBUG): Deactivating emergency mode.
2023-02-10T17:43:27.806Z,1676051007.806 [CBIT](DEBUG): Backplane powered.
2023-02-10T17:43:27.807Z,1676051007.807 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-02-10T17:43:27.819Z,1676051007.819 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-02-10T17:43:27.843Z,1676051007.843 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-02-10T17:43:27.844Z,1676051007.844 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-02-10T17:43:27.917Z,1676051007.917 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-02-10T17:43:27.918Z,1676051007.918 [Default:A.Wait](DEBUG): Construct Wait.
2023-02-10T17:43:27.920Z,1676051007.920 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-02-10T17:43:27.964Z,1676051007.964 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-02-10T17:43:27.967Z,1676051007.967 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-02-10T17:43:27.989Z,1676051007.989 [Default:E.Execute](DEBUG): Construct Execute.
2023-02-10T17:43:27.992Z,1676051007.992 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-02-10T17:43:28.010Z,1676051008.010 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-02-10T17:43:28.022Z,1676051008.022 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-02-10T17:43:28.096Z,1676051008.096 [MultiRay](INFO): Powering up MultiRay Lights
2023-02-10T17:43:28.118Z,1676051008.118 [Radio_Surface](INFO): Powering up
2023-02-10T17:43:28.135Z,1676051008.135 [Power24vConverter](INFO): Powering up.
2023-02-10T17:43:28.135Z,1676051008.135 [CANONSampler](INFO): Powering down
2023-02-10T17:43:28.180Z,1676051008.180 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-02-10T17:43:28.186Z,1676051008.186 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-02-10T17:43:28.187Z,1676051008.187 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-02-10T17:43:28.195Z,1676051008.195 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-02-10T17:43:28.196Z,1676051008.196 [MassServo](DEBUG): Initializing EZServoServo.
2023-02-10T17:43:28.202Z,1676051008.202 [MassServo](DEBUG): Initializing MassServo.
2023-02-10T17:43:28.203Z,1676051008.203 [RudderServo](DEBUG): Initializing EZServoServo.
2023-02-10T17:43:28.210Z,1676051008.210 [RudderServo](DEBUG): Initializing RudderServo.
2023-02-10T17:43:28.211Z,1676051008.211 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-02-10T17:43:28.218Z,1676051008.218 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-02-10T17:43:28.455Z,1676051008.455 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-02-10T17:43:28.455Z,1676051008.455 [DropWeight] Hardware Fault, FailCount= 1
2023-02-10T17:43:28.455Z,1676051008.455 [DropWeight](ERROR): Hardware Fault
2023-02-10T17:43:28.508Z,1676051008.508 [CommandExec](FAULT): Scheduling is paused
2023-02-10T17:43:28.509Z,1676051008.509 [CBIT](INFO): Critical error at 20230210T174328
2023-02-10T17:43:28.509Z,1676051008.509 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-02-10T17:43:28.519Z,1676051008.519 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-02-10T17:43:28.520Z,1676051008.520 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-02-10T17:43:29.162Z,1676051009.162 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-02-10T17:43:29.162Z,1676051009.162 [RudderServo](FAULT): Rudder failed to initialize
2023-02-10T17:43:29.162Z,1676051009.162 [RudderServo] Communications Fault, FailCount= 1
2023-02-10T17:43:29.162Z,1676051009.162 [RudderServo](ERROR): Communications Fault
2023-02-10T17:43:29.260Z,1676051009.260 [CBIT](INFO): Critical error at 20230210T174328
2023-02-10T17:43:29.263Z,1676051009.263 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-02-10T17:43:29.671Z,1676051009.671 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-02-10T17:43:29.671Z,1676051009.671 [RudderServo](INFO): Powering down
2023-02-10T17:43:30.114Z,1676051010.114 [RudderServo](DEBUG): Initializing EZServoServo.
2023-02-10T17:43:30.235Z,1676051010.235 [RudderServo](DEBUG): Initializing RudderServo.
2023-02-10T17:43:30.239Z,1676051010.239 [CBIT](INFO): Clearing failed state for component RudderServo
2023-02-10T17:43:30.239Z,1676051010.239 [RudderServo] No Fault, FailCount= 1
2023-02-10T17:43:30.354Z,1676051010.354 [WetLabsBB2FL](INFO): Powering down
2023-02-10T17:43:56.764Z,1676051036.764 [NAL9602](INFO): Powering up NAL9602
2023-02-10T17:44:07.672Z,1676051047.672 [NAL9602](INFO): NAL9602 initialized
2023-02-10T17:44:20.222Z,1676051060.222 [SBIT](IMPORTANT): Beginning Startup BIT
2023-02-10T17:44:20.231Z,1676051060.231 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-10T17:44:31.132Z,1676051071.132 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-02-10T17:44:31.546Z,1676051071.546 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-10T17:44:42.547Z,1676051082.547 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-02-10T17:44:42.943Z,1676051082.943 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-10T17:44:53.891Z,1676051093.891 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-02-10T17:44:53.891Z,1676051093.891 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-02-10T17:45:14.384Z,1676051114.384 [SBIT](IMPORTANT): SBIT PASSED
2023-02-10T17:45:14.385Z,1676051114.385 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-02-10T17:45:14.385Z,1676051114.385 [SBIT](IMPORTANT): AHRS_M2.readAccelerations=0 bool;

2023-02-10T17:45:14.386Z,1676051114.386 [SBIT](IMPORTANT): AHRS_M2.readAngularVelocities=0 bool;

2023-02-10T17:45:14.386Z,1676051114.386 [SBIT](IMPORTANT): AHRS_M2.readMagnetics=0 bool;

2023-02-10T17:45:14.386Z,1676051114.386 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-02-10T17:45:14.386Z,1676051114.386 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count;

2023-02-10T17:45:14.386Z,1676051114.386 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-02-10T17:45:14.386Z,1676051114.386 [SBIT](IMPORTANT): CANONSampler.sampleTimeout=10 minute;

2023-02-10T17:45:14.386Z,1676051114.386 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-02-10T17:45:14.386Z,1676051114.386 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2023-02-10T17:45:14.387Z,1676051114.387 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2023-02-10T17:45:14.387Z,1676051114.387 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=169.635298 cubic_centimeter;

2023-02-10T17:45:14.387Z,1676051114.387 [SBIT](IMPORTANT): VerticalControl.massDefault=-8.141334 millimeter;

2023-02-10T17:45:14.772Z,1676051114.772 [MissionManager](IMPORTANT): Started mission Startup
2023-02-10T17:45:14.773Z,1676051114.773 [Startup] Running Loop=1
2023-02-10T17:45:14.773Z,1676051114.773 [Startup](DEBUG): Aggregate::initialize Startup
2023-02-10T17:45:14.773Z,1676051114.773 [Startup:A.GoToSurface] Running Loop=1
2023-02-10T17:45:14.773Z,1676051114.773 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-02-10T17:45:14.773Z,1676051114.773 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-02-10T17:45:14.790Z,1676051114.790 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-02-10T17:45:14.790Z,1676051114.790 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-02-10T17:45:14.791Z,1676051114.791 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-02-10T17:45:14.791Z,1676051114.791 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-02-10T17:45:14.793Z,1676051114.793 [Startup:StartupSatComms] Running Loop=1
2023-02-10T17:45:14.793Z,1676051114.793 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-02-10T17:45:14.793Z,1676051114.793 [Startup:StartupSatComms:A] Running Loop=1
2023-02-10T17:45:15.165Z,1676051115.165 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-02-10T17:46:10.601Z,1676051170.601 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005464
2023-02-10T17:46:14.947Z,1676051174.947 [Startup:StartupSatComms:A](INFO): Timed out from 2023-02-10T17:45:14.8Z
2023-02-10T17:46:14.947Z,1676051174.947 [Startup:StartupSatComms:A] Stopped
2023-02-10T17:46:14.947Z,1676051174.947 [Startup:StartupSatComms:B] Running Loop=1
2023-02-10T17:46:15.373Z,1676051175.373 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-02-10T17:46:21.966Z,1676051181.966 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230210T173255/Courier0007.lzma
2023-02-10T17:46:22.968Z,1676051182.968 [DataOverHttps](INFO): Moved sent file to Logs/20230210T173255/Courier0007.lzma.bak
2023-02-10T17:46:22.968Z,1676051182.968 [DataOverHttps](INFO): SBD MOMSN=17460892
2023-02-10T17:46:27.904Z,1676051187.904 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-02-10T17:46:27.904Z,1676051187.904 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-10T17:46:27.947Z,1676051187.947 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-10T17:46:28.311Z,1676051188.311 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-10T17:46:28.311Z,1676051188.311 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-02-10T17:46:38.666Z,1676051198.666 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20230210T174317/Courier0000.lzma
2023-02-10T17:46:39.668Z,1676051199.668 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0000.lzma.bak
2023-02-10T17:46:39.668Z,1676051199.668 [DataOverHttps](INFO): SBD MOMSN=17460894
2023-02-10T17:46:55.742Z,1676051215.742 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20230210T173255/Express0005.lzma
2023-02-10T17:46:56.744Z,1676051216.744 [DataOverHttps](INFO): Moved sent file to Logs/20230210T173255/Express0005.lzma.bak
2023-02-10T17:46:56.744Z,1676051216.744 [DataOverHttps](INFO): SBD MOMSN=17460899
2023-02-10T17:47:12.217Z,1676051232.217 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20230210T173255/Express0008.lzma
2023-02-10T17:47:13.220Z,1676051233.220 [DataOverHttps](INFO): Moved sent file to Logs/20230210T173255/Express0008.lzma.bak
2023-02-10T17:47:13.220Z,1676051233.220 [DataOverHttps](INFO): SBD MOMSN=17460906
2023-02-10T17:47:15.154Z,1676051235.154 [Startup:StartupSatComms:B](INFO): Timed out from 2023-02-10T17:46:14.9Z
2023-02-10T17:47:15.154Z,1676051235.154 [Startup:StartupSatComms:B] Stopped
2023-02-10T17:47:15.154Z,1676051235.154 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-02-10T17:47:15.154Z,1676051235.154 [Startup:StartupSatComms] Stopped
2023-02-10T17:47:15.155Z,1676051235.155 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-02-10T17:47:15.155Z,1676051235.155 [Startup](INFO): Completed Startup
2023-02-10T17:47:15.155Z,1676051235.155 [MissionManager](INFO): Startup is completed.
2023-02-10T17:47:15.156Z,1676051235.156 [MissionManager](INFO): Uninitializing Mission Startup
2023-02-10T17:47:15.156Z,1676051235.156 [Startup] Stopped
2023-02-10T17:47:15.156Z,1676051235.156 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-02-10T17:47:15.156Z,1676051235.156 [Startup:A.GoToSurface] Stopped
2023-02-10T17:47:15.156Z,1676051235.156 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-02-10T17:47:15.563Z,1676051235.563 [MissionManager](IMPORTANT): Started mission Default
2023-02-10T17:47:15.563Z,1676051235.563 [Default] Running Loop=1
2023-02-10T17:47:15.563Z,1676051235.563 [Default](DEBUG): Aggregate::initialize Default
2023-02-10T17:47:15.563Z,1676051235.563 [Default:B.GoToSurface] Running Loop=1
2023-02-10T17:47:15.563Z,1676051235.563 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-02-10T17:47:15.564Z,1676051235.564 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-02-10T17:47:15.564Z,1676051235.564 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-02-10T17:47:15.564Z,1676051235.564 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-02-10T17:47:15.564Z,1676051235.564 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-02-10T17:47:15.565Z,1676051235.565 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-02-10T17:47:15.565Z,1676051235.565 [Default:A.Wait] Running Loop=1
2023-02-10T17:47:15.565Z,1676051235.565 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-02-10T17:47:28.916Z,1676051248.916 [Default:A.Wait](INFO): Done Waiting.
2023-02-10T17:47:28.916Z,1676051248.916 [Default:A.Wait] Stopped
2023-02-10T17:47:28.916Z,1676051248.916 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T17:47:29.305Z,1676051249.305 [Default:CheckIn] Running Loop=1
2023-02-10T17:47:29.305Z,1676051249.305 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T17:47:29.305Z,1676051249.305 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T17:47:29.713Z,1676051249.713 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-02-10T17:47:51.009Z,1676051271.009 [DataOverHttps](INFO): Sending 48 bytes from file Logs/20230210T173255/Express0011.lzma
2023-02-10T17:47:52.012Z,1676051272.012 [DataOverHttps](INFO): Moved sent file to Logs/20230210T173255/Express0011.lzma.bak
2023-02-10T17:47:52.012Z,1676051272.012 [DataOverHttps](INFO): SBD MOMSN=17460917
2023-02-10T17:48:25.023Z,1676051305.023 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-02-10T17:48:27.851Z,1676051307.851 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T17:48:29.466Z,1676051309.466 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-02-10T17:48:42.798Z,1676051322.798 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T17:48:58.154Z,1676051338.154 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T17:49:11.088Z,1676051351.088 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T17:49:13.502Z,1676051353.502 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T17:49:28.854Z,1676051368.854 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T17:49:28.873Z,1676051368.873 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-02-10T17:49:28.874Z,1676051368.874 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-10T17:49:28.916Z,1676051368.916 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-10T17:49:29.325Z,1676051369.325 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-10T17:49:29.325Z,1676051369.325 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-02-10T17:49:43.803Z,1676051383.803 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T17:49:58.346Z,1676051398.346 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T17:50:13.310Z,1676051413.310 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T17:50:29.067Z,1676051429.067 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T17:50:53.718Z,1676051453.718 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2023-02-10T17:50:53.723Z,1676051453.723 [BPC1](INFO): Received data from all battery sticks.
2023-02-10T17:52:29.476Z,1676051549.476 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T17:47:29.3Z
2023-02-10T17:52:29.476Z,1676051549.476 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T17:52:29.476Z,1676051549.476 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T17:52:29.884Z,1676051549.884 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-02-10T17:52:29.884Z,1676051549.884 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-10T17:52:29.886Z,1676051549.886 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-02-10T17:52:29.895Z,1676051549.895 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-10T17:52:30.365Z,1676051550.365 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-10T17:52:30.365Z,1676051550.365 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-02-10T17:52:36.690Z,1676051556.690 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230210T174317/Courier0004.lzma
2023-02-10T17:52:37.692Z,1676051557.692 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0004.lzma.bak
2023-02-10T17:52:37.693Z,1676051557.693 [DataOverHttps](INFO): SBD MOMSN=17460919
2023-02-10T17:52:53.107Z,1676051573.107 [DataOverHttps](INFO): Sending 838 bytes from file Logs/20230210T174317/Express0001.lzma
2023-02-10T17:52:54.108Z,1676051574.108 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0001.lzma.bak
2023-02-10T17:52:54.108Z,1676051574.108 [DataOverHttps](INFO): SBD MOMSN=17460921
2023-02-10T17:53:09.609Z,1676051589.609 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20230210T174317/Express0005.lzma
2023-02-10T17:53:10.612Z,1676051590.612 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0005.lzma.bak
2023-02-10T17:53:10.612Z,1676051590.612 [DataOverHttps](INFO): SBD MOMSN=17460948
2023-02-10T17:53:11.970Z,1676051591.970 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T17:53:11.970Z,1676051591.970 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T17:53:11.970Z,1676051591.970 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T17:53:28.922Z,1676051608.922 [Power24vConverter](INFO): Powering down.
2023-02-10T17:54:10.519Z,1676051650.519 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-10T17:54:10.519Z,1676051650.519 [NAL9602] Data Fault, FailCount= 1
2023-02-10T17:54:10.520Z,1676051650.520 [NAL9602](ERROR): Data Fault
2023-02-10T17:54:10.545Z,1676051650.545 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-10T17:54:10.929Z,1676051650.929 [NAL9602](INFO): Powering down
2023-02-10T17:54:11.752Z,1676051651.752 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-10T17:54:11.752Z,1676051651.752 [NAL9602] No Fault, FailCount= 1
2023-02-10T17:54:41.223Z,1676051681.223 [NAL9602](INFO): Powering up NAL9602
2023-02-10T17:54:52.141Z,1676051692.141 [NAL9602](INFO): NAL9602 initialized
2023-02-10T17:55:30.945Z,1676051730.945 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-02-10T17:55:30.945Z,1676051730.945 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-10T17:55:30.956Z,1676051730.956 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-10T17:55:31.336Z,1676051731.336 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-10T17:55:31.336Z,1676051731.336 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-02-10T17:58:12.532Z,1676051892.532 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T17:58:12.532Z,1676051892.532 [Default:CheckIn:C.Wait] Stopped
2023-02-10T17:58:12.532Z,1676051892.532 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T17:58:12.533Z,1676051892.533 [Default:CheckIn:D] Running Loop=1
2023-02-10T17:58:12.936Z,1676051892.936 [Default:CheckIn:D] Stopped
2023-02-10T17:58:12.936Z,1676051892.936 [Default:CheckIn:E] Running Loop=1
2023-02-10T17:58:13.357Z,1676051893.357 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.956222 min 
2023-02-10T17:58:13.357Z,1676051893.357 [Default:CheckIn:E] Stopped
2023-02-10T17:58:13.357Z,1676051893.357 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T17:58:13.357Z,1676051893.358 [Default:CheckIn] Stopped
2023-02-10T17:58:13.358Z,1676051893.358 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T17:58:13.358Z,1676051893.358 [Default:CheckIn](INFO): Running loop #2
2023-02-10T17:58:13.358Z,1676051893.358 [Default:CheckIn] Running Loop=2
2023-02-10T17:58:13.358Z,1676051893.358 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T17:58:13.358Z,1676051893.358 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T17:58:28.721Z,1676051908.721 [CBIT](INFO): Clearing failed state for component DropWeight
2023-02-10T17:58:28.722Z,1676051908.722 [DropWeight] No Fault, FailCount= 1
2023-02-10T17:58:31.935Z,1676051911.935 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-02-10T17:58:31.935Z,1676051911.935 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-10T17:58:31.962Z,1676051911.962 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-10T17:58:32.357Z,1676051912.357 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-10T17:58:32.357Z,1676051912.357 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-02-10T18:01:32.931Z,1676052092.931 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-02-10T18:01:32.931Z,1676052092.931 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-10T18:01:32.941Z,1676052092.941 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-10T18:01:33.355Z,1676052093.355 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-10T18:01:33.355Z,1676052093.355 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-02-10T18:03:13.572Z,1676052193.572 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T17:58:13.4Z
2023-02-10T18:03:13.573Z,1676052193.573 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T18:03:13.573Z,1676052193.573 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T18:03:21.221Z,1676052201.221 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230210T174317/Courier0007.lzma
2023-02-10T18:03:22.224Z,1676052202.224 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0007.lzma.bak
2023-02-10T18:03:22.224Z,1676052202.224 [DataOverHttps](INFO): SBD MOMSN=17460969
2023-02-10T18:03:37.601Z,1676052217.601 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20230210T174317/Express0008.lzma
2023-02-10T18:03:38.604Z,1676052218.604 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0008.lzma.bak
2023-02-10T18:03:38.604Z,1676052218.604 [DataOverHttps](INFO): SBD MOMSN=17460971
2023-02-10T18:03:39.829Z,1676052219.829 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T18:03:39.829Z,1676052219.829 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T18:03:39.829Z,1676052219.829 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T18:04:25.861Z,1676052265.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180424.00,A,3648.19243,N,12147.24723,W,2.760,32.00,100223,,,A*42
2023-02-10T18:04:25.865Z,1676052265.865 [NAL9602](INFO): GPS fix at 20230210T180424: (36.803207, -121.787454)
2023-02-10T18:04:58.179Z,1676052298.179 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T18:08:40.387Z,1676052520.387 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T18:08:40.387Z,1676052520.387 [Default:CheckIn:C.Wait] Stopped
2023-02-10T18:08:40.387Z,1676052520.387 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T18:08:40.387Z,1676052520.387 [Default:CheckIn:D] Running Loop=1
2023-02-10T18:08:40.797Z,1676052520.797 [Default:CheckIn:D] Stopped
2023-02-10T18:08:40.797Z,1676052520.797 [Default:CheckIn:E] Running Loop=1
2023-02-10T18:08:41.203Z,1676052521.203 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.420565 min 
2023-02-10T18:08:41.203Z,1676052521.203 [Default:CheckIn:E] Stopped
2023-02-10T18:08:41.204Z,1676052521.204 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T18:08:41.204Z,1676052521.204 [Default:CheckIn] Stopped
2023-02-10T18:08:41.204Z,1676052521.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T18:08:41.204Z,1676052521.204 [Default:CheckIn](INFO): Running loop #3
2023-02-10T18:08:41.204Z,1676052521.204 [Default:CheckIn] Running Loop=3
2023-02-10T18:08:41.204Z,1676052521.204 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T18:08:41.204Z,1676052521.204 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T18:08:43.213Z,1676052523.213 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180841.00,A,3648.16182,N,12147.28470,W,2.721,0.00,100223,,,A*71
2023-02-10T18:08:43.225Z,1676052523.225 [NAL9602](INFO): GPS fix at 20230210T180841: (36.802697, -121.788078)
2023-02-10T18:08:43.236Z,1676052523.236 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T18:08:43.236Z,1676052523.236 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T18:08:50.458Z,1676052530.458 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230210T174317/Courier0010.lzma
2023-02-10T18:08:51.460Z,1676052531.460 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0010.lzma.bak
2023-02-10T18:08:51.460Z,1676052531.460 [DataOverHttps](INFO): SBD MOMSN=17460988
2023-02-10T18:09:07.342Z,1676052547.342 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20230210T174317/Express0011.lzma
2023-02-10T18:09:08.344Z,1676052548.344 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0011.lzma.bak
2023-02-10T18:09:08.344Z,1676052548.344 [DataOverHttps](INFO): SBD MOMSN=17460991
2023-02-10T18:09:09.494Z,1676052549.494 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T18:09:09.494Z,1676052549.494 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T18:09:09.495Z,1676052549.495 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T18:13:45.407Z,1676052825.407 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T18:14:10.062Z,1676052850.062 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T18:14:10.062Z,1676052850.062 [Default:CheckIn:C.Wait] Stopped
2023-02-10T18:14:10.062Z,1676052850.062 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T18:14:10.062Z,1676052850.062 [Default:CheckIn:D] Running Loop=1
2023-02-10T18:14:10.480Z,1676052850.480 [Default:CheckIn:D] Stopped
2023-02-10T18:14:10.480Z,1676052850.480 [Default:CheckIn:E] Running Loop=1
2023-02-10T18:14:10.873Z,1676052850.873 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.915279 min 
2023-02-10T18:14:10.873Z,1676052850.873 [Default:CheckIn:E] Stopped
2023-02-10T18:14:10.873Z,1676052850.873 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T18:14:10.873Z,1676052850.873 [Default:CheckIn] Stopped
2023-02-10T18:14:10.873Z,1676052850.873 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T18:14:10.873Z,1676052850.873 [Default:CheckIn](INFO): Running loop #4
2023-02-10T18:14:10.874Z,1676052850.874 [Default:CheckIn] Running Loop=4
2023-02-10T18:14:10.874Z,1676052850.874 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T18:14:10.874Z,1676052850.874 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T18:14:21.769Z,1676052861.769 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181420.00,A,3648.15860,N,12147.27830,W,2.099,0.00,100223,,,A*7E
2023-02-10T18:14:21.771Z,1676052861.771 [NAL9602](INFO): GPS fix at 20230210T181420: (36.802643, -121.787972)
2023-02-10T18:14:21.807Z,1676052861.807 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T18:14:21.807Z,1676052861.807 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T18:14:29.365Z,1676052869.365 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230210T174317/Courier0013.lzma
2023-02-10T18:14:30.368Z,1676052870.368 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0013.lzma.bak
2023-02-10T18:14:30.369Z,1676052870.369 [DataOverHttps](INFO): SBD MOMSN=17460994
2023-02-10T18:14:46.110Z,1676052886.110 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230210T174317/Express0014.lzma
2023-02-10T18:14:47.112Z,1676052887.112 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0014.lzma.bak
2023-02-10T18:14:47.112Z,1676052887.112 [DataOverHttps](INFO): SBD MOMSN=17460997
2023-02-10T18:14:48.442Z,1676052888.442 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T18:14:48.442Z,1676052888.443 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T18:14:48.443Z,1676052888.443 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T18:14:54.497Z,1676052894.497 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T18:19:49.020Z,1676053189.020 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T18:19:49.021Z,1676053189.021 [Default:CheckIn:C.Wait] Stopped
2023-02-10T18:19:49.021Z,1676053189.021 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T18:19:49.021Z,1676053189.021 [Default:CheckIn:D] Running Loop=1
2023-02-10T18:19:49.420Z,1676053189.420 [Default:CheckIn:D] Stopped
2023-02-10T18:19:49.420Z,1676053189.420 [Default:CheckIn:E] Running Loop=1
2023-02-10T18:19:49.830Z,1676053189.830 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.564276 min 
2023-02-10T18:19:49.830Z,1676053189.830 [Default:CheckIn:E] Stopped
2023-02-10T18:19:49.830Z,1676053189.830 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T18:19:49.830Z,1676053189.830 [Default:CheckIn] Stopped
2023-02-10T18:19:49.830Z,1676053189.830 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T18:19:49.830Z,1676053189.830 [Default:CheckIn](INFO): Running loop #5
2023-02-10T18:19:49.830Z,1676053189.830 [Default:CheckIn] Running Loop=5
2023-02-10T18:19:49.831Z,1676053189.831 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T18:19:49.831Z,1676053189.831 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T18:19:51.837Z,1676053191.837 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181950.00,A,3648.16431,N,12147.27954,W,0.855,201.10,100223,,,A*74
2023-02-10T18:19:51.839Z,1676053191.839 [NAL9602](INFO): GPS fix at 20230210T181950: (36.802738, -121.787992)
2023-02-10T18:19:51.878Z,1676053191.878 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T18:19:51.879Z,1676053191.879 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T18:19:59.753Z,1676053199.753 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230210T174317/Courier0016.lzma
2023-02-10T18:20:00.756Z,1676053200.756 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0016.lzma.bak
2023-02-10T18:20:00.756Z,1676053200.756 [DataOverHttps](INFO): SBD MOMSN=17461014
2023-02-10T18:20:16.513Z,1676053216.513 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230210T174317/Express0017.lzma
2023-02-10T18:20:17.516Z,1676053217.516 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0017.lzma.bak
2023-02-10T18:20:17.516Z,1676053217.516 [DataOverHttps](INFO): SBD MOMSN=17461017
2023-02-10T18:20:18.950Z,1676053218.950 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T18:20:18.950Z,1676053218.950 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T18:20:18.950Z,1676053218.950 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T18:20:22.584Z,1676053222.584 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-02-10T18:20:22.666Z,1676053222.666 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-02-10T18:24:54.127Z,1676053494.127 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T18:25:19.589Z,1676053519.589 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T18:25:19.589Z,1676053519.589 [Default:CheckIn:C.Wait] Stopped
2023-02-10T18:25:19.589Z,1676053519.589 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T18:25:19.589Z,1676053519.589 [Default:CheckIn:D] Running Loop=1
2023-02-10T18:25:20.004Z,1676053520.004 [Default:CheckIn:D] Stopped
2023-02-10T18:25:20.004Z,1676053520.004 [Default:CheckIn:E] Running Loop=1
2023-02-10T18:25:20.397Z,1676053520.397 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.074011 min 
2023-02-10T18:25:20.397Z,1676053520.397 [Default:CheckIn:E] Stopped
2023-02-10T18:25:20.397Z,1676053520.397 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T18:25:20.397Z,1676053520.397 [Default:CheckIn] Stopped
2023-02-10T18:25:20.397Z,1676053520.397 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T18:25:20.397Z,1676053520.397 [Default:CheckIn](INFO): Running loop #6
2023-02-10T18:25:20.397Z,1676053520.397 [Default:CheckIn] Running Loop=6
2023-02-10T18:25:20.397Z,1676053520.397 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T18:25:20.397Z,1676053520.397 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T18:25:22.409Z,1676053522.409 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182520.00,A,3648.16609,N,12147.27518,W,0.350,46.63,100223,,,A*4A
2023-02-10T18:25:22.411Z,1676053522.411 [NAL9602](INFO): GPS fix at 20230210T182520: (36.802768, -121.787920)
2023-02-10T18:25:22.425Z,1676053522.425 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T18:25:22.425Z,1676053522.425 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T18:25:30.105Z,1676053530.105 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230210T174317/Courier0019.lzma
2023-02-10T18:25:31.108Z,1676053531.108 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0019.lzma.bak
2023-02-10T18:25:31.108Z,1676053531.108 [DataOverHttps](INFO): SBD MOMSN=17461020
2023-02-10T18:25:46.953Z,1676053546.953 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230210T174317/Express0020.lzma
2023-02-10T18:25:47.956Z,1676053547.956 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0020.lzma.bak
2023-02-10T18:25:47.956Z,1676053547.956 [DataOverHttps](INFO): SBD MOMSN=17461023
2023-02-10T18:25:49.081Z,1676053549.081 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T18:25:49.081Z,1676053549.081 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T18:25:49.081Z,1676053549.081 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T18:25:54.326Z,1676053554.326 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T18:28:30.263Z,1676053710.263 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-02-10T18:28:30.263Z,1676053710.263 [DropWeight] Hardware Fault, FailCount= 1
2023-02-10T18:28:30.263Z,1676053710.263 [DropWeight](ERROR): Hardware Fault
2023-02-10T18:28:30.306Z,1676053710.306 [CBIT](INFO): Critical error at 20230210T182830
2023-02-10T18:28:30.309Z,1676053710.309 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-02-10T18:28:30.309Z,1676053710.309 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-02-10T18:28:30.683Z,1676053710.683 [CBIT](INFO): Critical error at 20230210T182830
2023-02-10T18:30:49.666Z,1676053849.666 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T18:30:49.666Z,1676053849.666 [Default:CheckIn:C.Wait] Stopped
2023-02-10T18:30:49.666Z,1676053849.666 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T18:30:49.667Z,1676053849.667 [Default:CheckIn:D] Running Loop=1
2023-02-10T18:30:50.063Z,1676053850.063 [Default:CheckIn:D] Stopped
2023-02-10T18:30:50.063Z,1676053850.063 [Default:CheckIn:E] Running Loop=1
2023-02-10T18:30:50.485Z,1676053850.485 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.575000 min 
2023-02-10T18:30:50.485Z,1676053850.485 [Default:CheckIn:E] Stopped
2023-02-10T18:30:50.485Z,1676053850.485 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T18:30:50.485Z,1676053850.485 [Default:CheckIn] Stopped
2023-02-10T18:30:50.485Z,1676053850.485 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T18:30:50.486Z,1676053850.486 [Default:CheckIn](INFO): Running loop #7
2023-02-10T18:30:50.502Z,1676053850.502 [Default:CheckIn] Running Loop=7
2023-02-10T18:30:50.502Z,1676053850.502 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T18:30:50.502Z,1676053850.502 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T18:31:23.182Z,1676053883.182 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-02-10T18:31:23.258Z,1676053883.258 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-02-10T18:35:50.664Z,1676054150.664 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T18:30:50.5Z
2023-02-10T18:35:50.664Z,1676054150.664 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T18:35:50.664Z,1676054150.664 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T18:35:54.295Z,1676054154.295 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T18:35:58.441Z,1676054158.441 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20230210T174317/Courier0022.lzma
2023-02-10T18:35:59.444Z,1676054159.444 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0022.lzma.bak
2023-02-10T18:35:59.444Z,1676054159.444 [DataOverHttps](INFO): SBD MOMSN=17461043
2023-02-10T18:36:15.314Z,1676054175.314 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230210T174317/Express0023.lzma
2023-02-10T18:36:16.316Z,1676054176.316 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0023.lzma.bak
2023-02-10T18:36:16.316Z,1676054176.316 [DataOverHttps](INFO): SBD MOMSN=17461047
2023-02-10T18:36:17.739Z,1676054177.739 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T18:36:17.739Z,1676054177.739 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T18:36:17.739Z,1676054177.739 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T18:36:24.999Z,1676054184.999 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T18:41:18.371Z,1676054478.371 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T18:41:18.371Z,1676054478.371 [Default:CheckIn:C.Wait] Stopped
2023-02-10T18:41:18.372Z,1676054478.372 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T18:41:18.372Z,1676054478.372 [Default:CheckIn:D] Running Loop=1
2023-02-10T18:41:18.768Z,1676054478.768 [Default:CheckIn:D] Stopped
2023-02-10T18:41:18.768Z,1676054478.768 [Default:CheckIn:E] Running Loop=1
2023-02-10T18:41:19.168Z,1676054479.168 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.053422 min 
2023-02-10T18:41:19.168Z,1676054479.168 [Default:CheckIn:E] Stopped
2023-02-10T18:41:19.168Z,1676054479.168 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T18:41:19.168Z,1676054479.168 [Default:CheckIn] Stopped
2023-02-10T18:41:19.168Z,1676054479.168 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T18:41:19.168Z,1676054479.168 [Default:CheckIn](INFO): Running loop #8
2023-02-10T18:41:19.168Z,1676054479.168 [Default:CheckIn] Running Loop=8
2023-02-10T18:41:19.169Z,1676054479.169 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T18:41:19.169Z,1676054479.169 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T18:41:20.774Z,1676054480.774 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-10T18:41:20.774Z,1676054480.774 [NAL9602] Data Fault, FailCount= 2
2023-02-10T18:41:20.774Z,1676054480.774 [NAL9602](ERROR): Data Fault
2023-02-10T18:41:20.794Z,1676054480.794 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-10T18:41:21.179Z,1676054481.179 [NAL9602](INFO): Powering down
2023-02-10T18:41:22.017Z,1676054482.017 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-10T18:41:22.017Z,1676054482.017 [NAL9602] No Fault, FailCount= 2
2023-02-10T18:41:51.483Z,1676054511.483 [NAL9602](INFO): Powering up NAL9602
2023-02-10T18:42:02.387Z,1676054522.387 [NAL9602](INFO): NAL9602 initialized
2023-02-10T18:43:30.496Z,1676054610.496 [CBIT](INFO): Clearing failed state for component DropWeight
2023-02-10T18:43:30.496Z,1676054610.496 [DropWeight] No Fault, FailCount= 1
2023-02-10T18:46:19.346Z,1676054779.346 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T18:41:19.2Z
2023-02-10T18:46:19.346Z,1676054779.346 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T18:46:19.346Z,1676054779.346 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T18:46:26.969Z,1676054786.969 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230210T174317/Courier0025.lzma
2023-02-10T18:46:27.974Z,1676054787.974 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0025.lzma.bak
2023-02-10T18:46:27.974Z,1676054787.974 [DataOverHttps](INFO): SBD MOMSN=17461064
2023-02-10T18:46:43.877Z,1676054803.877 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20230210T174317/Express0026.lzma
2023-02-10T18:46:44.880Z,1676054804.880 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0026.lzma.bak
2023-02-10T18:46:44.880Z,1676054804.880 [DataOverHttps](INFO): SBD MOMSN=17461066
2023-02-10T18:46:46.411Z,1676054806.411 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T18:46:46.411Z,1676054806.411 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T18:46:46.411Z,1676054806.411 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T18:47:05.791Z,1676054825.791 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T18:47:36.499Z,1676054856.499 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T18:51:47.069Z,1676055107.069 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T18:51:47.069Z,1676055107.069 [Default:CheckIn:C.Wait] Stopped
2023-02-10T18:51:47.069Z,1676055107.069 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T18:51:47.069Z,1676055107.069 [Default:CheckIn:D] Running Loop=1
2023-02-10T18:51:47.473Z,1676055107.473 [Default:CheckIn:D] Stopped
2023-02-10T18:51:47.473Z,1676055107.473 [Default:CheckIn:E] Running Loop=1
2023-02-10T18:51:47.881Z,1676055107.881 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.531832 min 
2023-02-10T18:51:47.881Z,1676055107.881 [Default:CheckIn:E] Stopped
2023-02-10T18:51:47.881Z,1676055107.881 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T18:51:47.881Z,1676055107.881 [Default:CheckIn] Stopped
2023-02-10T18:51:47.881Z,1676055107.881 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T18:51:47.881Z,1676055107.881 [Default:CheckIn](INFO): Running loop #9
2023-02-10T18:51:47.881Z,1676055107.881 [Default:CheckIn] Running Loop=9
2023-02-10T18:51:47.882Z,1676055107.882 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T18:51:47.882Z,1676055107.882 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T18:55:48.252Z,1676055348.252 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T18:55:53.101Z,1676055353.101 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-02-10T18:56:03.200Z,1676055363.200 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T18:56:05.626Z,1676055365.626 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-02-10T18:56:18.148Z,1676055378.148 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T18:56:18.154Z,1676055378.154 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-02-10T18:56:33.500Z,1676055393.500 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T18:56:48.074Z,1676055408.074 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T18:51:47.9Z
2023-02-10T18:56:48.074Z,1676055408.074 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T18:56:48.074Z,1676055408.074 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T18:56:48.852Z,1676055408.852 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T18:56:52.087Z,1676055412.087 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T18:56:55.773Z,1676055415.773 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230210T174317/Courier0028.lzma
2023-02-10T18:56:56.776Z,1676055416.776 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0028.lzma.bak
2023-02-10T18:56:56.776Z,1676055416.776 [DataOverHttps](INFO): SBD MOMSN=17461088
2023-02-10T18:57:03.800Z,1676055423.800 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T18:57:12.582Z,1676055432.582 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230210T174317/Express0029.lzma
2023-02-10T18:57:13.584Z,1676055433.584 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0029.lzma.bak
2023-02-10T18:57:13.584Z,1676055433.584 [DataOverHttps](INFO): SBD MOMSN=17461090
2023-02-10T18:57:14.741Z,1676055434.741 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T18:57:14.741Z,1676055434.741 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T18:57:14.741Z,1676055434.741 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T18:57:18.749Z,1676055438.749 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T18:57:22.795Z,1676055442.795 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T18:57:34.100Z,1676055454.100 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T18:57:49.455Z,1676055469.455 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T18:58:04.400Z,1676055484.400 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T18:58:21.375Z,1676055501.375 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 6.
2023-02-10T18:58:21.378Z,1676055501.378 [BPC1](INFO): Received data from all battery sticks.
2023-02-10T19:02:15.316Z,1676055735.316 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T19:02:15.316Z,1676055735.316 [Default:CheckIn:C.Wait] Stopped
2023-02-10T19:02:15.316Z,1676055735.316 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T19:02:15.316Z,1676055735.316 [Default:CheckIn:D] Running Loop=1
2023-02-10T19:02:15.709Z,1676055735.709 [Default:CheckIn:D] Stopped
2023-02-10T19:02:15.709Z,1676055735.709 [Default:CheckIn:E] Running Loop=1
2023-02-10T19:02:16.108Z,1676055736.108 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.002425 min 
2023-02-10T19:02:16.108Z,1676055736.108 [Default:CheckIn:E] Stopped
2023-02-10T19:02:16.108Z,1676055736.108 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T19:02:16.108Z,1676055736.108 [Default:CheckIn] Stopped
2023-02-10T19:02:16.108Z,1676055736.108 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T19:02:16.108Z,1676055736.108 [Default:CheckIn](INFO): Running loop #10
2023-02-10T19:02:16.109Z,1676055736.109 [Default:CheckIn] Running Loop=10
2023-02-10T19:02:16.109Z,1676055736.109 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T19:02:16.109Z,1676055736.109 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T19:02:17.714Z,1676055737.714 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-10T19:02:17.714Z,1676055737.714 [NAL9602] Data Fault, FailCount= 3
2023-02-10T19:02:17.714Z,1676055737.714 [NAL9602](ERROR): Data Fault
2023-02-10T19:02:17.734Z,1676055737.734 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-10T19:02:18.125Z,1676055738.125 [NAL9602](INFO): Powering down
2023-02-10T19:02:18.970Z,1676055738.970 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-10T19:02:18.970Z,1676055738.970 [NAL9602] No Fault, FailCount= 3
2023-02-10T19:02:48.423Z,1676055768.423 [NAL9602](INFO): Powering up NAL9602
2023-02-10T19:02:59.328Z,1676055779.328 [NAL9602](INFO): NAL9602 initialized
2023-02-10T19:07:16.295Z,1676056036.295 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T19:02:16.1Z
2023-02-10T19:07:16.295Z,1676056036.295 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T19:07:16.296Z,1676056036.296 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T19:07:23.465Z,1676056043.465 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20230210T174317/Courier0031.lzma
2023-02-10T19:07:24.468Z,1676056044.468 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0031.lzma.bak
2023-02-10T19:07:24.468Z,1676056044.468 [DataOverHttps](INFO): SBD MOMSN=17461108
2023-02-10T19:07:40.854Z,1676056060.854 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20230210T174317/Express0032.lzma
2023-02-10T19:07:41.856Z,1676056061.856 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0032.lzma.bak
2023-02-10T19:07:41.856Z,1676056061.856 [DataOverHttps](INFO): SBD MOMSN=17461110
2023-02-10T19:07:43.370Z,1676056063.370 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T19:07:43.370Z,1676056063.370 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T19:07:43.370Z,1676056063.370 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T19:08:02.736Z,1676056082.736 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T19:08:33.439Z,1676056113.439 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T19:12:43.948Z,1676056363.948 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T19:12:43.948Z,1676056363.948 [Default:CheckIn:C.Wait] Stopped
2023-02-10T19:12:43.948Z,1676056363.948 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T19:12:43.948Z,1676056363.948 [Default:CheckIn:D] Running Loop=1
2023-02-10T19:12:44.352Z,1676056364.352 [Default:CheckIn:D] Stopped
2023-02-10T19:12:44.352Z,1676056364.352 [Default:CheckIn:E] Running Loop=1
2023-02-10T19:12:44.763Z,1676056364.763 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.479818 min 
2023-02-10T19:12:44.763Z,1676056364.763 [Default:CheckIn:E] Stopped
2023-02-10T19:12:44.763Z,1676056364.763 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T19:12:44.763Z,1676056364.763 [Default:CheckIn] Stopped
2023-02-10T19:12:44.763Z,1676056364.763 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T19:12:44.763Z,1676056364.763 [Default:CheckIn](INFO): Running loop #11
2023-02-10T19:12:44.763Z,1676056364.763 [Default:CheckIn] Running Loop=11
2023-02-10T19:12:44.763Z,1676056364.763 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T19:12:44.763Z,1676056364.763 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T19:13:17.488Z,1676056397.488 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-02-10T19:13:17.570Z,1676056397.570 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-02-10T19:13:32.019Z,1676056412.019 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-02-10T19:13:32.019Z,1676056412.019 [DropWeight] Hardware Fault, FailCount= 1
2023-02-10T19:13:32.019Z,1676056412.019 [DropWeight](ERROR): Hardware Fault
2023-02-10T19:13:32.047Z,1676056412.047 [CBIT](INFO): Critical error at 20230210T191332
2023-02-10T19:13:32.049Z,1676056412.049 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-02-10T19:13:32.049Z,1676056412.049 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-02-10T19:13:32.455Z,1676056412.455 [CBIT](INFO): Critical error at 20230210T191332
2023-02-10T19:17:44.935Z,1676056664.935 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T19:12:44.8Z
2023-02-10T19:17:44.935Z,1676056664.935 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T19:17:44.935Z,1676056664.935 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T19:17:49.363Z,1676056669.363 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T19:17:52.349Z,1676056672.349 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20230210T174317/Courier0034.lzma
2023-02-10T19:17:53.352Z,1676056673.352 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0034.lzma.bak
2023-02-10T19:17:53.352Z,1676056673.352 [DataOverHttps](INFO): SBD MOMSN=17461130
2023-02-10T19:18:08.397Z,1676056688.397 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230210T174317/Express0035.lzma
2023-02-10T19:18:09.400Z,1676056689.400 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0035.lzma.bak
2023-02-10T19:18:09.400Z,1676056689.400 [DataOverHttps](INFO): SBD MOMSN=17461134
2023-02-10T19:18:10.794Z,1676056690.794 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T19:18:10.794Z,1676056690.794 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T19:18:10.794Z,1676056690.794 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T19:18:20.065Z,1676056700.065 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T19:23:11.364Z,1676056991.364 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T19:23:11.364Z,1676056991.364 [Default:CheckIn:C.Wait] Stopped
2023-02-10T19:23:11.365Z,1676056991.365 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T19:23:11.365Z,1676056991.365 [Default:CheckIn:D] Running Loop=1
2023-02-10T19:23:11.763Z,1676056991.763 [Default:CheckIn:D] Stopped
2023-02-10T19:23:11.763Z,1676056991.763 [Default:CheckIn:E] Running Loop=1
2023-02-10T19:23:12.177Z,1676056992.177 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.936662 min 
2023-02-10T19:23:12.177Z,1676056992.177 [Default:CheckIn:E] Stopped
2023-02-10T19:23:12.177Z,1676056992.177 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T19:23:12.177Z,1676056992.177 [Default:CheckIn] Stopped
2023-02-10T19:23:12.178Z,1676056992.178 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T19:23:12.178Z,1676056992.178 [Default:CheckIn](INFO): Running loop #12
2023-02-10T19:23:12.178Z,1676056992.178 [Default:CheckIn] Running Loop=12
2023-02-10T19:23:12.178Z,1676056992.178 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T19:23:12.178Z,1676056992.178 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T19:23:13.780Z,1676056993.780 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-10T19:23:13.780Z,1676056993.780 [NAL9602] Data Fault, FailCount= 4
2023-02-10T19:23:13.780Z,1676056993.780 [NAL9602](ERROR): Data Fault
2023-02-10T19:23:13.800Z,1676056993.800 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-10T19:23:14.179Z,1676056994.179 [NAL9602](INFO): Powering down
2023-02-10T19:23:15.010Z,1676056995.010 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-10T19:23:15.010Z,1676056995.010 [NAL9602] No Fault, FailCount= 4
2023-02-10T19:23:44.487Z,1676057024.487 [NAL9602](INFO): Powering up NAL9602
2023-02-10T19:23:55.391Z,1676057035.391 [NAL9602](INFO): NAL9602 initialized
2023-02-10T19:28:12.378Z,1676057292.378 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T19:23:12.2Z
2023-02-10T19:28:12.378Z,1676057292.378 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T19:28:12.378Z,1676057292.378 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T19:28:18.441Z,1676057298.441 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230210T174317/Courier0037.lzma
2023-02-10T19:28:19.445Z,1676057299.445 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0037.lzma.bak
2023-02-10T19:28:19.445Z,1676057299.445 [DataOverHttps](INFO): SBD MOMSN=17461152
2023-02-10T19:28:32.191Z,1676057312.191 [CBIT](INFO): Clearing failed state for component DropWeight
2023-02-10T19:28:32.191Z,1676057312.191 [DropWeight] No Fault, FailCount= 1
2023-02-10T19:28:35.397Z,1676057315.397 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230210T174317/Express0038.lzma
2023-02-10T19:28:36.400Z,1676057316.400 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0038.lzma.bak
2023-02-10T19:28:36.400Z,1676057316.400 [DataOverHttps](INFO): SBD MOMSN=17461154
2023-02-10T19:28:37.808Z,1676057317.808 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T19:28:37.808Z,1676057317.808 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T19:28:37.808Z,1676057317.808 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T19:28:58.796Z,1676057338.796 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T19:29:29.515Z,1676057369.515 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T19:33:38.414Z,1676057618.414 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T19:33:38.414Z,1676057618.414 [Default:CheckIn:C.Wait] Stopped
2023-02-10T19:33:38.414Z,1676057618.414 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T19:33:38.415Z,1676057618.415 [Default:CheckIn:D] Running Loop=1
2023-02-10T19:33:38.796Z,1676057618.796 [Default:CheckIn:D] Stopped
2023-02-10T19:33:38.796Z,1676057618.796 [Default:CheckIn:E] Running Loop=1
2023-02-10T19:33:39.207Z,1676057619.207 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.387215 min 
2023-02-10T19:33:39.207Z,1676057619.207 [Default:CheckIn:E] Stopped
2023-02-10T19:33:39.207Z,1676057619.207 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T19:33:39.207Z,1676057619.207 [Default:CheckIn] Stopped
2023-02-10T19:33:39.207Z,1676057619.207 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T19:33:39.208Z,1676057619.208 [Default:CheckIn](INFO): Running loop #13
2023-02-10T19:33:39.208Z,1676057619.208 [Default:CheckIn] Running Loop=13
2023-02-10T19:33:39.208Z,1676057619.208 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T19:33:39.208Z,1676057619.208 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T19:34:11.916Z,1676057651.916 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-02-10T19:34:11.990Z,1676057651.990 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-02-10T19:38:39.378Z,1676057919.378 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T19:33:39.2Z
2023-02-10T19:38:39.378Z,1676057919.378 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T19:38:39.378Z,1676057919.378 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T19:38:43.559Z,1676057923.559 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T19:38:47.157Z,1676057927.157 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230210T174317/Courier0040.lzma
2023-02-10T19:38:48.160Z,1676057928.160 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0040.lzma.bak
2023-02-10T19:38:48.160Z,1676057928.160 [DataOverHttps](INFO): SBD MOMSN=17461174
2023-02-10T19:39:04.050Z,1676057944.050 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230210T174317/Express0041.lzma
2023-02-10T19:39:05.052Z,1676057945.052 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0041.lzma.bak
2023-02-10T19:39:05.052Z,1676057945.052 [DataOverHttps](INFO): SBD MOMSN=17461176
2023-02-10T19:39:06.195Z,1676057946.195 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T19:39:06.195Z,1676057946.195 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T19:39:06.195Z,1676057946.195 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T19:39:14.267Z,1676057954.267 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T19:44:06.769Z,1676058246.769 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T19:44:06.769Z,1676058246.769 [Default:CheckIn:C.Wait] Stopped
2023-02-10T19:44:06.769Z,1676058246.769 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T19:44:06.769Z,1676058246.769 [Default:CheckIn:D] Running Loop=1
2023-02-10T19:44:07.185Z,1676058247.185 [Default:CheckIn:D] Stopped
2023-02-10T19:44:07.185Z,1676058247.185 [Default:CheckIn:E] Running Loop=1
2023-02-10T19:44:07.581Z,1676058247.581 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.860368 min 
2023-02-10T19:44:07.581Z,1676058247.581 [Default:CheckIn:E] Stopped
2023-02-10T19:44:07.581Z,1676058247.581 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T19:44:07.581Z,1676058247.581 [Default:CheckIn] Stopped
2023-02-10T19:44:07.581Z,1676058247.581 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T19:44:07.581Z,1676058247.581 [Default:CheckIn](INFO): Running loop #14
2023-02-10T19:44:07.581Z,1676058247.581 [Default:CheckIn] Running Loop=14
2023-02-10T19:44:07.582Z,1676058247.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T19:44:07.582Z,1676058247.582 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T19:44:09.187Z,1676058249.187 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-10T19:44:09.188Z,1676058249.188 [NAL9602] Data Fault, FailCount= 5
2023-02-10T19:44:09.188Z,1676058249.188 [NAL9602](ERROR): Data Fault
2023-02-10T19:44:09.224Z,1676058249.224 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-10T19:44:09.224Z,1676058249.224 [CBIT](CRITICAL): Data Fault in component: NAL9602
2023-02-10T19:44:09.587Z,1676058249.587 [NAL9602](INFO): Powering down
2023-02-10T19:44:09.609Z,1676058249.609 [CBIT](INFO): Critical error at 20230210T194409
2023-02-10T19:46:09.230Z,1676058369.230 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-10T19:46:09.230Z,1676058369.230 [NAL9602] No Fault, FailCount= 5
2023-02-10T19:46:09.600Z,1676058369.600 [NAL9602](INFO): Powering up NAL9602
2023-02-10T19:46:20.511Z,1676058380.511 [NAL9602](INFO): NAL9602 initialized
2023-02-10T19:49:07.778Z,1676058547.778 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T19:44:07.6Z
2023-02-10T19:49:07.778Z,1676058547.778 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T19:49:07.778Z,1676058547.778 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T19:49:15.201Z,1676058555.201 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20230210T174317/Courier0043.lzma
2023-02-10T19:49:16.204Z,1676058556.204 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0043.lzma.bak
2023-02-10T19:49:16.204Z,1676058556.204 [DataOverHttps](INFO): SBD MOMSN=17461195
2023-02-10T19:49:31.994Z,1676058571.994 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20230210T174317/Express0044.lzma
2023-02-10T19:49:32.996Z,1676058572.996 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0044.lzma.bak
2023-02-10T19:49:32.996Z,1676058572.996 [DataOverHttps](INFO): SBD MOMSN=17461198
2023-02-10T19:49:34.445Z,1676058574.445 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T19:49:34.445Z,1676058574.445 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T19:49:34.445Z,1676058574.445 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T19:51:23.911Z,1676058683.911 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T19:51:54.631Z,1676058714.631 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T19:54:35.041Z,1676058875.041 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T19:54:35.041Z,1676058875.041 [Default:CheckIn:C.Wait] Stopped
2023-02-10T19:54:35.041Z,1676058875.041 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T19:54:35.041Z,1676058875.041 [Default:CheckIn:D] Running Loop=1
2023-02-10T19:54:35.431Z,1676058875.431 [Default:CheckIn:D] Stopped
2023-02-10T19:54:35.431Z,1676058875.431 [Default:CheckIn:E] Running Loop=1
2023-02-10T19:54:35.841Z,1676058875.841 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.331136 min 
2023-02-10T19:54:35.841Z,1676058875.841 [Default:CheckIn:E] Stopped
2023-02-10T19:54:35.841Z,1676058875.841 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T19:54:35.842Z,1676058875.842 [Default:CheckIn] Stopped
2023-02-10T19:54:35.842Z,1676058875.842 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T19:54:35.842Z,1676058875.842 [Default:CheckIn](INFO): Running loop #15
2023-02-10T19:54:35.842Z,1676058875.842 [Default:CheckIn] Running Loop=15
2023-02-10T19:54:35.842Z,1676058875.842 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T19:54:35.842Z,1676058875.842 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T19:55:08.550Z,1676058908.550 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-02-10T19:55:08.626Z,1676058908.626 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-02-10T19:58:33.383Z,1676059113.383 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-02-10T19:58:33.383Z,1676059113.383 [DropWeight] Hardware Fault, FailCount= 1
2023-02-10T19:58:33.383Z,1676059113.383 [DropWeight](ERROR): Hardware Fault
2023-02-10T19:58:33.401Z,1676059113.401 [CBIT](INFO): Critical error at 20230210T195833
2023-02-10T19:58:33.404Z,1676059113.404 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-02-10T19:58:33.404Z,1676059113.404 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-02-10T19:58:33.814Z,1676059113.814 [CBIT](INFO): Critical error at 20230210T195833
2023-02-10T19:59:36.013Z,1676059176.013 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T19:54:35.8Z
2023-02-10T19:59:36.013Z,1676059176.013 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T19:59:36.013Z,1676059176.013 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T19:59:40.039Z,1676059180.039 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T19:59:43.737Z,1676059183.737 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20230210T174317/Courier0046.lzma
2023-02-10T19:59:44.740Z,1676059184.740 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0046.lzma.bak
2023-02-10T19:59:44.740Z,1676059184.740 [DataOverHttps](INFO): SBD MOMSN=17461218
2023-02-10T20:00:01.033Z,1676059201.033 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230210T174317/Express0047.lzma
2023-02-10T20:00:02.036Z,1676059202.036 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0047.lzma.bak
2023-02-10T20:00:02.036Z,1676059202.036 [DataOverHttps](INFO): SBD MOMSN=17461222
2023-02-10T20:00:03.529Z,1676059203.529 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T20:00:03.529Z,1676059203.529 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T20:00:03.529Z,1676059203.529 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T20:00:10.572Z,1676059210.572 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T20:03:19.468Z,1676059399.468 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T20:03:20.680Z,1676059400.680 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2023-02-10T20:03:25.124Z,1676059405.124 [BPC1](ERROR): BPC1B got IPBS message with 2 sticks (min is 7).
2023-02-10T20:03:26.337Z,1676059406.337 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T20:03:41.284Z,1676059421.284 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T20:03:56.237Z,1676059436.237 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T20:04:12.394Z,1676059452.394 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T20:04:27.342Z,1676059467.342 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T20:04:42.693Z,1676059482.693 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T20:04:57.640Z,1676059497.640 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T20:05:04.120Z,1676059504.120 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T20:05:04.120Z,1676059504.120 [Default:CheckIn:C.Wait] Stopped
2023-02-10T20:05:04.120Z,1676059504.120 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T20:05:04.121Z,1676059504.121 [Default:CheckIn:D] Running Loop=1
2023-02-10T20:05:04.522Z,1676059504.522 [Default:CheckIn:D] Stopped
2023-02-10T20:05:04.523Z,1676059504.523 [Default:CheckIn:E] Running Loop=1
2023-02-10T20:05:04.939Z,1676059504.939 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.815983 min 
2023-02-10T20:05:04.939Z,1676059504.939 [Default:CheckIn:E] Stopped
2023-02-10T20:05:04.939Z,1676059504.939 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T20:05:04.939Z,1676059504.939 [Default:CheckIn] Stopped
2023-02-10T20:05:04.939Z,1676059504.939 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T20:05:04.939Z,1676059504.939 [Default:CheckIn](INFO): Running loop #16
2023-02-10T20:05:04.939Z,1676059504.939 [Default:CheckIn] Running Loop=16
2023-02-10T20:05:04.939Z,1676059504.939 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T20:05:04.940Z,1676059504.940 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T20:05:06.531Z,1676059506.531 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-10T20:05:06.532Z,1676059506.532 [NAL9602] Data Fault, FailCount= 1
2023-02-10T20:05:06.532Z,1676059506.532 [NAL9602](ERROR): Data Fault
2023-02-10T20:05:06.584Z,1676059506.584 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-10T20:05:06.935Z,1676059506.935 [NAL9602](INFO): Powering down
2023-02-10T20:05:07.774Z,1676059507.774 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-10T20:05:07.775Z,1676059507.775 [NAL9602] No Fault, FailCount= 1
2023-02-10T20:05:12.992Z,1676059512.992 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T20:05:28.344Z,1676059528.344 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-02-10T20:05:37.234Z,1676059537.234 [NAL9602](INFO): Powering up NAL9602
2023-02-10T20:05:46.534Z,1676059546.534 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2023-02-10T20:05:46.537Z,1676059546.537 [BPC1](INFO): Received data from all battery sticks.
2023-02-10T20:05:48.147Z,1676059548.147 [NAL9602](INFO): NAL9602 initialized
2023-02-10T20:10:05.112Z,1676059805.112 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T20:05:04.9Z
2023-02-10T20:10:05.112Z,1676059805.112 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T20:10:05.112Z,1676059805.112 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T20:10:12.137Z,1676059812.137 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20230210T174317/Courier0049.lzma
2023-02-10T20:10:13.140Z,1676059813.140 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0049.lzma.bak
2023-02-10T20:10:13.140Z,1676059813.140 [DataOverHttps](INFO): SBD MOMSN=17461239
2023-02-10T20:10:29.042Z,1676059829.042 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230210T174317/Express0050.lzma
2023-02-10T20:10:30.044Z,1676059830.044 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0050.lzma.bak
2023-02-10T20:10:30.044Z,1676059830.044 [DataOverHttps](INFO): SBD MOMSN=17461241
2023-02-10T20:10:31.389Z,1676059831.389 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T20:10:31.389Z,1676059831.389 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T20:10:31.389Z,1676059831.389 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T20:10:51.598Z,1676059851.598 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T20:11:22.307Z,1676059882.307 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T20:13:33.620Z,1676060013.620 [CBIT](INFO): Clearing failed state for component DropWeight
2023-02-10T20:13:33.620Z,1676060013.620 [DropWeight] No Fault, FailCount= 1
2023-02-10T20:15:31.987Z,1676060131.987 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T20:15:31.987Z,1676060131.987 [Default:CheckIn:C.Wait] Stopped
2023-02-10T20:15:31.987Z,1676060131.987 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T20:15:31.987Z,1676060131.987 [Default:CheckIn:D] Running Loop=1
2023-02-10T20:15:32.448Z,1676060132.448 [Default:CheckIn:D] Stopped
2023-02-10T20:15:32.448Z,1676060132.448 [Default:CheckIn:E] Running Loop=1
2023-02-10T20:15:32.793Z,1676060132.793 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.281413 min 
2023-02-10T20:15:32.793Z,1676060132.793 [Default:CheckIn:E] Stopped
2023-02-10T20:15:32.794Z,1676060132.794 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T20:15:32.810Z,1676060132.810 [Default:CheckIn] Stopped
2023-02-10T20:15:32.810Z,1676060132.810 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T20:15:32.811Z,1676060132.811 [Default:CheckIn](INFO): Running loop #17
2023-02-10T20:15:32.811Z,1676060132.811 [Default:CheckIn] Running Loop=17
2023-02-10T20:15:32.811Z,1676060132.811 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T20:15:32.811Z,1676060132.811 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T20:16:05.518Z,1676060165.518 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-02-10T20:16:05.598Z,1676060165.598 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-02-10T20:20:33.025Z,1676060433.025 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T20:15:32.8Z
2023-02-10T20:20:33.025Z,1676060433.025 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T20:20:33.025Z,1676060433.025 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T20:20:36.591Z,1676060436.591 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T20:20:40.745Z,1676060440.745 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230210T174317/Courier0052.lzma
2023-02-10T20:20:41.748Z,1676060441.748 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0052.lzma.bak
2023-02-10T20:20:41.748Z,1676060441.748 [DataOverHttps](INFO): SBD MOMSN=17461261
2023-02-10T20:20:57.525Z,1676060457.525 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230210T174317/Express0053.lzma
2023-02-10T20:20:58.528Z,1676060458.528 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0053.lzma.bak
2023-02-10T20:20:58.528Z,1676060458.528 [DataOverHttps](INFO): SBD MOMSN=17461263
2023-02-10T20:21:00.046Z,1676060460.046 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T20:21:00.046Z,1676060460.046 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T20:21:00.046Z,1676060460.046 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T20:21:07.294Z,1676060467.294 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T20:26:00.636Z,1676060760.636 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T20:26:00.636Z,1676060760.636 [Default:CheckIn:C.Wait] Stopped
2023-02-10T20:26:00.636Z,1676060760.636 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T20:26:00.636Z,1676060760.636 [Default:CheckIn:D] Running Loop=1
2023-02-10T20:26:01.046Z,1676060761.046 [Default:CheckIn:D] Stopped
2023-02-10T20:26:01.046Z,1676060761.046 [Default:CheckIn:E] Running Loop=1
2023-02-10T20:26:01.453Z,1676060761.453 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 158.758057 min 
2023-02-10T20:26:01.453Z,1676060761.453 [Default:CheckIn:E] Stopped
2023-02-10T20:26:01.453Z,1676060761.453 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T20:26:01.453Z,1676060761.453 [Default:CheckIn] Stopped
2023-02-10T20:26:01.453Z,1676060761.453 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T20:26:01.453Z,1676060761.453 [Default:CheckIn](INFO): Running loop #18
2023-02-10T20:26:01.454Z,1676060761.454 [Default:CheckIn] Running Loop=18
2023-02-10T20:26:01.454Z,1676060761.454 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T20:26:01.454Z,1676060761.454 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T20:26:03.052Z,1676060763.052 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-10T20:26:03.052Z,1676060763.052 [NAL9602] Data Fault, FailCount= 2
2023-02-10T20:26:03.052Z,1676060763.052 [NAL9602](ERROR): Data Fault
2023-02-10T20:26:03.072Z,1676060763.072 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-10T20:26:03.469Z,1676060763.469 [NAL9602](INFO): Powering down
2023-02-10T20:26:04.286Z,1676060764.286 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-10T20:26:04.286Z,1676060764.286 [NAL9602] No Fault, FailCount= 2
2023-02-10T20:26:33.755Z,1676060793.755 [NAL9602](INFO): Powering up NAL9602
2023-02-10T20:26:44.667Z,1676060804.667 [NAL9602](INFO): NAL9602 initialized
2023-02-10T20:31:01.638Z,1676061061.638 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T20:26:01.5Z
2023-02-10T20:31:01.638Z,1676061061.638 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T20:31:01.638Z,1676061061.638 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T20:31:09.221Z,1676061069.221 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230210T174317/Courier0055.lzma
2023-02-10T20:31:10.224Z,1676061070.224 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0055.lzma.bak
2023-02-10T20:31:10.224Z,1676061070.224 [DataOverHttps](INFO): SBD MOMSN=17461282
2023-02-10T20:31:26.170Z,1676061086.170 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20230210T174317/Express0056.lzma
2023-02-10T20:31:27.172Z,1676061087.172 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0056.lzma.bak
2023-02-10T20:31:27.172Z,1676061087.172 [DataOverHttps](INFO): SBD MOMSN=17461284
2023-02-10T20:31:28.717Z,1676061088.717 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T20:31:28.717Z,1676061088.717 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T20:31:28.717Z,1676061088.717 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T20:31:48.078Z,1676061108.078 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T20:32:18.787Z,1676061138.787 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T20:36:29.277Z,1676061389.277 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T20:36:29.277Z,1676061389.277 [Default:CheckIn:C.Wait] Stopped
2023-02-10T20:36:29.277Z,1676061389.277 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T20:36:29.277Z,1676061389.277 [Default:CheckIn:D] Running Loop=1
2023-02-10T20:36:29.688Z,1676061389.688 [Default:CheckIn:D] Stopped
2023-02-10T20:36:29.688Z,1676061389.688 [Default:CheckIn:E] Running Loop=1
2023-02-10T20:36:30.080Z,1676061390.080 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 169.235417 min 
2023-02-10T20:36:30.080Z,1676061390.080 [Default:CheckIn:E] Stopped
2023-02-10T20:36:30.080Z,1676061390.080 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T20:36:30.080Z,1676061390.080 [Default:CheckIn] Stopped
2023-02-10T20:36:30.080Z,1676061390.080 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T20:36:30.080Z,1676061390.080 [Default:CheckIn](INFO): Running loop #19
2023-02-10T20:36:30.080Z,1676061390.080 [Default:CheckIn] Running Loop=19
2023-02-10T20:36:30.080Z,1676061390.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T20:36:30.080Z,1676061390.080 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T20:37:02.800Z,1676061422.800 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-02-10T20:37:02.878Z,1676061422.878 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-02-10T20:41:30.270Z,1676061690.270 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T20:36:30.1Z
2023-02-10T20:41:30.270Z,1676061690.270 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T20:41:30.270Z,1676061690.270 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T20:41:34.295Z,1676061694.295 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T20:41:37.129Z,1676061697.129 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230210T174317/Courier0058.lzma
2023-02-10T20:41:38.132Z,1676061698.132 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0058.lzma.bak
2023-02-10T20:41:38.132Z,1676061698.132 [DataOverHttps](INFO): SBD MOMSN=17461302
2023-02-10T20:41:54.013Z,1676061714.013 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20230210T174317/Express0059.lzma
2023-02-10T20:41:55.016Z,1676061715.016 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0059.lzma.bak
2023-02-10T20:41:55.016Z,1676061715.016 [DataOverHttps](INFO): SBD MOMSN=17461304
2023-02-10T20:41:56.146Z,1676061716.146 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T20:41:56.146Z,1676061716.146 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T20:41:56.146Z,1676061716.146 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T20:42:05.003Z,1676061725.003 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T20:43:35.089Z,1676061815.089 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-02-10T20:43:35.090Z,1676061815.090 [DropWeight] Hardware Fault, FailCount= 1
2023-02-10T20:43:35.090Z,1676061815.090 [DropWeight](ERROR): Hardware Fault
2023-02-10T20:43:35.108Z,1676061815.108 [CBIT](INFO): Critical error at 20230210T204335
2023-02-10T20:43:35.131Z,1676061815.131 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-02-10T20:43:35.131Z,1676061815.131 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-02-10T20:43:35.568Z,1676061815.568 [CBIT](INFO): Critical error at 20230210T204335
2023-02-10T20:46:56.701Z,1676062016.701 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T20:46:56.701Z,1676062016.701 [Default:CheckIn:C.Wait] Stopped
2023-02-10T20:46:56.701Z,1676062016.701 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T20:46:56.701Z,1676062016.701 [Default:CheckIn:D] Running Loop=1
2023-02-10T20:46:57.099Z,1676062017.099 [Default:CheckIn:D] Stopped
2023-02-10T20:46:57.099Z,1676062017.099 [Default:CheckIn:E] Running Loop=1
2023-02-10T20:46:57.513Z,1676062017.513 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 179.692269 min 
2023-02-10T20:46:57.513Z,1676062017.513 [Default:CheckIn:E] Stopped
2023-02-10T20:46:57.513Z,1676062017.513 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T20:46:57.513Z,1676062017.513 [Default:CheckIn] Stopped
2023-02-10T20:46:57.514Z,1676062017.514 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T20:46:57.514Z,1676062017.514 [Default:CheckIn](INFO): Running loop #20
2023-02-10T20:46:57.514Z,1676062017.514 [Default:CheckIn] Running Loop=20
2023-02-10T20:46:57.514Z,1676062017.514 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T20:46:57.514Z,1676062017.514 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T20:46:59.118Z,1676062019.118 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-10T20:46:59.118Z,1676062019.118 [NAL9602] Data Fault, FailCount= 3
2023-02-10T20:46:59.118Z,1676062019.118 [NAL9602](ERROR): Data Fault
2023-02-10T20:46:59.141Z,1676062019.141 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-10T20:46:59.515Z,1676062019.515 [NAL9602](INFO): Powering down
2023-02-10T20:47:00.347Z,1676062020.347 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-10T20:47:00.347Z,1676062020.347 [NAL9602] No Fault, FailCount= 3
2023-02-10T20:47:29.816Z,1676062049.816 [NAL9602](INFO): Powering up NAL9602
2023-02-10T20:47:40.736Z,1676062060.736 [NAL9602](INFO): NAL9602 initialized
2023-02-10T20:51:57.719Z,1676062317.719 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-10T20:46:57.5Z
2023-02-10T20:51:57.719Z,1676062317.719 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T20:51:57.719Z,1676062317.719 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-10T20:52:05.681Z,1676062325.681 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20230210T174317/Courier0061.lzma
2023-02-10T20:52:06.684Z,1676062326.684 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Courier0061.lzma.bak
2023-02-10T20:52:06.684Z,1676062326.684 [DataOverHttps](INFO): SBD MOMSN=17461323
2023-02-10T20:52:22.490Z,1676062342.490 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230210T174317/Express0062.lzma
2023-02-10T20:52:23.492Z,1676062343.492 [DataOverHttps](INFO): Moved sent file to Logs/20230210T174317/Express0062.lzma.bak
2023-02-10T20:52:23.492Z,1676062343.492 [DataOverHttps](INFO): SBD MOMSN=17461327
2023-02-10T20:52:24.759Z,1676062344.759 [Default:CheckIn:Read_Iridium] Stopped
2023-02-10T20:52:24.759Z,1676062344.759 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-10T20:52:24.759Z,1676062344.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-10T20:52:44.139Z,1676062364.139 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-10T20:53:14.843Z,1676062394.843 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-10T20:57:25.327Z,1676062645.327 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-10T20:57:25.327Z,1676062645.327 [Default:CheckIn:C.Wait] Stopped
2023-02-10T20:57:25.327Z,1676062645.327 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-10T20:57:25.328Z,1676062645.328 [Default:CheckIn:D] Running Loop=1
2023-02-10T20:57:25.738Z,1676062645.738 [Default:CheckIn:D] Stopped
2023-02-10T20:57:25.738Z,1676062645.738 [Default:CheckIn:E] Running Loop=1
2023-02-10T20:57:26.145Z,1676062646.145 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 190.169596 min 
2023-02-10T20:57:26.145Z,1676062646.145 [Default:CheckIn:E] Stopped
2023-02-10T20:57:26.146Z,1676062646.146 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-10T20:57:26.146Z,1676062646.146 [Default:CheckIn] Stopped
2023-02-10T20:57:26.146Z,1676062646.146 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T20:57:26.146Z,1676062646.146 [Default:CheckIn](INFO): Running loop #21
2023-02-10T20:57:26.146Z,1676062646.146 [Default:CheckIn] Running Loop=21
2023-02-10T20:57:26.146Z,1676062646.146 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-10T20:57:26.146Z,1676062646.146 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-10T20:57:40.967Z,1676062660.967 [CommandExec](IMPORTANT): got command quit
2023-02-10T20:57:41.970Z,1676062661.970 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-10T20:57:41.970Z,1676062661.970 [CommandExec](INFO): Uninitializing the command executive.
2023-02-10T20:57:41.970Z,1676062661.970 [CommandExec](INFO): Uninitializing the command scheduler.
2023-02-10T20:57:41.971Z,1676062661.971 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:42.030Z,1676062662.030 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-02-10T20:57:42.030Z,1676062662.030 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-02-10T20:57:42.030Z,1676062662.030 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:42.031Z,1676062662.031 [NavChartDb](INFO): Join timeout helper Thread ID is 1414
2023-02-10T20:57:42.242Z,1676062662.242 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-10T20:57:42.242Z,1676062662.242 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:42.250Z,1676062662.250 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-02-10T20:57:42.250Z,1676062662.250 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:42.250Z,1676062662.250 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1415
2023-02-10T20:57:42.294Z,1676062662.294 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-10T20:57:42.294Z,1676062662.294 [WetLabsBB2FL](INFO): Powering down
2023-02-10T20:57:42.295Z,1676062662.295 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:42.306Z,1676062662.306 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-02-10T20:57:42.306Z,1676062662.306 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:42.307Z,1676062662.307 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1416
2023-02-10T20:57:43.150Z,1676062663.150 [CTD_Seabird](INFO): Powering down
2023-02-10T20:57:43.164Z,1676062663.164 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-10T20:57:43.166Z,1676062663.166 [CTD_Seabird](INFO): Powering down
2023-02-10T20:57:43.178Z,1676062663.178 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:43.194Z,1676062663.194 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-02-10T20:57:43.194Z,1676062663.194 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:43.194Z,1676062663.194 [Radio_Surface](INFO): Join timeout helper Thread ID is 1417
2023-02-10T20:57:43.234Z,1676062663.234 [Radio_Surface](INFO): Powering down
2023-02-10T20:57:43.235Z,1676062663.235 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-10T20:57:43.235Z,1676062663.235 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:43.242Z,1676062663.242 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-02-10T20:57:43.242Z,1676062663.242 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:43.243Z,1676062663.243 [Onboard](INFO): Join timeout helper Thread ID is 1418
2023-02-10T20:57:43.894Z,1676062663.894 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-02-10T20:57:47.770Z,1676062667.770 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-10T20:57:47.770Z,1676062667.770 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:47.791Z,1676062667.791 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-02-10T20:57:47.791Z,1676062667.791 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:47.791Z,1676062667.791 [DataOverHttps](INFO): Join timeout helper Thread ID is 1419
2023-02-10T20:57:47.910Z,1676062667.910 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-10T20:57:47.910Z,1676062667.910 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:47.919Z,1676062667.919 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-02-10T20:57:47.919Z,1676062667.919 [logger ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:47.919Z,1676062667.919 [logger](INFO): Join timeout helper Thread ID is 1420
2023-02-10T20:57:47.958Z,1676062667.958 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-10T20:57:47.958Z,1676062667.958 [logger ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:47.979Z,1676062667.979 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-02-10T20:57:47.979Z,1676062667.979 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:47.979Z,1676062667.979 [CommandLine](INFO): Join timeout helper Thread ID is 1421
2023-02-10T20:57:48.038Z,1676062668.038 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-10T20:57:48.038Z,1676062668.038 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:48.059Z,1676062668.059 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-02-10T20:57:48.059Z,1676062668.059 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:48.059Z,1676062668.059 [CommandExec](INFO): Join timeout helper Thread ID is 1422
2023-02-10T20:57:48.060Z,1676062668.060 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-02-10T20:57:48.060Z,1676062668.060 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:48.061Z,1676062668.061 [controlThread](INFO): Join timeout helper Thread ID is 1423
2023-02-10T20:57:48.330Z,1676062668.330 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-10T20:57:48.330Z,1676062668.330 [controlThread](DEBUG): Uninitializing ControlThread
2023-02-10T20:57:48.331Z,1676062668.331 [AHRS_M2](INFO): Powering down
2023-02-10T20:57:48.403Z,1676062668.403 [MultiRay](INFO): Powering down.
2023-02-10T20:57:48.404Z,1676062668.404 [NAL9602](INFO): Powering down
2023-02-10T20:57:48.406Z,1676062668.406 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-02-10T20:57:48.407Z,1676062668.407 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-02-10T20:57:48.407Z,1676062668.407 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-02-10T20:57:48.408Z,1676062668.408 [MissionManager](INFO): Uninitializing Mission Default
2023-02-10T20:57:48.408Z,1676062668.408 [Default] Stopped
2023-02-10T20:57:48.408Z,1676062668.408 [Default](DEBUG): Aggregate::uninitialize Default
2023-02-10T20:57:48.408Z,1676062668.408 [Default:B.GoToSurface] Stopped
2023-02-10T20:57:48.408Z,1676062668.408 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-02-10T20:57:48.408Z,1676062668.408 [Default:CheckIn] Stopped
2023-02-10T20:57:48.409Z,1676062668.409 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-10T20:57:48.409Z,1676062668.409 [Default:CheckIn:Read_GPS] Stopped
2023-02-10T20:57:48.411Z,1676062668.411 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-02-10T20:57:48.412Z,1676062668.412 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-02-10T20:57:48.412Z,1676062668.412 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-02-10T20:57:48.412Z,1676062668.412 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-02-10T20:57:48.413Z,1676062668.413 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-02-10T20:57:48.413Z,1676062668.413 [BuoyancyServo](INFO): Powering down
2023-02-10T20:57:48.426Z,1676062668.426 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-02-10T20:57:48.426Z,1676062668.426 [ElevatorServo](INFO): Powering down
2023-02-10T20:57:48.427Z,1676062668.427 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-02-10T20:57:48.427Z,1676062668.427 [MassServo](INFO): Powering down
2023-02-10T20:57:48.428Z,1676062668.428 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-02-10T20:57:48.428Z,1676062668.428 [RudderServo](INFO): Powering down
2023-02-10T20:57:48.429Z,1676062668.429 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-02-10T20:57:48.429Z,1676062668.429 [ThrusterServo](INFO): Powering down
2023-02-10T20:57:48.430Z,1676062668.430 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-02-10T20:57:48.430Z,1676062668.430 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-02-10T20:57:48.431Z,1676062668.431 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-02-10T20:57:48.431Z,1676062668.431 [CBIT](DEBUG): Powering off loads.
2023-02-10T20:57:48.442Z,1676062668.442 [CBIT](DEBUG): Disabling WDT.
2023-02-10T20:57:48.454Z,1676062668.454 [CBIT](DEBUG): Opening all GF detection circuits.
2023-02-10T20:57:48.455Z,1676062668.455 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:48.496Z,1676062668.496 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:48.499Z,1676062668.499 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:48.505Z,1676062668.505 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:48.576Z,1676062668.576 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:48.579Z,1676062668.579 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:48.619Z,1676062668.619 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-10T20:57:48.709Z,1676062668.709 [logger ThreadHandler](INFO): Thread cancelled.