2022-12-20T21:28:05.786Z,1671571685.786 [Supervisor](DEBUG): Initializing supervisor.
2022-12-20T21:28:05.790Z,1671571685.790 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-12-20T21:28:05.791Z,1671571685.791 [SyncHandler](INFO): Protected caller Thread ID is 834
2022-12-20T21:28:05.791Z,1671571685.791 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-12-20T21:28:05.792Z,1671571685.792 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-12-20T21:28:05.792Z,1671571685.792 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 835
2022-12-20T21:28:05.796Z,1671571685.796 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-12-20T21:28:05.813Z,1671571685.813 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-12-20T21:28:05.814Z,1671571685.814 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-12-20T21:28:05.815Z,1671571685.815 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 836
2022-12-20T21:28:05.819Z,1671571685.819 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-12-20T21:28:05.820Z,1671571685.820 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-12-20T21:28:05.820Z,1671571685.820 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837
2022-12-20T21:28:05.822Z,1671571685.822 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-12-20T21:28:05.823Z,1671571685.823 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-12-20T21:28:05.824Z,1671571685.824 [logger ThreadHandler](INFO): Protected caller Thread ID is 838
2022-12-20T21:28:05.827Z,1671571685.827 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-12-20T21:28:05.828Z,1671571685.828 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-12-20T21:28:05.832Z,1671571685.832 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-12-20T21:28:06.303Z,1671571686.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-12-20T21:28:06.305Z,1671571686.305 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-12-20T21:28:06.517Z,1671571686.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-12-20T21:28:06.519Z,1671571686.519 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-12-20T21:28:06.611Z,1671571686.611 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-12-20T21:28:06.838Z,1671571686.838 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-12-20T21:28:06.840Z,1671571686.840 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-12-20T21:28:06.920Z,1671571686.920 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-12-20T21:28:07.018Z,1671571687.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-12-20T21:28:07.020Z,1671571687.020 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-12-20T21:28:07.379Z,1671571687.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-12-20T21:28:07.380Z,1671571687.380 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-12-20T21:28:07.514Z,1671571687.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-12-20T21:28:07.516Z,1671571687.516 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-12-20T21:28:08.018Z,1671571688.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-12-20T21:28:08.020Z,1671571688.020 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-12-20T21:28:08.216Z,1671571688.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-12-20T21:28:08.218Z,1671571688.218 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-12-20T21:28:08.630Z,1671571688.630 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-12-20T21:28:08.631Z,1671571688.631 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-12-20T21:28:08.918Z,1671571688.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-12-20T21:28:08.920Z,1671571688.920 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-12-20T21:28:09.032Z,1671571689.032 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-12-20T21:28:09.033Z,1671571689.033 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-12-20T21:28:10.316Z,1671571690.316 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-12-20T21:28:10.317Z,1671571690.317 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-12-20T21:28:10.746Z,1671571690.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-12-20T21:28:10.748Z,1671571690.748 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-12-20T21:28:10.971Z,1671571690.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-12-20T21:28:10.973Z,1671571690.973 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2022-12-20T21:28:10.976Z,1671571690.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2022-12-20T21:28:11.170Z,1671571691.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2022-12-20T21:28:11.254Z,1671571691.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2022-12-20T21:28:11.392Z,1671571691.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2022-12-20T21:28:11.478Z,1671571691.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2022-12-20T21:28:11.748Z,1671571691.748 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-12-20T21:28:11.749Z,1671571691.749 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2022-12-20T21:28:11.854Z,1671571691.854 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2022-12-20T21:28:11.966Z,1671571691.966 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2022-12-20T21:28:12.080Z,1671571692.080 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2022-12-20T21:28:12.226Z,1671571692.226 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2022-12-20T21:28:12.326Z,1671571692.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2022-12-20T21:28:12.420Z,1671571692.420 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-12-20T21:28:12.450Z,1671571692.450 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-12-20T21:28:12.467Z,1671571692.467 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-12-20T21:28:12.468Z,1671571692.468 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-12-20T21:28:12.650Z,1671571692.650 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-12-20T21:28:12.652Z,1671571692.652 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-12-20T21:28:12.724Z,1671571692.724 [VerticalControl](DEBUG): Construct VerticalControl.
2022-12-20T21:28:12.785Z,1671571692.785 [VerticalControl] Loaded
2022-12-20T21:28:12.785Z,1671571692.785 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-12-20T21:28:12.788Z,1671571692.788 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-12-20T21:28:12.830Z,1671571692.830 [HorizontalControl] Loaded
2022-12-20T21:28:12.830Z,1671571692.830 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-12-20T21:28:12.832Z,1671571692.832 [SpeedControl](DEBUG): Construct SpeedControl.
2022-12-20T21:28:12.836Z,1671571692.836 [SpeedControl] Loaded
2022-12-20T21:28:12.836Z,1671571692.836 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-12-20T21:28:12.839Z,1671571692.839 [LoopControl](DEBUG): Construct LoopControl.
2022-12-20T21:28:12.839Z,1671571692.839 [LoopControl] Loaded
2022-12-20T21:28:12.839Z,1671571692.839 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-12-20T21:28:12.840Z,1671571692.840 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-12-20T21:28:12.840Z,1671571692.840 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-12-20T21:28:13.036Z,1671571693.036 [BuoyancyServo] Loaded
2022-12-20T21:28:13.036Z,1671571693.036 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-12-20T21:28:13.056Z,1671571693.056 [ElevatorServo] Loaded
2022-12-20T21:28:13.056Z,1671571693.056 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-12-20T21:28:13.076Z,1671571693.076 [MassServo] Loaded
2022-12-20T21:28:13.076Z,1671571693.076 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-12-20T21:28:13.095Z,1671571693.095 [RudderServo] Loaded
2022-12-20T21:28:13.095Z,1671571693.095 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-12-20T21:28:13.110Z,1671571693.110 [ThrusterHE] Loaded
2022-12-20T21:28:13.110Z,1671571693.110 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-12-20T21:28:13.111Z,1671571693.111 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-12-20T21:28:13.111Z,1671571693.111 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-12-20T21:28:13.184Z,1671571693.184 [DepthRateCalculator] Loaded
2022-12-20T21:28:13.185Z,1671571693.185 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-12-20T21:28:13.190Z,1671571693.190 [PitchRateCalculator] Loaded
2022-12-20T21:28:13.190Z,1671571693.190 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-12-20T21:28:13.200Z,1671571693.200 [SpeedCalculator] Loaded
2022-12-20T21:28:13.200Z,1671571693.200 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-12-20T21:28:13.205Z,1671571693.205 [YawRateCalculator] Loaded
2022-12-20T21:28:13.205Z,1671571693.205 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-12-20T21:28:13.225Z,1671571693.225 [ElevatorOffsetCalculator] Loaded
2022-12-20T21:28:13.225Z,1671571693.225 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-12-20T21:28:13.225Z,1671571693.225 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-12-20T21:28:13.226Z,1671571693.226 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-12-20T21:28:13.369Z,1671571693.369 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-12-20T21:28:13.370Z,1671571693.370 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-12-20T21:28:13.384Z,1671571693.384 [NavChart] Loaded
2022-12-20T21:28:13.384Z,1671571693.384 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-12-20T21:28:13.390Z,1671571693.390 [UniversalFixResidualReporter] Loaded
2022-12-20T21:28:13.390Z,1671571693.390 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-12-20T21:28:13.390Z,1671571693.390 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-12-20T21:28:13.391Z,1671571693.391 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-12-20T21:28:14.163Z,1671571694.163 [AHRS_M2] Loaded
2022-12-20T21:28:14.164Z,1671571694.164 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-12-20T21:28:14.422Z,1671571694.422 [BackseatComponent] Loaded
2022-12-20T21:28:14.422Z,1671571694.422 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-12-20T21:28:14.423Z,1671571694.423 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408D14E0
2022-12-20T21:28:14.424Z,1671571694.424 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921
2022-12-20T21:28:14.426Z,1671571694.426 [LcmUniversalReporter] Loaded
2022-12-20T21:28:14.427Z,1671571694.427 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-12-20T21:28:15.355Z,1671571695.355 [BPC1] Loaded
2022-12-20T21:28:15.355Z,1671571695.355 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-12-20T21:28:15.654Z,1671571695.654 [DataOverHttps] Loaded
2022-12-20T21:28:15.655Z,1671571695.655 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-12-20T21:28:15.666Z,1671571695.666 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409014E0
2022-12-20T21:28:15.666Z,1671571695.666 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2022-12-20T21:28:15.696Z,1671571695.696 [Depth_Keller] Loaded
2022-12-20T21:28:15.697Z,1671571695.697 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-12-20T21:28:15.702Z,1671571695.702 [DropWeight] Loaded
2022-12-20T21:28:15.702Z,1671571695.702 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-12-20T21:28:15.803Z,1671571695.803 [NAL9602] Loaded
2022-12-20T21:28:15.803Z,1671571695.803 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-12-20T21:28:15.864Z,1671571695.864 [Onboard] Loaded
2022-12-20T21:28:15.865Z,1671571695.865 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-12-20T21:28:15.904Z,1671571695.904 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2022-12-20T21:28:15.904Z,1671571695.904 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2022-12-20T21:28:15.953Z,1671571695.953 [Power24vConverter] Loaded
2022-12-20T21:28:15.953Z,1671571695.953 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-12-20T21:28:15.980Z,1671571695.980 [Radio_Surface] Loaded
2022-12-20T21:28:15.980Z,1671571695.980 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-12-20T21:28:15.981Z,1671571695.981 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2022-12-20T21:28:15.981Z,1671571695.981 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2022-12-20T21:28:16.075Z,1671571696.075 [DAT] Loaded
2022-12-20T21:28:16.076Z,1671571696.076 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-12-20T21:28:16.077Z,1671571696.077 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2022-12-20T21:28:16.077Z,1671571696.077 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925
2022-12-20T21:28:16.078Z,1671571696.078 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-12-20T21:28:16.078Z,1671571696.078 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-12-20T21:28:16.164Z,1671571696.164 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-12-20T21:28:16.164Z,1671571696.164 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-12-20T21:28:16.365Z,1671571696.365 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-12-20T21:28:16.366Z,1671571696.366 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-12-20T21:28:16.410Z,1671571696.410 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-12-20T21:28:16.411Z,1671571696.411 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-12-20T21:28:16.637Z,1671571696.637 [CTD_Seabird] Loaded
2022-12-20T21:28:16.638Z,1671571696.638 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-12-20T21:28:16.639Z,1671571696.639 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ABC4E0
2022-12-20T21:28:16.639Z,1671571696.639 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926
2022-12-20T21:28:16.660Z,1671571696.660 [PAR_Licor] Loaded
2022-12-20T21:28:16.660Z,1671571696.660 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-12-20T21:28:16.692Z,1671571696.692 [WetLabsBB2FL] Loaded
2022-12-20T21:28:16.693Z,1671571696.693 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-12-20T21:28:16.694Z,1671571696.694 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AEC4E0
2022-12-20T21:28:16.694Z,1671571696.694 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927
2022-12-20T21:28:16.695Z,1671571696.695 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-12-20T21:28:16.695Z,1671571696.695 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-12-20T21:28:17.084Z,1671571697.084 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-12-20T21:28:17.085Z,1671571697.085 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-12-20T21:28:17.270Z,1671571697.270 [SBIT](DEBUG): Construct Startup Built In Test.
2022-12-20T21:28:17.279Z,1671571697.279 [SBIT] Loaded
2022-12-20T21:28:17.280Z,1671571697.280 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-12-20T21:28:17.283Z,1671571697.283 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-12-20T21:28:17.296Z,1671571697.296 [IBIT] Loaded
2022-12-20T21:28:17.297Z,1671571697.297 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-12-20T21:28:17.303Z,1671571697.303 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-12-20T21:28:17.408Z,1671571697.408 [CBIT] Loaded
2022-12-20T21:28:17.409Z,1671571697.409 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-12-20T21:28:17.409Z,1671571697.409 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-12-20T21:28:17.415Z,1671571697.415 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-12-20T21:28:17.418Z,1671571697.418 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-12-20T21:28:17.429Z,1671571697.429 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-12-20T21:28:17.430Z,1671571697.430 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCF4E0
2022-12-20T21:28:17.430Z,1671571697.430 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2022-12-20T21:28:17.434Z,1671571697.434 [Supervisor](INFO): Main Thread ID is 829
2022-12-20T21:28:17.435Z,1671571697.435 [Supervisor](DEBUG): Running supervisor.
2022-12-20T21:28:17.435Z,1671571697.435 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2022-12-20T21:28:17.436Z,1671571697.436 [CommandExec](INFO): Initializing the command executive.
2022-12-20T21:28:17.437Z,1671571697.437 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2022-12-20T21:28:17.439Z,1671571697.439 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2022-12-20T21:28:17.440Z,1671571697.440 [controlThread](DEBUG): Initializing ControlThread
2022-12-20T21:28:17.441Z,1671571697.441 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-12-20T21:28:17.442Z,1671571697.442 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-12-20T21:28:17.443Z,1671571697.443 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-12-20T21:28:17.443Z,1671571697.443 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-12-20T21:28:17.445Z,1671571697.445 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-12-20T21:28:17.445Z,1671571697.445 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-12-20T21:28:17.446Z,1671571697.446 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-12-20T21:28:17.446Z,1671571697.446 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-12-20T21:28:17.446Z,1671571697.446 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-12-20T21:28:17.448Z,1671571697.448 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-12-20T21:28:17.448Z,1671571697.448 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-12-20T21:28:17.456Z,1671571697.456 [SBIT](INFO): Initialize SBIT Component.
2022-12-20T21:28:17.456Z,1671571697.456 [SBIT](IMPORTANT): git: 2022-12-14
2022-12-20T21:28:17.457Z,1671571697.457 [SBIT](INFO): git hash: c3aea31d7798b2e77c1fbaec46e28ec3366e0fcc
2022-12-20T21:28:17.457Z,1671571697.457 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-12-20T21:28:17.458Z,1671571697.458 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-12-20T21:28:17.459Z,1671571697.459 [SBIT](INFO): Beginning SBIT in 99.000000 seconds.
2022-12-20T21:28:17.460Z,1671571697.460 [IBIT](INFO): Initialize IBIT Component.
2022-12-20T21:28:17.461Z,1671571697.461 [CBIT](DEBUG): Initialize CBIT Component.
2022-12-20T21:28:17.462Z,1671571697.462 [logger ThreadHandler](INFO): Handler Thread ID is 932
2022-12-20T21:28:17.472Z,1671571697.472 [CBIT](DEBUG): Initialized mux pins.
2022-12-20T21:28:17.472Z,1671571697.472 [CBIT](DEBUG): Initializing the watchdog timer.
2022-12-20T21:28:17.480Z,1671571697.480 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 933
2022-12-20T21:28:17.492Z,1671571697.492 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2022-12-20T21:28:17.493Z,1671571697.493 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-12-20T21:28:17.496Z,1671571697.496 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-12-20T21:28:17.496Z,1671571697.496 [CBIT](DEBUG): Initializing heartbeat.
2022-12-20T21:28:17.504Z,1671571697.504 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2022-12-20T21:28:17.522Z,1671571697.522 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2022-12-20T21:28:17.541Z,1671571697.541 [DAT ThreadHandler](INFO): Handler Thread ID is 937
2022-12-20T21:28:17.541Z,1671571697.541 [DAT](INFO): Powering up
2022-12-20T21:28:17.542Z,1671571697.542 [DAT](DEBUG): Initializing DAT.
2022-12-20T21:28:17.545Z,1671571697.545 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2022-12-20T21:28:17.546Z,1671571697.546 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-12-20T21:28:17.549Z,1671571697.549 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2022-12-20T21:28:17.552Z,1671571697.552 [WetLabsBB2FL](INFO): Powering up
2022-12-20T21:28:17.553Z,1671571697.553 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2022-12-20T21:28:17.561Z,1671571697.561 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-12-20T21:28:17.561Z,1671571697.561 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-12-20T21:28:17.561Z,1671571697.561 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-12-20T21:28:17.561Z,1671571697.561 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-12-20T21:28:17.562Z,1671571697.562 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-12-20T21:28:17.562Z,1671571697.562 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-12-20T21:28:17.562Z,1671571697.562 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-12-20T21:28:17.562Z,1671571697.562 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-12-20T21:28:17.568Z,1671571697.568 [CBIT](DEBUG): Deactivating GF circuits.
2022-12-20T21:28:17.568Z,1671571697.568 [CBIT](DEBUG): Deactivating emergency mode.
2022-12-20T21:28:17.604Z,1671571697.604 [CBIT](DEBUG): Backplane powered.
2022-12-20T21:28:17.605Z,1671571697.605 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-12-20T21:28:17.653Z,1671571697.653 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-12-20T21:28:17.680Z,1671571697.680 [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>


2022-12-20T21:28:17.680Z,1671571697.680 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-12-20T21:28:17.772Z,1671571697.772 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-12-20T21:28:17.774Z,1671571697.774 [Default:A.Wait](DEBUG): Construct Wait.
2022-12-20T21:28:17.776Z,1671571697.776 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-12-20T21:28:17.815Z,1671571697.815 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-12-20T21:28:17.818Z,1671571697.818 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-12-20T21:28:17.840Z,1671571697.840 [Default:E.Execute](DEBUG): Construct Execute.
2022-12-20T21:28:17.843Z,1671571697.843 [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>


2022-12-20T21:28:17.864Z,1671571697.864 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-20T21:28:17.870Z,1671571697.870 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-12-20T21:28:17.928Z,1671571697.928 [Radio_Surface](INFO): Powering up
2022-12-20T21:28:18.026Z,1671571698.026 [Power24vConverter](INFO): Powering up.
2022-12-20T21:28:18.102Z,1671571698.102 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-12-20T21:28:18.108Z,1671571698.108 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-12-20T21:28:18.109Z,1671571698.109 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-12-20T21:28:18.116Z,1671571698.116 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-12-20T21:28:18.117Z,1671571698.117 [MassServo](DEBUG): Initializing EZServoServo.
2022-12-20T21:28:18.124Z,1671571698.124 [MassServo](DEBUG): Initializing MassServo.
2022-12-20T21:28:18.125Z,1671571698.125 [RudderServo](DEBUG): Initializing EZServoServo.
2022-12-20T21:28:18.132Z,1671571698.132 [RudderServo](DEBUG): Initializing RudderServo.
2022-12-20T21:28:18.133Z,1671571698.133 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-12-20T21:28:18.140Z,1671571698.140 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-12-20T21:28:18.346Z,1671571698.346 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-12-20T21:28:18.346Z,1671571698.346 [DropWeight] Hardware Fault, FailCount= 1
2022-12-20T21:28:18.346Z,1671571698.346 [DropWeight](ERROR): Hardware Fault
2022-12-20T21:28:18.378Z,1671571698.378 [CommandExec](FAULT): Scheduling is paused
2022-12-20T21:28:18.379Z,1671571698.379 [CBIT](INFO): Critical error at 20221220T212818
2022-12-20T21:28:18.379Z,1671571698.379 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-12-20T21:28:18.389Z,1671571698.389 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-12-20T21:28:18.390Z,1671571698.390 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-12-20T21:28:19.032Z,1671571699.032 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-12-20T21:28:19.032Z,1671571699.032 [RudderServo](FAULT): Rudder failed to initialize
2022-12-20T21:28:19.032Z,1671571699.032 [RudderServo] Communications Fault, FailCount= 1
2022-12-20T21:28:19.032Z,1671571699.032 [RudderServo](ERROR): Communications Fault
2022-12-20T21:28:19.034Z,1671571699.034 [CBIT](INFO): Critical error at 20221220T212818
2022-12-20T21:28:19.036Z,1671571699.036 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-12-20T21:28:19.257Z,1671571699.257 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-12-20T21:28:19.257Z,1671571699.257 [RudderServo](INFO): Powering down
2022-12-20T21:28:19.660Z,1671571699.660 [WetLabsBB2FL](INFO): Powering down
2022-12-20T21:28:19.912Z,1671571699.912 [RudderServo](DEBUG): Initializing EZServoServo.
2022-12-20T21:28:20.033Z,1671571700.033 [RudderServo](DEBUG): Initializing RudderServo.
2022-12-20T21:28:20.037Z,1671571700.037 [CBIT](INFO): Clearing failed state for component RudderServo
2022-12-20T21:28:20.037Z,1671571700.037 [RudderServo] No Fault, FailCount= 1
2022-12-20T21:28:23.817Z,1671571703.817 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-12-20T21:28:29.636Z,1671571709.636 [DAT](INFO): DAT read: 

2022-12-20T21:28:29.638Z,1671571709.638 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2022-12-20T21:28:31.401Z,1671571711.401 [DAT](INFO): DAT read: MF Frequency Band

2022-12-20T21:28:31.402Z,1671571711.402 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2022-12-20T21:28:31.402Z,1671571711.402 [DAT](INFO): DAT read: Dec 20 2022 21:28:25 

2022-12-20T21:28:32.409Z,1671571712.409 [DAT](INFO): DAT read: Features enabled [Bearing]

2022-12-20T21:28:32.410Z,1671571712.410 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2022-12-20T21:28:32.410Z,1671571712.410 [DAT](INFO): commRate: 800
2022-12-20T21:28:34.476Z,1671571714.476 [DAT](INFO): entering command mode
2022-12-20T21:28:34.676Z,1671571714.676 [DAT](INFO): DAT read: 

2022-12-20T21:28:34.677Z,1671571714.677 [DAT](INFO): DAT read: user:1>

2022-12-20T21:28:34.677Z,1671571714.677 [DAT](INFO): setting verbose to 3
2022-12-20T21:28:34.928Z,1671571714.928 [DAT](INFO): DAT read: user:1>

2022-12-20T21:28:34.930Z,1671571714.930 [DAT](INFO): DAT read: Verbose         | 3           

2022-12-20T21:28:34.930Z,1671571714.930 [DAT](INFO): set verbose to 3
2022-12-20T21:28:34.930Z,1671571714.930 [DAT](INFO): setting DatVerbose to 27440
2022-12-20T21:28:35.180Z,1671571715.180 [DAT](INFO): DAT read: user:2>

2022-12-20T21:28:35.181Z,1671571715.181 [DAT](INFO): DAT read: DatVerbose      | 27440       

2022-12-20T21:28:35.182Z,1671571715.182 [DAT](INFO): set DatVerbose to 27440
2022-12-20T21:28:35.182Z,1671571715.182 [DAT](INFO): setting transmit power to 8
2022-12-20T21:28:35.432Z,1671571715.432 [DAT](INFO): DAT read: user:3>

2022-12-20T21:28:35.433Z,1671571715.433 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2022-12-20T21:28:35.434Z,1671571715.434 [DAT](INFO): set transmit power to 8
2022-12-20T21:28:35.434Z,1671571715.434 [DAT](INFO): setting local address to 5
2022-12-20T21:28:35.684Z,1671571715.684 [DAT](INFO): DAT read: user:4>

2022-12-20T21:28:35.685Z,1671571715.685 [DAT](INFO): DAT read: LocalAddr       | 5           

2022-12-20T21:28:35.686Z,1671571715.686 [DAT](INFO): set local address to 5
2022-12-20T21:28:35.687Z,1671571715.687 [DAT](INFO): Setting time to: 21:28:35 And date to:12/20/2022
2022-12-20T21:28:35.937Z,1671571715.937 [DAT](INFO): DAT read: user:5>

2022-12-20T21:28:35.938Z,1671571715.938 [DAT](INFO): DAT read: Tue Dec 20, 2022  21:28:35  

2022-12-20T21:28:35.938Z,1671571715.938 [DAT](INFO): Local DAT time set to Tue Dec 20, 2022  21:28:35  

2022-12-20T21:28:46.157Z,1671571726.157 [NAL9602](INFO): Powering up NAL9602
2022-12-20T21:28:57.062Z,1671571737.062 [NAL9602](INFO): NAL9602 initialized
2022-12-20T21:29:38.682Z,1671571778.682 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:29:52.422Z,1671571792.422 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:29:56.888Z,1671571796.888 [SBIT](IMPORTANT): Beginning Startup BIT
2022-12-20T21:29:56.893Z,1671571796.893 [CBIT](IMPORTANT): Beginning ground fault scan
2022-12-20T21:30:05.749Z,1671571805.749 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:30:07.823Z,1671571807.823 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006806
CHAN A1 (24V): -0.007875
CHAN A2 (12V): -0.004592
CHAN A3 (5V): -0.002571
CHAN B0 (3.3V): -0.000942
CHAN B1 (3.15aV): -0.001317
CHAN B2 (3.15bV): -0.001706
CHAN B3 (GND): -0.000229
OPEN: 0.004468
Full Scale: +/- 1 mA
2022-12-20T21:30:19.083Z,1671571819.083 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:30:32.413Z,1671571832.413 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:30:45.750Z,1671571845.750 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:30:51.031Z,1671571851.031 [SBIT](IMPORTANT): SBIT PASSED
2022-12-20T21:30:51.031Z,1671571851.031 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-12-20T21:30:51.032Z,1671571851.032 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2022-12-20T21:30:51.033Z,1671571851.033 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2022-12-20T21:30:51.033Z,1671571851.033 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2022-12-20T21:30:51.033Z,1671571851.033 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;

2022-12-20T21:30:51.033Z,1671571851.033 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2022-12-20T21:30:51.033Z,1671571851.033 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2022-12-20T21:30:51.033Z,1671571851.033 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2022-12-20T21:30:51.033Z,1671571851.033 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=30 ampere_hour;

2022-12-20T21:30:51.033Z,1671571851.033 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;

2022-12-20T21:30:51.034Z,1671571851.034 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=324.282117 cubic_centimeter;

2022-12-20T21:30:51.034Z,1671571851.034 [SBIT](IMPORTANT): VerticalControl.massDefault=20 millimeter;

2022-12-20T21:30:51.413Z,1671571851.413 [MissionManager](IMPORTANT): Started mission Startup
2022-12-20T21:30:51.413Z,1671571851.413 [Startup] Running Loop=1
2022-12-20T21:30:51.413Z,1671571851.413 [Startup](DEBUG): Aggregate::initialize Startup
2022-12-20T21:30:51.413Z,1671571851.413 [Startup:A.GoToSurface] Running Loop=1
2022-12-20T21:30:51.413Z,1671571851.413 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-12-20T21:30:51.414Z,1671571851.414 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-12-20T21:30:51.414Z,1671571851.414 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-12-20T21:30:51.415Z,1671571851.415 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-12-20T21:30:51.415Z,1671571851.415 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-12-20T21:30:51.416Z,1671571851.416 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-12-20T21:30:51.417Z,1671571851.417 [Startup:StartupSatComms] Running Loop=1
2022-12-20T21:30:51.417Z,1671571851.417 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-12-20T21:30:51.417Z,1671571851.417 [Startup:StartupSatComms:A] Running Loop=1
2022-12-20T21:30:51.813Z,1671571851.813 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-12-20T21:30:59.077Z,1671571859.077 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:31:12.410Z,1671571872.410 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:31:17.686Z,1671571877.686 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-12-20T21:31:17.688Z,1671571877.688 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-20T21:31:17.699Z,1671571877.699 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-20T21:31:18.093Z,1671571878.093 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-20T21:31:18.093Z,1671571878.093 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-12-20T21:31:25.395Z,1671571885.395 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:31:38.667Z,1671571898.667 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:31:51.609Z,1671571911.609 [Startup:StartupSatComms:A](INFO): Timed out from 2022-12-20T21:30:51.4Z
2022-12-20T21:31:51.609Z,1671571911.609 [Startup:StartupSatComms:A] Stopped
2022-12-20T21:31:51.609Z,1671571911.609 [Startup:StartupSatComms:B] Running Loop=1
2022-12-20T21:31:51.651Z,1671571911.651 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004568
2022-12-20T21:31:52.070Z,1671571912.070 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-12-20T21:31:52.469Z,1671571912.469 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:31:58.891Z,1671571918.891 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221220T185823/Courier0085.lzma
2022-12-20T21:31:59.894Z,1671571919.894 [DataOverHttps](INFO): Moved sent file to Logs/20221220T185823/Courier0085.lzma.bak
2022-12-20T21:31:59.894Z,1671571919.894 [DataOverHttps](INFO): SBD MOMSN=17400058
2022-12-20T21:32:10.365Z,1671571930.365 [CommandExec](IMPORTANT): got command failComponent
2022-12-20T21:32:10.365Z,1671571930.365 [CommandExec](IMPORTANT): Failed components:
2022-12-20T21:32:10.365Z,1671571930.365 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2022-12-20T21:32:13.360Z,1671571933.360 [CommandLine](INFO): End of History
2022-12-20T21:32:13.360Z,1671571933.360 [CommandLine](INFO): End of History
2022-12-20T21:32:13.361Z,1671571933.361 [CommandLine](INFO): End of History
2022-12-20T21:32:13.362Z,1671571933.362 [CommandLine](INFO): End of History
2022-12-20T21:32:13.363Z,1671571933.363 [CommandLine](INFO): End of History
2022-12-20T21:32:13.397Z,1671571933.397 [CommandLine](INFO): End of History
2022-12-20T21:32:13.398Z,1671571933.398 [CommandLine](INFO): End of History
2022-12-20T21:32:13.399Z,1671571933.399 [CommandLine](INFO): End of History
2022-12-20T21:32:13.474Z,1671571933.474 [CommandLine](INFO): End of History
2022-12-20T21:32:13.475Z,1671571933.475 [CommandLine](INFO): End of History
2022-12-20T21:32:13.475Z,1671571933.475 [CommandLine](INFO): End of History
2022-12-20T21:32:13.534Z,1671571933.534 [CommandLine](INFO): End of History
2022-12-20T21:32:13.535Z,1671571933.535 [CommandLine](INFO): End of History
2022-12-20T21:32:13.537Z,1671571933.537 [CommandLine](INFO): End of History
2022-12-20T21:32:13.565Z,1671571933.565 [CommandLine](INFO): End of History
2022-12-20T21:32:13.566Z,1671571933.566 [CommandLine](INFO): End of History
2022-12-20T21:32:13.567Z,1671571933.567 [CommandLine](INFO): End of History
2022-12-20T21:32:15.872Z,1671571935.872 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20221220T212805/Courier0000.lzma
2022-12-20T21:32:16.873Z,1671571936.873 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0000.lzma.bak
2022-12-20T21:32:16.874Z,1671571936.874 [DataOverHttps](INFO): SBD MOMSN=17400060
2022-12-20T21:32:32.423Z,1671571952.423 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20221220T185823/Express0086.lzma
2022-12-20T21:32:33.426Z,1671571953.426 [DataOverHttps](INFO): Moved sent file to Logs/20221220T185823/Express0086.lzma.bak
2022-12-20T21:32:33.427Z,1671571953.427 [DataOverHttps](INFO): SBD MOMSN=17400065
2022-12-20T21:32:51.361Z,1671571971.361 [DataOverHttps](INFO): Sending 1046 bytes from file Logs/20221220T212805/Express0001.lzma
2022-12-20T21:32:51.858Z,1671571971.858 [Startup:StartupSatComms:B](INFO): Timed out from 2022-12-20T21:31:51.6Z
2022-12-20T21:32:51.858Z,1671571971.858 [Startup:StartupSatComms:B] Stopped
2022-12-20T21:32:51.859Z,1671571971.859 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-12-20T21:32:51.859Z,1671571971.859 [Startup:StartupSatComms] Stopped
2022-12-20T21:32:51.859Z,1671571971.859 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-12-20T21:32:51.860Z,1671571971.860 [Startup](INFO): Completed Startup
2022-12-20T21:32:51.860Z,1671571971.860 [MissionManager](INFO): Startup is completed.
2022-12-20T21:32:51.860Z,1671571971.860 [MissionManager](INFO): Uninitializing Mission Startup
2022-12-20T21:32:51.860Z,1671571971.860 [Startup] Stopped
2022-12-20T21:32:51.860Z,1671571971.860 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-12-20T21:32:51.860Z,1671571971.860 [Startup:A.GoToSurface] Stopped
2022-12-20T21:32:51.860Z,1671571971.860 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-12-20T21:32:52.262Z,1671571972.262 [MissionManager](IMPORTANT): Started mission Default
2022-12-20T21:32:52.262Z,1671571972.262 [Default] Running Loop=1
2022-12-20T21:32:52.262Z,1671571972.262 [Default](DEBUG): Aggregate::initialize Default
2022-12-20T21:32:52.262Z,1671571972.262 [Default:B.GoToSurface] Running Loop=1
2022-12-20T21:32:52.262Z,1671571972.262 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-12-20T21:32:52.263Z,1671571972.263 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-12-20T21:32:52.263Z,1671571972.263 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-12-20T21:32:52.263Z,1671571972.263 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-12-20T21:32:52.264Z,1671571972.264 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-12-20T21:32:52.264Z,1671571972.264 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-12-20T21:32:52.264Z,1671571972.264 [Default:A.Wait] Running Loop=1
2022-12-20T21:32:52.265Z,1671571972.265 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-12-20T21:32:52.362Z,1671571972.362 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0001.lzma.bak
2022-12-20T21:32:52.362Z,1671571972.362 [DataOverHttps](INFO): SBD MOMSN=17400071
2022-12-20T21:33:05.601Z,1671571985.601 [Default:A.Wait](INFO): Done Waiting.
2022-12-20T21:33:05.601Z,1671571985.601 [Default:A.Wait] Stopped
2022-12-20T21:33:05.602Z,1671571985.602 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T21:33:06.018Z,1671571986.018 [Default:CheckIn] Running Loop=1
2022-12-20T21:33:06.018Z,1671571986.018 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T21:33:06.018Z,1671571986.018 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T21:33:06.427Z,1671571986.427 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-12-20T21:34:00.130Z,1671572040.130 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T21:34:01.348Z,1671572041.348 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213400.00,A,3648.13110,N,12147.21602,W,0.019,0.00,201222,,,A*7C
2022-12-20T21:34:01.351Z,1671572041.351 [NAL9602](INFO): GPS fix at 20221220T213400: (36.802185, -121.786934)
2022-12-20T21:34:01.366Z,1671572041.366 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T21:34:01.366Z,1671572041.366 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T21:34:01.781Z,1671572041.781 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-12-20T21:34:09.060Z,1671572049.060 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20221220T212805/Courier0004.lzma
2022-12-20T21:34:10.061Z,1671572050.061 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0004.lzma.bak
2022-12-20T21:34:10.062Z,1671572050.062 [DataOverHttps](INFO): SBD MOMSN=17400095
2022-12-20T21:34:26.872Z,1671572066.872 [DataOverHttps](INFO): Sending 391 bytes from file Logs/20221220T212805/Express0005.lzma
2022-12-20T21:34:27.874Z,1671572067.874 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0005.lzma.bak
2022-12-20T21:34:27.874Z,1671572067.874 [DataOverHttps](INFO): SBD MOMSN=17400098
2022-12-20T21:34:29.241Z,1671572069.241 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T21:34:29.241Z,1671572069.241 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T21:34:29.241Z,1671572069.241 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T21:34:33.690Z,1671572073.690 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T21:38:32.340Z,1671572312.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-12-20T21:38:34.348Z,1671572314.348 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.262867
2022-12-20T21:39:07.481Z,1671572347.481 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-12-20T21:39:14.525Z,1671572354.525 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:39:27.861Z,1671572367.861 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:39:29.894Z,1671572369.894 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T21:39:29.894Z,1671572369.894 [Default:CheckIn:C.Wait] Stopped
2022-12-20T21:39:29.894Z,1671572369.894 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T21:39:29.895Z,1671572369.895 [Default:CheckIn:D] Running Loop=1
2022-12-20T21:39:30.313Z,1671572370.313 [Default:CheckIn:D] Stopped
2022-12-20T21:39:30.313Z,1671572370.313 [Default:CheckIn:E] Running Loop=1
2022-12-20T21:39:30.695Z,1671572370.695 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.634183 min 
2022-12-20T21:39:30.695Z,1671572370.695 [Default:CheckIn:E] Stopped
2022-12-20T21:39:30.695Z,1671572370.695 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T21:39:30.695Z,1671572370.695 [Default:CheckIn] Stopped
2022-12-20T21:39:30.695Z,1671572370.695 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T21:39:30.695Z,1671572370.695 [Default:CheckIn](INFO): Running loop #2
2022-12-20T21:39:30.695Z,1671572370.695 [Default:CheckIn] Running Loop=2
2022-12-20T21:39:30.695Z,1671572370.695 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T21:39:30.696Z,1671572370.696 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T21:39:41.189Z,1671572381.189 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:39:42.620Z,1671572382.620 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-12-20T21:39:54.924Z,1671572394.924 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:40:08.257Z,1671572408.257 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:40:17.760Z,1671572417.760 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-12-20T21:40:21.993Z,1671572421.993 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:40:35.323Z,1671572435.323 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:40:48.655Z,1671572448.655 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:40:52.908Z,1671572452.908 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-12-20T21:41:01.989Z,1671572461.989 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:41:14.376Z,1671572474.376 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002917
2022-12-20T21:41:15.320Z,1671572475.320 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:41:49.662Z,1671572509.662 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-12-20T21:42:52.193Z,1671572572.193 [DataOverHttps](IMPORTANT): SBD MTMSN=20221220T214251
2022-12-20T21:42:59.628Z,1671572579.628 [DataOverHttps](INFO): Received command: ibit
2022-12-20T21:42:59.720Z,1671572579.720 [CommandExec](IMPORTANT): got command ibit
2022-12-20T21:42:59.999Z,1671572579.999 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-12-20T21:42:59.999Z,1671572579.999 [IBIT](IMPORTANT): Beginning control surface checks.
2022-12-20T21:43:00.023Z,1671572580.023 [CBIT](IMPORTANT): Beginning ground fault scan
2022-12-20T21:43:10.918Z,1671572590.918 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006586
CHAN A1 (24V): -0.007934
CHAN A2 (12V): -0.005058
CHAN A3 (5V): -0.002072
CHAN B0 (3.3V): -0.000340
CHAN B1 (3.15aV): -0.001288
CHAN B2 (3.15bV): -0.001590
CHAN B3 (GND): -0.000095
OPEN: 0.004372
Full Scale: +/- 1 mA
2022-12-20T21:43:18.624Z,1671572598.624 [CBIT](INFO): Clearing failed state for component DropWeight
2022-12-20T21:43:18.624Z,1671572598.624 [DropWeight] No Fault, FailCount= 1
2022-12-20T21:44:35.309Z,1671572675.309 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T21:44:40.972Z,1671572680.972 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214440.00,A,3648.13360,N,12147.21425,W,0.039,208.28,201222,,,D*7A
2022-12-20T21:44:40.975Z,1671572680.975 [NAL9602](INFO): GPS fix at 20221220T214440: (36.802227, -121.786904)
2022-12-20T21:44:44.199Z,1671572684.199 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214443.00,A,3648.13352,N,12147.21415,W,0.078,208.28,201222,,,D*7E
2022-12-20T21:44:44.201Z,1671572684.201 [NAL9602](INFO): GPS fix at 20221220T214443: (36.802225, -121.786902)
2022-12-20T21:44:47.035Z,1671572687.035 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214446.00,A,3648.13348,N,12147.21411,W,0.019,208.28,201222,,,D*73
2022-12-20T21:44:47.038Z,1671572687.038 [NAL9602](INFO): GPS fix at 20221220T214446: (36.802225, -121.786902)
2022-12-20T21:44:49.855Z,1671572689.855 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214449.00,A,3648.13354,N,12147.21409,W,0.019,208.28,201222,,,D*78
2022-12-20T21:44:49.858Z,1671572689.858 [NAL9602](INFO): GPS fix at 20221220T214449: (36.802226, -121.786901)
2022-12-20T21:44:51.086Z,1671572691.086 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2022-12-20T21:44:51.485Z,1671572691.485 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2022-12-20T21:44:51.905Z,1671572691.905 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-12-20T21:44:51.906Z,1671572691.906 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-12-20T21:44:51.906Z,1671572691.906 [IBIT](IMPORTANT): Pressure:9.095975 PSI
2022-12-20T21:44:51.906Z,1671572691.906 [IBIT](IMPORTANT): Humidity:12.135270 %
2022-12-20T21:44:52.316Z,1671572692.316 [IBIT](IMPORTANT): Vehicle Pitch:-2.261611 degrees
2022-12-20T21:44:52.317Z,1671572692.317 [IBIT](IMPORTANT): Vehicle  Roll:-2.655024 degrees
2022-12-20T21:44:52.317Z,1671572692.317 [IBIT](IMPORTANT): Vehicle  Heading:25.018139 degrees
2022-12-20T21:44:52.709Z,1671572692.709 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-12-20T21:44:52.710Z,1671572692.710 [IBIT](IMPORTANT): buoyancyNeutral: 324.282104 cc
2022-12-20T21:44:52.710Z,1671572692.710 [IBIT](IMPORTANT): massDefault: 2.000000 cm
2022-12-20T21:44:52.710Z,1671572692.710 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2022-12-20T21:44:52.711Z,1671572692.711 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2022-12-20T21:44:52.711Z,1671572692.711 [IBIT](IMPORTANT): IBIT FAILED
2022-12-20T21:44:53.142Z,1671572693.142 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214452.00,A,3648.13358,N,12147.21413,W,0.058,208.28,201222,,,D*70
2022-12-20T21:44:53.145Z,1671572693.145 [NAL9602](INFO): GPS fix at 20221220T214452: (36.802226, -121.786902)
2022-12-20T21:44:53.156Z,1671572693.156 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-12-20T21:39:30.7Z
2022-12-20T21:44:53.156Z,1671572693.156 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T21:44:53.156Z,1671572693.156 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T21:45:00.303Z,1671572700.303 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221220T212805/Courier0007.lzma
2022-12-20T21:45:01.305Z,1671572701.305 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0007.lzma.bak
2022-12-20T21:45:01.306Z,1671572701.306 [DataOverHttps](INFO): SBD MOMSN=17400107
2022-12-20T21:45:20.405Z,1671572720.405 [DataOverHttps](INFO): Sending 848 bytes from file Logs/20221220T212805/Express0008.lzma
2022-12-20T21:45:21.406Z,1671572721.406 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0008.lzma.bak
2022-12-20T21:45:21.406Z,1671572721.406 [DataOverHttps](INFO): SBD MOMSN=17400110
2022-12-20T21:45:22.658Z,1671572722.658 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T21:45:22.658Z,1671572722.658 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T21:45:22.659Z,1671572722.659 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T21:45:25.465Z,1671572725.465 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T21:48:37.423Z,1671572917.423 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T21:48:37.425Z,1671572917.425 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2022-12-20T21:48:37.436Z,1671572917.436 [BPC1](INFO): Received data from all battery sticks.
2022-12-20T21:50:23.265Z,1671573023.265 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T21:50:23.266Z,1671573023.266 [Default:CheckIn:C.Wait] Stopped
2022-12-20T21:50:23.266Z,1671573023.266 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T21:50:23.266Z,1671573023.266 [Default:CheckIn:D] Running Loop=1
2022-12-20T21:50:23.678Z,1671573023.678 [Default:CheckIn:D] Stopped
2022-12-20T21:50:23.678Z,1671573023.678 [Default:CheckIn:E] Running Loop=1
2022-12-20T21:50:24.071Z,1671573024.071 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.523592 min 
2022-12-20T21:50:24.071Z,1671573024.071 [Default:CheckIn:E] Stopped
2022-12-20T21:50:24.071Z,1671573024.071 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T21:50:24.071Z,1671573024.071 [Default:CheckIn] Stopped
2022-12-20T21:50:24.071Z,1671573024.071 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T21:50:24.072Z,1671573024.072 [Default:CheckIn](INFO): Running loop #3
2022-12-20T21:50:24.072Z,1671573024.072 [Default:CheckIn] Running Loop=3
2022-12-20T21:50:24.072Z,1671573024.072 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T21:50:24.073Z,1671573024.073 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T21:50:26.083Z,1671573026.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215025.00,A,3648.13304,N,12147.21540,W,0.000,208.28,201222,,,D*76
2022-12-20T21:50:26.085Z,1671573026.085 [NAL9602](INFO): GPS fix at 20221220T215025: (36.802217, -121.786923)
2022-12-20T21:50:26.096Z,1671573026.096 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T21:50:26.096Z,1671573026.096 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T21:50:33.505Z,1671573033.505 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20221220T212805/Courier0012.lzma
2022-12-20T21:50:34.506Z,1671573034.506 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0012.lzma.bak
2022-12-20T21:50:34.506Z,1671573034.506 [DataOverHttps](INFO): SBD MOMSN=17400135
2022-12-20T21:50:53.123Z,1671573053.123 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20221220T212805/Express0014.lzma
2022-12-20T21:50:54.129Z,1671573054.129 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0014.lzma.bak
2022-12-20T21:50:54.129Z,1671573054.129 [DataOverHttps](INFO): SBD MOMSN=17400138
2022-12-20T21:50:55.221Z,1671573055.221 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T21:50:55.221Z,1671573055.221 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T21:50:55.221Z,1671573055.221 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T21:50:56.820Z,1671573056.820 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T21:50:56.900Z,1671573056.900 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T21:55:28.380Z,1671573328.380 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T21:55:55.866Z,1671573355.866 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T21:55:55.867Z,1671573355.867 [Default:CheckIn:C.Wait] Stopped
2022-12-20T21:55:55.867Z,1671573355.867 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T21:55:55.867Z,1671573355.867 [Default:CheckIn:D] Running Loop=1
2022-12-20T21:55:56.271Z,1671573356.271 [Default:CheckIn:D] Stopped
2022-12-20T21:55:56.271Z,1671573356.271 [Default:CheckIn:E] Running Loop=1
2022-12-20T21:55:56.676Z,1671573356.676 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.066807 min 
2022-12-20T21:55:56.676Z,1671573356.676 [Default:CheckIn:E] Stopped
2022-12-20T21:55:56.676Z,1671573356.676 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T21:55:56.676Z,1671573356.676 [Default:CheckIn] Stopped
2022-12-20T21:55:56.676Z,1671573356.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T21:55:56.677Z,1671573356.677 [Default:CheckIn](INFO): Running loop #4
2022-12-20T21:55:56.677Z,1671573356.677 [Default:CheckIn] Running Loop=4
2022-12-20T21:55:56.677Z,1671573356.677 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T21:55:56.677Z,1671573356.677 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T21:55:58.687Z,1671573358.687 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215557.00,A,3648.13313,N,12147.21521,W,0.000,208.28,201222,,,D*77
2022-12-20T21:55:58.690Z,1671573358.690 [NAL9602](INFO): GPS fix at 20221220T215557: (36.802219, -121.786920)
2022-12-20T21:55:58.720Z,1671573358.720 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T21:55:58.720Z,1671573358.720 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T21:56:06.643Z,1671573366.643 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0016.lzma
2022-12-20T21:56:07.646Z,1671573367.646 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0016.lzma.bak
2022-12-20T21:56:07.646Z,1671573367.646 [DataOverHttps](INFO): SBD MOMSN=17400163
2022-12-20T21:56:23.376Z,1671573383.376 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20221220T212805/Express0017.lzma
2022-12-20T21:56:24.378Z,1671573384.378 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0017.lzma.bak
2022-12-20T21:56:24.378Z,1671573384.378 [DataOverHttps](INFO): SBD MOMSN=17400166
2022-12-20T21:56:25.771Z,1671573385.771 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T21:56:25.771Z,1671573385.771 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T21:56:25.771Z,1671573385.771 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T21:56:31.409Z,1671573391.409 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T22:01:26.460Z,1671573686.460 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T22:01:26.460Z,1671573686.460 [Default:CheckIn:C.Wait] Stopped
2022-12-20T22:01:26.460Z,1671573686.460 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T22:01:26.460Z,1671573686.460 [Default:CheckIn:D] Running Loop=1
2022-12-20T22:01:26.857Z,1671573686.857 [Default:CheckIn:D] Stopped
2022-12-20T22:01:26.857Z,1671573686.857 [Default:CheckIn:E] Running Loop=1
2022-12-20T22:01:27.255Z,1671573687.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.576575 min 
2022-12-20T22:01:27.255Z,1671573687.255 [Default:CheckIn:E] Stopped
2022-12-20T22:01:27.255Z,1671573687.255 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T22:01:27.255Z,1671573687.255 [Default:CheckIn] Stopped
2022-12-20T22:01:27.255Z,1671573687.255 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T22:01:27.255Z,1671573687.255 [Default:CheckIn](INFO): Running loop #5
2022-12-20T22:01:27.256Z,1671573687.256 [Default:CheckIn] Running Loop=5
2022-12-20T22:01:27.256Z,1671573687.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T22:01:27.256Z,1671573687.256 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T22:01:29.272Z,1671573689.272 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220128.00,A,3648.13468,N,12147.21584,W,0.000,208.28,201222,,,D*79
2022-12-20T22:01:29.274Z,1671573689.274 [NAL9602](INFO): GPS fix at 20221220T220128: (36.802245, -121.786931)
2022-12-20T22:01:29.285Z,1671573689.285 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T22:01:29.285Z,1671573689.285 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T22:01:36.995Z,1671573696.995 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221220T212805/Courier0019.lzma
2022-12-20T22:01:37.998Z,1671573697.998 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0019.lzma.bak
2022-12-20T22:01:37.998Z,1671573697.998 [DataOverHttps](INFO): SBD MOMSN=17400172
2022-12-20T22:01:53.675Z,1671573713.675 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20221220T212805/Express0020.lzma
2022-12-20T22:01:54.678Z,1671573714.678 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0020.lzma.bak
2022-12-20T22:01:54.678Z,1671573714.678 [DataOverHttps](INFO): SBD MOMSN=17400175
2022-12-20T22:01:55.948Z,1671573715.948 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T22:01:55.948Z,1671573715.948 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T22:01:55.948Z,1671573715.948 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T22:01:59.969Z,1671573719.969 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T22:02:00.044Z,1671573720.044 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T22:06:31.535Z,1671573991.535 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T22:06:56.590Z,1671574016.590 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T22:06:56.591Z,1671574016.591 [Default:CheckIn:C.Wait] Stopped
2022-12-20T22:06:56.591Z,1671574016.591 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T22:06:56.591Z,1671574016.591 [Default:CheckIn:D] Running Loop=1
2022-12-20T22:06:56.990Z,1671574016.990 [Default:CheckIn:D] Stopped
2022-12-20T22:06:56.990Z,1671574016.990 [Default:CheckIn:E] Running Loop=1
2022-12-20T22:06:57.395Z,1671574017.395 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.078794 min 
2022-12-20T22:06:57.395Z,1671574017.395 [Default:CheckIn:E] Stopped
2022-12-20T22:06:57.395Z,1671574017.395 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T22:06:57.396Z,1671574017.396 [Default:CheckIn] Stopped
2022-12-20T22:06:57.396Z,1671574017.396 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T22:06:57.396Z,1671574017.396 [Default:CheckIn](INFO): Running loop #6
2022-12-20T22:06:57.396Z,1671574017.396 [Default:CheckIn] Running Loop=6
2022-12-20T22:06:57.396Z,1671574017.396 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T22:06:57.396Z,1671574017.396 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T22:06:59.414Z,1671574019.414 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220658.00,A,3648.13297,N,12147.21620,W,0.019,208.28,201222,,,D*7A
2022-12-20T22:06:59.419Z,1671574019.419 [NAL9602](INFO): GPS fix at 20221220T220658: (36.802216, -121.786937)
2022-12-20T22:06:59.438Z,1671574019.438 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T22:06:59.438Z,1671574019.438 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T22:07:09.960Z,1671574029.960 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221220T212805/Courier0022.lzma
2022-12-20T22:07:10.962Z,1671574030.962 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0022.lzma.bak
2022-12-20T22:07:10.963Z,1671574030.963 [DataOverHttps](INFO): SBD MOMSN=17400180
2022-12-20T22:07:26.931Z,1671574046.931 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20221220T212805/Express0023.lzma
2022-12-20T22:07:27.934Z,1671574047.934 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0023.lzma.bak
2022-12-20T22:07:27.934Z,1671574047.934 [DataOverHttps](INFO): SBD MOMSN=17400183
2022-12-20T22:07:29.015Z,1671574049.015 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T22:07:29.015Z,1671574049.015 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T22:07:29.015Z,1671574049.015 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T22:07:31.389Z,1671574051.389 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T22:12:29.651Z,1671574349.651 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T22:12:29.651Z,1671574349.651 [Default:CheckIn:C.Wait] Stopped
2022-12-20T22:12:29.651Z,1671574349.651 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T22:12:29.651Z,1671574349.651 [Default:CheckIn:D] Running Loop=1
2022-12-20T22:12:30.047Z,1671574350.047 [Default:CheckIn:D] Stopped
2022-12-20T22:12:30.047Z,1671574350.047 [Default:CheckIn:E] Running Loop=1
2022-12-20T22:12:30.446Z,1671574350.446 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.629740 min 
2022-12-20T22:12:30.446Z,1671574350.446 [Default:CheckIn:E] Stopped
2022-12-20T22:12:30.446Z,1671574350.446 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T22:12:30.446Z,1671574350.446 [Default:CheckIn] Stopped
2022-12-20T22:12:30.446Z,1671574350.446 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T22:12:30.446Z,1671574350.446 [Default:CheckIn](INFO): Running loop #7
2022-12-20T22:12:30.446Z,1671574350.446 [Default:CheckIn] Running Loop=7
2022-12-20T22:12:30.446Z,1671574350.446 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T22:12:30.447Z,1671574350.447 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T22:12:32.466Z,1671574352.466 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221231.00,A,3648.13212,N,12147.21657,W,0.000,208.28,201222,,,D*75
2022-12-20T22:12:32.468Z,1671574352.468 [NAL9602](INFO): GPS fix at 20221220T221231: (36.802202, -121.786943)
2022-12-20T22:12:32.479Z,1671574352.479 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T22:12:32.479Z,1671574352.479 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T22:12:43.071Z,1671574363.071 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0025.lzma
2022-12-20T22:12:44.074Z,1671574364.074 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0025.lzma.bak
2022-12-20T22:12:44.074Z,1671574364.074 [DataOverHttps](INFO): SBD MOMSN=17400189
2022-12-20T22:12:59.931Z,1671574379.931 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20221220T212805/Express0026.lzma
2022-12-20T22:13:00.934Z,1671574380.934 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0026.lzma.bak
2022-12-20T22:13:00.934Z,1671574380.934 [DataOverHttps](INFO): SBD MOMSN=17400192
2022-12-20T22:13:02.068Z,1671574382.068 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T22:13:02.068Z,1671574382.068 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T22:13:02.068Z,1671574382.068 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T22:13:03.249Z,1671574383.249 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T22:13:03.332Z,1671574383.332 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T22:13:19.881Z,1671574399.881 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-12-20T22:13:19.881Z,1671574399.881 [DropWeight] Hardware Fault, FailCount= 1
2022-12-20T22:13:19.881Z,1671574399.881 [DropWeight](ERROR): Hardware Fault
2022-12-20T22:13:19.898Z,1671574399.898 [CBIT](INFO): Critical error at 20221220T221319
2022-12-20T22:13:19.901Z,1671574399.901 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-12-20T22:13:19.901Z,1671574399.901 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-12-20T22:13:20.308Z,1671574400.308 [CBIT](INFO): Critical error at 20221220T221319
2022-12-20T22:17:34.445Z,1671574654.445 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T22:18:02.733Z,1671574682.733 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T22:18:02.733Z,1671574682.733 [Default:CheckIn:C.Wait] Stopped
2022-12-20T22:18:02.733Z,1671574682.733 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T22:18:02.733Z,1671574682.733 [Default:CheckIn:D] Running Loop=1
2022-12-20T22:18:03.146Z,1671574683.146 [Default:CheckIn:D] Stopped
2022-12-20T22:18:03.146Z,1671574683.146 [Default:CheckIn:E] Running Loop=1
2022-12-20T22:18:03.554Z,1671574683.554 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.181401 min 
2022-12-20T22:18:03.554Z,1671574683.554 [Default:CheckIn:E] Stopped
2022-12-20T22:18:03.554Z,1671574683.554 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T22:18:03.554Z,1671574683.554 [Default:CheckIn] Stopped
2022-12-20T22:18:03.554Z,1671574683.554 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T22:18:03.554Z,1671574683.554 [Default:CheckIn](INFO): Running loop #8
2022-12-20T22:18:03.554Z,1671574683.554 [Default:CheckIn] Running Loop=8
2022-12-20T22:18:03.554Z,1671574683.554 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T22:18:03.554Z,1671574683.554 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T22:18:05.555Z,1671574685.555 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221804.00,A,3648.13415,N,12147.21642,W,0.078,208.28,201222,,,D*73
2022-12-20T22:18:05.557Z,1671574685.557 [NAL9602](INFO): GPS fix at 20221220T221804: (36.802236, -121.786940)
2022-12-20T22:18:05.568Z,1671574685.568 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T22:18:05.568Z,1671574685.568 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T22:18:13.267Z,1671574693.267 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20221220T212805/Courier0028.lzma
2022-12-20T22:18:14.269Z,1671574694.269 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0028.lzma.bak
2022-12-20T22:18:14.270Z,1671574694.270 [DataOverHttps](INFO): SBD MOMSN=17400198
2022-12-20T22:18:30.123Z,1671574710.123 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20221220T212805/Express0029.lzma
2022-12-20T22:18:31.126Z,1671574711.126 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0029.lzma.bak
2022-12-20T22:18:31.126Z,1671574711.126 [DataOverHttps](INFO): SBD MOMSN=17400203
2022-12-20T22:18:32.640Z,1671574712.640 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T22:18:32.640Z,1671574712.640 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T22:18:32.640Z,1671574712.640 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T22:18:37.513Z,1671574717.513 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T22:23:33.282Z,1671575013.282 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T22:23:33.282Z,1671575013.282 [Default:CheckIn:C.Wait] Stopped
2022-12-20T22:23:33.282Z,1671575013.282 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T22:23:33.282Z,1671575013.282 [Default:CheckIn:D] Running Loop=1
2022-12-20T22:23:33.691Z,1671575013.691 [Default:CheckIn:D] Stopped
2022-12-20T22:23:33.691Z,1671575013.691 [Default:CheckIn:E] Running Loop=1
2022-12-20T22:23:34.086Z,1671575014.086 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.690479 min 
2022-12-20T22:23:34.086Z,1671575014.086 [Default:CheckIn:E] Stopped
2022-12-20T22:23:34.086Z,1671575014.086 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T22:23:34.086Z,1671575014.086 [Default:CheckIn] Stopped
2022-12-20T22:23:34.087Z,1671575014.087 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T22:23:34.087Z,1671575014.087 [Default:CheckIn](INFO): Running loop #9
2022-12-20T22:23:34.087Z,1671575014.087 [Default:CheckIn] Running Loop=9
2022-12-20T22:23:34.087Z,1671575014.087 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T22:23:34.087Z,1671575014.087 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T22:23:36.098Z,1671575016.098 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222335.00,A,3648.13252,N,12147.21658,W,0.019,208.28,201222,,,D*70
2022-12-20T22:23:36.101Z,1671575016.101 [NAL9602](INFO): GPS fix at 20221220T222335: (36.802209, -121.786943)
2022-12-20T22:23:36.111Z,1671575016.111 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T22:23:36.111Z,1671575016.111 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T22:23:43.787Z,1671575023.787 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0031.lzma
2022-12-20T22:23:44.790Z,1671575024.790 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0031.lzma.bak
2022-12-20T22:23:44.790Z,1671575024.790 [DataOverHttps](INFO): SBD MOMSN=17400210
2022-12-20T22:24:00.640Z,1671575040.640 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20221220T212805/Express0032.lzma
2022-12-20T22:24:01.646Z,1671575041.646 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0032.lzma.bak
2022-12-20T22:24:01.646Z,1671575041.646 [DataOverHttps](INFO): SBD MOMSN=17400213
2022-12-20T22:24:02.783Z,1671575042.783 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T22:24:02.783Z,1671575042.783 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T22:24:02.783Z,1671575042.783 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T22:24:06.801Z,1671575046.801 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T22:24:06.880Z,1671575046.880 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T22:28:20.229Z,1671575300.229 [CBIT](INFO): Clearing failed state for component DropWeight
2022-12-20T22:28:20.229Z,1671575300.229 [DropWeight] No Fault, FailCount= 1
2022-12-20T22:28:38.341Z,1671575318.341 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T22:29:03.413Z,1671575343.413 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T22:29:03.413Z,1671575343.413 [Default:CheckIn:C.Wait] Stopped
2022-12-20T22:29:03.413Z,1671575343.413 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T22:29:03.413Z,1671575343.413 [Default:CheckIn:D] Running Loop=1
2022-12-20T22:29:03.811Z,1671575343.811 [Default:CheckIn:D] Stopped
2022-12-20T22:29:03.811Z,1671575343.811 [Default:CheckIn:E] Running Loop=1
2022-12-20T22:29:04.215Z,1671575344.215 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.192476 min 
2022-12-20T22:29:04.215Z,1671575344.215 [Default:CheckIn:E] Stopped
2022-12-20T22:29:04.215Z,1671575344.215 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T22:29:04.215Z,1671575344.215 [Default:CheckIn] Stopped
2022-12-20T22:29:04.215Z,1671575344.215 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T22:29:04.215Z,1671575344.215 [Default:CheckIn](INFO): Running loop #10
2022-12-20T22:29:04.215Z,1671575344.215 [Default:CheckIn] Running Loop=10
2022-12-20T22:29:04.216Z,1671575344.216 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T22:29:04.216Z,1671575344.216 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T22:29:06.218Z,1671575346.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222905.00,A,3648.13333,N,12147.21612,W,0.039,208.28,201222,,,D*73
2022-12-20T22:29:06.237Z,1671575346.237 [NAL9602](INFO): GPS fix at 20221220T222905: (36.802222, -121.786935)
2022-12-20T22:29:06.265Z,1671575346.265 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T22:29:06.266Z,1671575346.266 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T22:29:14.831Z,1671575354.831 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0034.lzma
2022-12-20T22:29:15.834Z,1671575355.834 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0034.lzma.bak
2022-12-20T22:29:15.834Z,1671575355.834 [DataOverHttps](INFO): SBD MOMSN=17400218
2022-12-20T22:29:31.944Z,1671575371.944 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20221220T212805/Express0035.lzma
2022-12-20T22:29:32.946Z,1671575372.946 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0035.lzma.bak
2022-12-20T22:29:32.946Z,1671575372.946 [DataOverHttps](INFO): SBD MOMSN=17400221
2022-12-20T22:29:34.264Z,1671575374.264 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T22:29:34.265Z,1671575374.265 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T22:29:34.265Z,1671575374.265 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T22:29:38.697Z,1671575378.697 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T22:34:34.911Z,1671575674.911 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T22:34:34.911Z,1671575674.911 [Default:CheckIn:C.Wait] Stopped
2022-12-20T22:34:34.911Z,1671575674.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T22:34:34.911Z,1671575674.911 [Default:CheckIn:D] Running Loop=1
2022-12-20T22:34:35.319Z,1671575675.319 [Default:CheckIn:D] Stopped
2022-12-20T22:34:35.319Z,1671575675.319 [Default:CheckIn:E] Running Loop=1
2022-12-20T22:34:35.718Z,1671575675.718 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.717615 min 
2022-12-20T22:34:35.718Z,1671575675.718 [Default:CheckIn:E] Stopped
2022-12-20T22:34:35.719Z,1671575675.719 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T22:34:35.719Z,1671575675.719 [Default:CheckIn] Stopped
2022-12-20T22:34:35.719Z,1671575675.719 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T22:34:35.719Z,1671575675.719 [Default:CheckIn](INFO): Running loop #11
2022-12-20T22:34:35.719Z,1671575675.719 [Default:CheckIn] Running Loop=11
2022-12-20T22:34:35.719Z,1671575675.719 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T22:34:35.719Z,1671575675.719 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T22:34:37.736Z,1671575677.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223437.00,A,3648.13227,N,12147.21531,W,0.039,0.00,201222,,,D*78
2022-12-20T22:34:37.739Z,1671575677.739 [NAL9602](INFO): GPS fix at 20221220T223437: (36.802205, -121.786922)
2022-12-20T22:34:37.749Z,1671575677.749 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T22:34:37.749Z,1671575677.749 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T22:34:45.055Z,1671575685.055 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0037.lzma
2022-12-20T22:34:46.058Z,1671575686.058 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0037.lzma.bak
2022-12-20T22:34:46.058Z,1671575686.058 [DataOverHttps](INFO): SBD MOMSN=17400229
2022-12-20T22:35:01.860Z,1671575701.860 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20221220T212805/Express0038.lzma
2022-12-20T22:35:02.862Z,1671575702.862 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0038.lzma.bak
2022-12-20T22:35:02.862Z,1671575702.862 [DataOverHttps](INFO): SBD MOMSN=17400233
2022-12-20T22:35:04.025Z,1671575704.025 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T22:35:04.025Z,1671575704.025 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T22:35:04.025Z,1671575704.025 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T22:35:08.485Z,1671575708.485 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T22:35:08.560Z,1671575708.560 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T22:39:40.410Z,1671575980.410 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T22:40:04.658Z,1671576004.658 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T22:40:04.659Z,1671576004.659 [Default:CheckIn:C.Wait] Stopped
2022-12-20T22:40:04.659Z,1671576004.659 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T22:40:04.659Z,1671576004.659 [Default:CheckIn:D] Running Loop=1
2022-12-20T22:40:05.066Z,1671576005.066 [Default:CheckIn:D] Stopped
2022-12-20T22:40:05.066Z,1671576005.066 [Default:CheckIn:E] Running Loop=1
2022-12-20T22:40:05.477Z,1671576005.477 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.213399 min 
2022-12-20T22:40:05.478Z,1671576005.478 [Default:CheckIn:E] Stopped
2022-12-20T22:40:05.478Z,1671576005.478 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T22:40:05.478Z,1671576005.478 [Default:CheckIn] Stopped
2022-12-20T22:40:05.478Z,1671576005.478 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T22:40:05.478Z,1671576005.478 [Default:CheckIn](INFO): Running loop #12
2022-12-20T22:40:05.478Z,1671576005.478 [Default:CheckIn] Running Loop=12
2022-12-20T22:40:05.478Z,1671576005.478 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T22:40:05.478Z,1671576005.478 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T22:40:07.479Z,1671576007.479 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224006.00,A,3648.13656,N,12147.21685,W,0.019,0.00,201222,,,D*75
2022-12-20T22:40:07.481Z,1671576007.481 [NAL9602](INFO): GPS fix at 20221220T224006: (36.802276, -121.786947)
2022-12-20T22:40:07.492Z,1671576007.492 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T22:40:07.492Z,1671576007.492 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T22:40:15.303Z,1671576015.303 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0040.lzma
2022-12-20T22:40:16.306Z,1671576016.306 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0040.lzma.bak
2022-12-20T22:40:16.306Z,1671576016.306 [DataOverHttps](INFO): SBD MOMSN=17400237
2022-12-20T22:40:32.056Z,1671576032.056 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20221220T212805/Express0041.lzma
2022-12-20T22:40:33.058Z,1671576033.058 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0041.lzma.bak
2022-12-20T22:40:33.058Z,1671576033.058 [DataOverHttps](INFO): SBD MOMSN=17400240
2022-12-20T22:40:34.177Z,1671576034.177 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T22:40:34.177Z,1671576034.177 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T22:40:34.177Z,1671576034.177 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T22:40:39.449Z,1671576039.449 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T22:45:34.795Z,1671576334.795 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T22:45:34.795Z,1671576334.795 [Default:CheckIn:C.Wait] Stopped
2022-12-20T22:45:34.795Z,1671576334.795 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T22:45:34.795Z,1671576334.795 [Default:CheckIn:D] Running Loop=1
2022-12-20T22:45:35.194Z,1671576335.194 [Default:CheckIn:D] Stopped
2022-12-20T22:45:35.194Z,1671576335.194 [Default:CheckIn:E] Running Loop=1
2022-12-20T22:45:35.599Z,1671576335.599 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.715527 min 
2022-12-20T22:45:35.599Z,1671576335.599 [Default:CheckIn:E] Stopped
2022-12-20T22:45:35.600Z,1671576335.600 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T22:45:35.600Z,1671576335.600 [Default:CheckIn] Stopped
2022-12-20T22:45:35.600Z,1671576335.600 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T22:45:35.600Z,1671576335.600 [Default:CheckIn](INFO): Running loop #13
2022-12-20T22:45:35.600Z,1671576335.600 [Default:CheckIn] Running Loop=13
2022-12-20T22:45:35.600Z,1671576335.600 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T22:45:35.600Z,1671576335.600 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T22:45:37.609Z,1671576337.609 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224536.00,A,3648.13315,N,12147.21468,W,0.019,0.00,201222,,,D*70
2022-12-20T22:45:37.621Z,1671576337.621 [NAL9602](INFO): GPS fix at 20221220T224536: (36.802219, -121.786911)
2022-12-20T22:45:37.632Z,1671576337.632 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T22:45:37.632Z,1671576337.632 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T22:45:45.219Z,1671576345.219 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221220T212805/Courier0043.lzma
2022-12-20T22:45:46.222Z,1671576346.222 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0043.lzma.bak
2022-12-20T22:45:46.222Z,1671576346.222 [DataOverHttps](INFO): SBD MOMSN=17400244
2022-12-20T22:46:01.924Z,1671576361.924 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20221220T212805/Express0044.lzma
2022-12-20T22:46:02.926Z,1671576362.926 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0044.lzma.bak
2022-12-20T22:46:02.926Z,1671576362.926 [DataOverHttps](INFO): SBD MOMSN=17400247
2022-12-20T22:46:04.298Z,1671576364.298 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T22:46:04.298Z,1671576364.298 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T22:46:04.298Z,1671576364.298 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T22:46:08.317Z,1671576368.317 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T22:46:08.400Z,1671576368.400 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T22:50:40.295Z,1671576640.295 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T22:51:04.927Z,1671576664.927 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T22:51:04.927Z,1671576664.927 [Default:CheckIn:C.Wait] Stopped
2022-12-20T22:51:04.927Z,1671576664.927 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T22:51:04.927Z,1671576664.927 [Default:CheckIn:D] Running Loop=1
2022-12-20T22:51:05.335Z,1671576665.335 [Default:CheckIn:D] Stopped
2022-12-20T22:51:05.335Z,1671576665.335 [Default:CheckIn:E] Running Loop=1
2022-12-20T22:51:05.747Z,1671576665.747 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.217879 min 
2022-12-20T22:51:05.747Z,1671576665.747 [Default:CheckIn:E] Stopped
2022-12-20T22:51:05.747Z,1671576665.747 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T22:51:05.747Z,1671576665.747 [Default:CheckIn] Stopped
2022-12-20T22:51:05.747Z,1671576665.747 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T22:51:05.747Z,1671576665.747 [Default:CheckIn](INFO): Running loop #14
2022-12-20T22:51:05.747Z,1671576665.747 [Default:CheckIn] Running Loop=14
2022-12-20T22:51:05.747Z,1671576665.747 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T22:51:05.748Z,1671576665.748 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T22:51:07.351Z,1671576667.351 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T22:51:07.755Z,1671576667.755 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225107.00,A,3648.13385,N,12147.21482,W,0.019,0.00,201222,,,D*7A
2022-12-20T22:51:07.757Z,1671576667.757 [NAL9602](INFO): GPS fix at 20221220T225107: (36.802231, -121.786914)
2022-12-20T22:51:07.767Z,1671576667.767 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T22:51:07.767Z,1671576667.767 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T22:51:15.779Z,1671576675.779 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0046.lzma
2022-12-20T22:51:16.782Z,1671576676.782 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0046.lzma.bak
2022-12-20T22:51:16.782Z,1671576676.782 [DataOverHttps](INFO): SBD MOMSN=17400250
2022-12-20T22:51:21.068Z,1671576681.068 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T22:51:32.764Z,1671576692.764 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20221220T212805/Express0047.lzma
2022-12-20T22:51:33.766Z,1671576693.766 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0047.lzma.bak
2022-12-20T22:51:33.766Z,1671576693.766 [DataOverHttps](INFO): SBD MOMSN=17400253
2022-12-20T22:51:34.414Z,1671576694.414 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T22:51:34.850Z,1671576694.850 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T22:51:34.850Z,1671576694.850 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T22:51:34.850Z,1671576694.850 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T22:51:40.469Z,1671576700.469 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T22:51:48.150Z,1671576708.150 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T22:52:01.088Z,1671576721.088 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T22:52:14.816Z,1671576734.816 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T22:52:28.196Z,1671576748.196 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T22:52:41.062Z,1671576761.062 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T22:52:41.883Z,1671576761.883 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-12-20T22:52:54.860Z,1671576774.860 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T22:53:08.630Z,1671576788.630 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T22:53:21.934Z,1671576801.934 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T22:53:48.937Z,1671576828.937 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-12-20T22:56:03.110Z,1671576963.110 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 5.
2022-12-20T22:56:03.113Z,1671576963.113 [BPC1](INFO): Received data from all battery sticks.
2022-12-20T22:56:35.473Z,1671576995.473 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T22:56:35.473Z,1671576995.473 [Default:CheckIn:C.Wait] Stopped
2022-12-20T22:56:35.473Z,1671576995.473 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T22:56:35.473Z,1671576995.473 [Default:CheckIn:D] Running Loop=1
2022-12-20T22:56:35.847Z,1671576995.847 [Default:CheckIn:D] Stopped
2022-12-20T22:56:35.847Z,1671576995.847 [Default:CheckIn:E] Running Loop=1
2022-12-20T22:56:36.252Z,1671576996.252 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.726408 min 
2022-12-20T22:56:36.252Z,1671576996.252 [Default:CheckIn:E] Stopped
2022-12-20T22:56:36.252Z,1671576996.252 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T22:56:36.252Z,1671576996.252 [Default:CheckIn] Stopped
2022-12-20T22:56:36.252Z,1671576996.252 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T22:56:36.252Z,1671576996.252 [Default:CheckIn](INFO): Running loop #15
2022-12-20T22:56:36.252Z,1671576996.252 [Default:CheckIn] Running Loop=15
2022-12-20T22:56:36.252Z,1671576996.252 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T22:56:36.253Z,1671576996.253 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T22:56:38.255Z,1671576998.255 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225637.00,A,3648.13271,N,12147.21602,W,0.039,0.00,201222,,,D*7C
2022-12-20T22:56:38.257Z,1671576998.257 [NAL9602](INFO): GPS fix at 20221220T225637: (36.802212, -121.786934)
2022-12-20T22:56:38.300Z,1671576998.300 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T22:56:38.300Z,1671576998.300 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T22:56:45.923Z,1671577005.923 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20221220T212805/Courier0049.lzma
2022-12-20T22:56:46.926Z,1671577006.926 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0049.lzma.bak
2022-12-20T22:56:46.926Z,1671577006.926 [DataOverHttps](INFO): SBD MOMSN=17400257
2022-12-20T22:57:02.648Z,1671577022.648 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20221220T212805/Express0050.lzma
2022-12-20T22:57:03.650Z,1671577023.650 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0050.lzma.bak
2022-12-20T22:57:03.650Z,1671577023.650 [DataOverHttps](INFO): SBD MOMSN=17400260
2022-12-20T22:57:04.966Z,1671577024.966 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T22:57:04.966Z,1671577024.966 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T22:57:04.966Z,1671577024.966 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T22:57:09.005Z,1671577029.005 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T22:57:09.080Z,1671577029.080 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T22:58:21.744Z,1671577101.744 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-12-20T22:58:21.745Z,1671577101.745 [DropWeight] Hardware Fault, FailCount= 1
2022-12-20T22:58:21.745Z,1671577101.745 [DropWeight](ERROR): Hardware Fault
2022-12-20T22:58:21.762Z,1671577101.762 [CBIT](INFO): Critical error at 20221220T225821
2022-12-20T22:58:21.765Z,1671577101.765 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-12-20T22:58:21.766Z,1671577101.766 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-12-20T22:58:22.178Z,1671577102.178 [CBIT](INFO): Critical error at 20221220T225821
2022-12-20T22:58:45.976Z,1671577125.976 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-12-20T22:58:47.984Z,1671577127.984 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251240
2022-12-20T22:58:58.412Z,1671577138.412 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002977
2022-12-20T23:01:40.517Z,1671577300.517 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T23:02:05.569Z,1671577325.569 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T23:02:05.569Z,1671577325.569 [Default:CheckIn:C.Wait] Stopped
2022-12-20T23:02:05.569Z,1671577325.569 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T23:02:05.569Z,1671577325.569 [Default:CheckIn:D] Running Loop=1
2022-12-20T23:02:05.978Z,1671577325.978 [Default:CheckIn:D] Stopped
2022-12-20T23:02:05.978Z,1671577325.978 [Default:CheckIn:E] Running Loop=1
2022-12-20T23:02:06.386Z,1671577326.386 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.228605 min 
2022-12-20T23:02:06.386Z,1671577326.386 [Default:CheckIn:E] Stopped
2022-12-20T23:02:06.387Z,1671577326.387 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T23:02:06.387Z,1671577326.387 [Default:CheckIn] Stopped
2022-12-20T23:02:06.387Z,1671577326.387 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T23:02:06.387Z,1671577326.387 [Default:CheckIn](INFO): Running loop #16
2022-12-20T23:02:06.387Z,1671577326.387 [Default:CheckIn] Running Loop=16
2022-12-20T23:02:06.387Z,1671577326.387 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T23:02:06.387Z,1671577326.387 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T23:02:08.389Z,1671577328.389 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230207.00,A,3648.13183,N,12147.21551,W,0.019,0.00,201222,,,D*76
2022-12-20T23:02:08.392Z,1671577328.392 [NAL9602](INFO): GPS fix at 20221220T230207: (36.802197, -121.786925)
2022-12-20T23:02:08.423Z,1671577328.423 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T23:02:08.423Z,1671577328.423 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T23:02:15.611Z,1671577335.611 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20221220T212805/Courier0052.lzma
2022-12-20T23:02:16.615Z,1671577336.615 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0052.lzma.bak
2022-12-20T23:02:16.615Z,1671577336.615 [DataOverHttps](INFO): SBD MOMSN=17400263
2022-12-20T23:02:32.332Z,1671577352.332 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20221220T212805/Express0053.lzma
2022-12-20T23:02:33.334Z,1671577353.334 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0053.lzma.bak
2022-12-20T23:02:33.334Z,1671577353.334 [DataOverHttps](INFO): SBD MOMSN=17400268
2022-12-20T23:02:34.673Z,1671577354.673 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T23:02:34.673Z,1671577354.673 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T23:02:34.673Z,1671577354.673 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T23:02:40.366Z,1671577360.366 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T23:07:35.305Z,1671577655.305 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T23:07:35.305Z,1671577655.305 [Default:CheckIn:C.Wait] Stopped
2022-12-20T23:07:35.305Z,1671577655.305 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T23:07:35.305Z,1671577655.305 [Default:CheckIn:D] Running Loop=1
2022-12-20T23:07:35.709Z,1671577655.709 [Default:CheckIn:D] Stopped
2022-12-20T23:07:35.709Z,1671577655.709 [Default:CheckIn:E] Running Loop=1
2022-12-20T23:07:36.114Z,1671577656.114 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.724121 min 
2022-12-20T23:07:36.114Z,1671577656.114 [Default:CheckIn:E] Stopped
2022-12-20T23:07:36.114Z,1671577656.114 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T23:07:36.115Z,1671577656.115 [Default:CheckIn] Stopped
2022-12-20T23:07:36.115Z,1671577656.115 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T23:07:36.115Z,1671577656.115 [Default:CheckIn](INFO): Running loop #17
2022-12-20T23:07:36.115Z,1671577656.115 [Default:CheckIn] Running Loop=17
2022-12-20T23:07:36.115Z,1671577656.115 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T23:07:36.115Z,1671577656.115 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T23:07:38.130Z,1671577658.130 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230737.00,A,3648.13297,N,12147.21546,W,0.000,0.00,201222,,,D*78
2022-12-20T23:07:38.133Z,1671577658.133 [NAL9602](INFO): GPS fix at 20221220T230737: (36.802216, -121.786924)
2022-12-20T23:07:38.159Z,1671577658.159 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T23:07:38.159Z,1671577658.159 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T23:07:45.859Z,1671577665.859 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0055.lzma
2022-12-20T23:07:46.862Z,1671577666.862 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0055.lzma.bak
2022-12-20T23:07:46.862Z,1671577666.862 [DataOverHttps](INFO): SBD MOMSN=17400272
2022-12-20T23:08:02.587Z,1671577682.587 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20221220T212805/Express0056.lzma
2022-12-20T23:08:03.590Z,1671577683.590 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0056.lzma.bak
2022-12-20T23:08:03.590Z,1671577683.590 [DataOverHttps](INFO): SBD MOMSN=17400275
2022-12-20T23:08:04.816Z,1671577684.816 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T23:08:04.816Z,1671577684.816 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T23:08:04.816Z,1671577684.816 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T23:08:08.829Z,1671577688.829 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T23:08:08.912Z,1671577688.912 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T23:12:40.381Z,1671577960.381 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T23:13:05.449Z,1671577985.449 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T23:13:05.449Z,1671577985.449 [Default:CheckIn:C.Wait] Stopped
2022-12-20T23:13:05.449Z,1671577985.449 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T23:13:05.449Z,1671577985.449 [Default:CheckIn:D] Running Loop=1
2022-12-20T23:13:05.856Z,1671577985.856 [Default:CheckIn:D] Stopped
2022-12-20T23:13:05.856Z,1671577985.856 [Default:CheckIn:E] Running Loop=1
2022-12-20T23:13:06.263Z,1671577986.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.226562 min 
2022-12-20T23:13:06.263Z,1671577986.263 [Default:CheckIn:E] Stopped
2022-12-20T23:13:06.263Z,1671577986.263 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T23:13:06.263Z,1671577986.263 [Default:CheckIn] Stopped
2022-12-20T23:13:06.263Z,1671577986.263 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T23:13:06.263Z,1671577986.263 [Default:CheckIn](INFO): Running loop #18
2022-12-20T23:13:06.276Z,1671577986.276 [Default:CheckIn] Running Loop=18
2022-12-20T23:13:06.276Z,1671577986.276 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T23:13:06.276Z,1671577986.276 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T23:13:08.259Z,1671577988.259 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231307.00,A,3648.13160,N,12147.21581,W,0.019,0.00,201222,,,D*76
2022-12-20T23:13:08.262Z,1671577988.262 [NAL9602](INFO): GPS fix at 20221220T231307: (36.802193, -121.786930)
2022-12-20T23:13:08.280Z,1671577988.280 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T23:13:08.280Z,1671577988.280 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T23:13:15.499Z,1671577995.499 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0058.lzma
2022-12-20T23:13:16.502Z,1671577996.502 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0058.lzma.bak
2022-12-20T23:13:16.502Z,1671577996.502 [DataOverHttps](INFO): SBD MOMSN=17400278
2022-12-20T23:13:22.072Z,1671578002.072 [CBIT](INFO): Clearing failed state for component DropWeight
2022-12-20T23:13:22.072Z,1671578002.072 [DropWeight] No Fault, FailCount= 1
2022-12-20T23:13:32.172Z,1671578012.172 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20221220T212805/Express0059.lzma
2022-12-20T23:13:33.174Z,1671578013.174 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0059.lzma.bak
2022-12-20T23:13:33.174Z,1671578013.174 [DataOverHttps](INFO): SBD MOMSN=17400281
2022-12-20T23:13:34.549Z,1671578014.549 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T23:13:34.549Z,1671578014.549 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T23:13:34.549Z,1671578014.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T23:13:40.597Z,1671578020.597 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T23:18:35.198Z,1671578315.198 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T23:18:35.198Z,1671578315.198 [Default:CheckIn:C.Wait] Stopped
2022-12-20T23:18:35.198Z,1671578315.198 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T23:18:35.198Z,1671578315.198 [Default:CheckIn:D] Running Loop=1
2022-12-20T23:18:35.603Z,1671578315.603 [Default:CheckIn:D] Stopped
2022-12-20T23:18:35.603Z,1671578315.603 [Default:CheckIn:E] Running Loop=1
2022-12-20T23:18:36.007Z,1671578316.007 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.722347 min 
2022-12-20T23:18:36.007Z,1671578316.007 [Default:CheckIn:E] Stopped
2022-12-20T23:18:36.007Z,1671578316.007 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T23:18:36.008Z,1671578316.008 [Default:CheckIn] Stopped
2022-12-20T23:18:36.008Z,1671578316.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T23:18:36.008Z,1671578316.008 [Default:CheckIn](INFO): Running loop #19
2022-12-20T23:18:36.008Z,1671578316.008 [Default:CheckIn] Running Loop=19
2022-12-20T23:18:36.008Z,1671578316.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T23:18:36.008Z,1671578316.008 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T23:18:38.010Z,1671578318.010 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231837.00,A,3648.13321,N,12147.21596,W,0.019,0.00,201222,,,D*7F
2022-12-20T23:18:38.013Z,1671578318.013 [NAL9602](INFO): GPS fix at 20221220T231837: (36.802220, -121.786933)
2022-12-20T23:18:38.055Z,1671578318.055 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T23:18:38.055Z,1671578318.055 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T23:18:45.819Z,1671578325.819 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221220T212805/Courier0061.lzma
2022-12-20T23:18:46.822Z,1671578326.822 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0061.lzma.bak
2022-12-20T23:18:46.822Z,1671578326.822 [DataOverHttps](INFO): SBD MOMSN=17400285
2022-12-20T23:19:02.636Z,1671578342.636 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20221220T212805/Express0062.lzma
2022-12-20T23:19:03.638Z,1671578343.638 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0062.lzma.bak
2022-12-20T23:19:03.639Z,1671578343.639 [DataOverHttps](INFO): SBD MOMSN=17400288
2022-12-20T23:19:04.784Z,1671578344.784 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T23:19:04.784Z,1671578344.784 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T23:19:04.784Z,1671578344.784 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T23:19:08.825Z,1671578348.825 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T23:19:08.900Z,1671578348.900 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T23:23:40.345Z,1671578620.345 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T23:24:05.407Z,1671578645.407 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T23:24:05.407Z,1671578645.407 [Default:CheckIn:C.Wait] Stopped
2022-12-20T23:24:05.407Z,1671578645.407 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T23:24:05.407Z,1671578645.407 [Default:CheckIn:D] Running Loop=1
2022-12-20T23:24:05.807Z,1671578645.807 [Default:CheckIn:D] Stopped
2022-12-20T23:24:05.807Z,1671578645.807 [Default:CheckIn:E] Running Loop=1
2022-12-20T23:24:06.220Z,1671578646.220 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.225741 min 
2022-12-20T23:24:06.220Z,1671578646.220 [Default:CheckIn:E] Stopped
2022-12-20T23:24:06.220Z,1671578646.220 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T23:24:06.220Z,1671578646.220 [Default:CheckIn] Stopped
2022-12-20T23:24:06.221Z,1671578646.221 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T23:24:06.221Z,1671578646.221 [Default:CheckIn](INFO): Running loop #20
2022-12-20T23:24:06.221Z,1671578646.221 [Default:CheckIn] Running Loop=20
2022-12-20T23:24:06.221Z,1671578646.221 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T23:24:06.221Z,1671578646.221 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T23:24:08.227Z,1671578648.227 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232407.00,A,3648.13270,N,12147.21558,W,0.019,0.00,201222,,,D*74
2022-12-20T23:24:08.229Z,1671578648.229 [NAL9602](INFO): GPS fix at 20221220T232407: (36.802212, -121.786926)
2022-12-20T23:24:08.260Z,1671578648.260 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T23:24:08.260Z,1671578648.260 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T23:24:15.623Z,1671578655.623 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0064.lzma
2022-12-20T23:24:16.626Z,1671578656.626 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0064.lzma.bak
2022-12-20T23:24:16.626Z,1671578656.626 [DataOverHttps](INFO): SBD MOMSN=17400291
2022-12-20T23:24:32.432Z,1671578672.432 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20221220T212805/Express0065.lzma
2022-12-20T23:24:33.434Z,1671578673.434 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0065.lzma.bak
2022-12-20T23:24:33.435Z,1671578673.435 [DataOverHttps](INFO): SBD MOMSN=17400294
2022-12-20T23:24:34.566Z,1671578674.566 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T23:24:34.566Z,1671578674.566 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T23:24:34.566Z,1671578674.566 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T23:24:40.589Z,1671578680.589 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T23:29:35.142Z,1671578975.142 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T23:29:35.142Z,1671578975.142 [Default:CheckIn:C.Wait] Stopped
2022-12-20T23:29:35.142Z,1671578975.142 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T23:29:35.142Z,1671578975.142 [Default:CheckIn:D] Running Loop=1
2022-12-20T23:29:35.544Z,1671578975.544 [Default:CheckIn:D] Stopped
2022-12-20T23:29:35.544Z,1671578975.544 [Default:CheckIn:E] Running Loop=1
2022-12-20T23:29:35.958Z,1671578975.958 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.721354 min 
2022-12-20T23:29:35.959Z,1671578975.959 [Default:CheckIn:E] Stopped
2022-12-20T23:29:35.959Z,1671578975.959 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T23:29:35.959Z,1671578975.959 [Default:CheckIn] Stopped
2022-12-20T23:29:35.959Z,1671578975.959 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T23:29:35.959Z,1671578975.959 [Default:CheckIn](INFO): Running loop #21
2022-12-20T23:29:35.959Z,1671578975.959 [Default:CheckIn] Running Loop=21
2022-12-20T23:29:35.959Z,1671578975.959 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T23:29:35.959Z,1671578975.959 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T23:29:37.959Z,1671578977.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232937.00,A,3648.13276,N,12147.21579,W,0.000,0.00,201222,,,D*77
2022-12-20T23:29:37.961Z,1671578977.961 [NAL9602](INFO): GPS fix at 20221220T232937: (36.802213, -121.786930)
2022-12-20T23:29:37.973Z,1671578977.973 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T23:29:37.973Z,1671578977.973 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T23:29:45.111Z,1671578985.111 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0067.lzma
2022-12-20T23:29:46.114Z,1671578986.114 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0067.lzma.bak
2022-12-20T23:29:46.114Z,1671578986.114 [DataOverHttps](INFO): SBD MOMSN=17400298
2022-12-20T23:30:01.886Z,1671579001.886 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20221220T212805/Express0068.lzma
2022-12-20T23:30:02.806Z,1671579002.806 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0068.lzma.bak
2022-12-20T23:30:02.806Z,1671579002.806 [DataOverHttps](INFO): SBD MOMSN=17400301
2022-12-20T23:30:04.231Z,1671579004.231 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T23:30:04.231Z,1671579004.231 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T23:30:04.231Z,1671579004.231 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T23:30:08.681Z,1671579008.681 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T23:30:08.764Z,1671579008.764 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T23:34:40.636Z,1671579280.636 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T23:35:04.891Z,1671579304.891 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T23:35:04.891Z,1671579304.891 [Default:CheckIn:C.Wait] Stopped
2022-12-20T23:35:04.891Z,1671579304.891 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T23:35:04.891Z,1671579304.891 [Default:CheckIn:D] Running Loop=1
2022-12-20T23:35:05.306Z,1671579305.306 [Default:CheckIn:D] Stopped
2022-12-20T23:35:05.306Z,1671579305.306 [Default:CheckIn:E] Running Loop=1
2022-12-20T23:35:05.698Z,1671579305.698 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.217391 min 
2022-12-20T23:35:05.698Z,1671579305.698 [Default:CheckIn:E] Stopped
2022-12-20T23:35:05.698Z,1671579305.698 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T23:35:05.698Z,1671579305.698 [Default:CheckIn] Stopped
2022-12-20T23:35:05.698Z,1671579305.698 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T23:35:05.699Z,1671579305.699 [Default:CheckIn](INFO): Running loop #22
2022-12-20T23:35:05.699Z,1671579305.699 [Default:CheckIn] Running Loop=22
2022-12-20T23:35:05.699Z,1671579305.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T23:35:05.699Z,1671579305.699 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T23:35:07.718Z,1671579307.718 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233507.00,A,3648.13199,N,12147.21502,W,0.019,0.00,201222,,,D*7F
2022-12-20T23:35:07.725Z,1671579307.725 [NAL9602](INFO): GPS fix at 20221220T233507: (36.802200, -121.786917)
2022-12-20T23:35:07.765Z,1671579307.765 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T23:35:07.765Z,1671579307.765 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T23:35:15.411Z,1671579315.411 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0070.lzma
2022-12-20T23:35:16.414Z,1671579316.414 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0070.lzma.bak
2022-12-20T23:35:16.414Z,1671579316.414 [DataOverHttps](INFO): SBD MOMSN=17400314
2022-12-20T23:35:32.088Z,1671579332.088 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20221220T212805/Express0071.lzma
2022-12-20T23:35:33.090Z,1671579333.090 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0071.lzma.bak
2022-12-20T23:35:33.090Z,1671579333.090 [DataOverHttps](INFO): SBD MOMSN=17400317
2022-12-20T23:35:34.377Z,1671579334.377 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T23:35:34.377Z,1671579334.377 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T23:35:34.377Z,1671579334.377 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T23:35:40.429Z,1671579340.429 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T23:40:35.026Z,1671579635.026 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T23:40:35.026Z,1671579635.026 [Default:CheckIn:C.Wait] Stopped
2022-12-20T23:40:35.026Z,1671579635.026 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T23:40:35.027Z,1671579635.027 [Default:CheckIn:D] Running Loop=1
2022-12-20T23:40:35.424Z,1671579635.424 [Default:CheckIn:D] Stopped
2022-12-20T23:40:35.424Z,1671579635.424 [Default:CheckIn:E] Running Loop=1
2022-12-20T23:40:35.829Z,1671579635.829 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.719368 min 
2022-12-20T23:40:35.829Z,1671579635.829 [Default:CheckIn:E] Stopped
2022-12-20T23:40:35.829Z,1671579635.829 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T23:40:35.829Z,1671579635.829 [Default:CheckIn] Stopped
2022-12-20T23:40:35.829Z,1671579635.829 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T23:40:35.830Z,1671579635.830 [Default:CheckIn](INFO): Running loop #23
2022-12-20T23:40:35.830Z,1671579635.830 [Default:CheckIn] Running Loop=23
2022-12-20T23:40:35.830Z,1671579635.830 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T23:40:35.830Z,1671579635.830 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T23:40:37.830Z,1671579637.830 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234037.00,A,3648.13171,N,12147.21734,W,0.019,0.00,201222,,,D*7F
2022-12-20T23:40:37.849Z,1671579637.849 [NAL9602](INFO): GPS fix at 20221220T234037: (36.802195, -121.786956)
2022-12-20T23:40:37.875Z,1671579637.875 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T23:40:37.875Z,1671579637.875 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T23:40:45.447Z,1671579645.447 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0073.lzma
2022-12-20T23:40:46.450Z,1671579646.450 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0073.lzma.bak
2022-12-20T23:40:46.450Z,1671579646.450 [DataOverHttps](INFO): SBD MOMSN=17400325
2022-12-20T23:41:02.308Z,1671579662.308 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20221220T212805/Express0074.lzma
2022-12-20T23:41:03.326Z,1671579663.326 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0074.lzma.bak
2022-12-20T23:41:03.326Z,1671579663.326 [DataOverHttps](INFO): SBD MOMSN=17400328
2022-12-20T23:41:04.516Z,1671579664.516 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T23:41:04.516Z,1671579664.516 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T23:41:04.516Z,1671579664.516 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T23:41:08.573Z,1671579668.573 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T23:41:08.648Z,1671579668.648 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T23:43:23.520Z,1671579803.520 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-12-20T23:43:23.520Z,1671579803.520 [DropWeight] Hardware Fault, FailCount= 1
2022-12-20T23:43:23.520Z,1671579803.520 [DropWeight](ERROR): Hardware Fault
2022-12-20T23:43:23.559Z,1671579803.559 [CBIT](INFO): Critical error at 20221220T234323
2022-12-20T23:43:23.565Z,1671579803.565 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-12-20T23:43:23.566Z,1671579803.566 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-12-20T23:43:23.963Z,1671579803.963 [CBIT](INFO): Critical error at 20221220T234323
2022-12-20T23:45:40.483Z,1671579940.483 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T23:46:05.137Z,1671579965.137 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T23:46:05.137Z,1671579965.137 [Default:CheckIn:C.Wait] Stopped
2022-12-20T23:46:05.138Z,1671579965.138 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T23:46:05.138Z,1671579965.138 [Default:CheckIn:D] Running Loop=1
2022-12-20T23:46:05.547Z,1671579965.547 [Default:CheckIn:D] Stopped
2022-12-20T23:46:05.547Z,1671579965.547 [Default:CheckIn:E] Running Loop=1
2022-12-20T23:46:05.947Z,1671579965.947 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.221411 min 
2022-12-20T23:46:05.947Z,1671579965.947 [Default:CheckIn:E] Stopped
2022-12-20T23:46:05.947Z,1671579965.947 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T23:46:05.947Z,1671579965.947 [Default:CheckIn] Stopped
2022-12-20T23:46:05.947Z,1671579965.947 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T23:46:05.948Z,1671579965.948 [Default:CheckIn](INFO): Running loop #24
2022-12-20T23:46:05.948Z,1671579965.948 [Default:CheckIn] Running Loop=24
2022-12-20T23:46:05.948Z,1671579965.948 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T23:46:05.948Z,1671579965.948 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T23:46:07.959Z,1671579967.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234607.00,A,3648.13412,N,12147.21554,W,0.019,0.00,201222,,,D*7E
2022-12-20T23:46:07.961Z,1671579967.961 [NAL9602](INFO): GPS fix at 20221220T234607: (36.802235, -121.786926)
2022-12-20T23:46:07.991Z,1671579967.991 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T23:46:07.991Z,1671579967.991 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T23:46:15.179Z,1671579975.179 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20221220T212805/Courier0076.lzma
2022-12-20T23:46:16.182Z,1671579976.182 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0076.lzma.bak
2022-12-20T23:46:16.182Z,1671579976.182 [DataOverHttps](INFO): SBD MOMSN=17400334
2022-12-20T23:46:31.940Z,1671579991.940 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20221220T212805/Express0077.lzma
2022-12-20T23:46:32.942Z,1671579992.942 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0077.lzma.bak
2022-12-20T23:46:32.942Z,1671579992.942 [DataOverHttps](INFO): SBD MOMSN=17400340
2022-12-20T23:46:34.244Z,1671579994.244 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T23:46:34.244Z,1671579994.244 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T23:46:34.244Z,1671579994.244 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T23:46:40.341Z,1671580000.341 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T23:51:34.887Z,1671580294.887 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T23:51:34.887Z,1671580294.887 [Default:CheckIn:C.Wait] Stopped
2022-12-20T23:51:34.887Z,1671580294.887 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T23:51:34.887Z,1671580294.887 [Default:CheckIn:D] Running Loop=1
2022-12-20T23:51:35.290Z,1671580295.290 [Default:CheckIn:D] Stopped
2022-12-20T23:51:35.290Z,1671580295.290 [Default:CheckIn:E] Running Loop=1
2022-12-20T23:51:35.698Z,1671580295.698 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.717139 min 
2022-12-20T23:51:35.698Z,1671580295.698 [Default:CheckIn:E] Stopped
2022-12-20T23:51:35.698Z,1671580295.698 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T23:51:35.698Z,1671580295.698 [Default:CheckIn] Stopped
2022-12-20T23:51:35.698Z,1671580295.698 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T23:51:35.698Z,1671580295.698 [Default:CheckIn](INFO): Running loop #25
2022-12-20T23:51:35.698Z,1671580295.698 [Default:CheckIn] Running Loop=25
2022-12-20T23:51:35.698Z,1671580295.698 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T23:51:35.698Z,1671580295.698 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T23:51:37.702Z,1671580297.702 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235137.00,A,3648.13367,N,12147.21456,W,0.019,0.00,201222,,,D*7D
2022-12-20T23:51:37.705Z,1671580297.705 [NAL9602](INFO): GPS fix at 20221220T235137: (36.802228, -121.786909)
2022-12-20T23:51:37.737Z,1671580297.737 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T23:51:37.737Z,1671580297.737 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T23:51:45.363Z,1671580305.363 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221220T212805/Courier0079.lzma
2022-12-20T23:51:46.366Z,1671580306.366 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0079.lzma.bak
2022-12-20T23:51:46.366Z,1671580306.366 [DataOverHttps](INFO): SBD MOMSN=17400345
2022-12-20T23:52:02.368Z,1671580322.368 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20221220T212805/Express0080.lzma
2022-12-20T23:52:03.370Z,1671580323.370 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0080.lzma.bak
2022-12-20T23:52:03.370Z,1671580323.370 [DataOverHttps](INFO): SBD MOMSN=17400349
2022-12-20T23:52:04.782Z,1671580324.782 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T23:52:04.782Z,1671580324.782 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T23:52:04.782Z,1671580324.782 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T23:52:08.405Z,1671580328.405 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-20T23:52:08.488Z,1671580328.488 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-20T23:56:40.369Z,1671580600.369 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-20T23:57:05.433Z,1671580625.433 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-20T23:57:05.433Z,1671580625.433 [Default:CheckIn:C.Wait] Stopped
2022-12-20T23:57:05.433Z,1671580625.433 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-20T23:57:05.433Z,1671580625.433 [Default:CheckIn:D] Running Loop=1
2022-12-20T23:57:05.839Z,1671580625.839 [Default:CheckIn:D] Stopped
2022-12-20T23:57:05.839Z,1671580625.839 [Default:CheckIn:E] Running Loop=1
2022-12-20T23:57:06.262Z,1671580626.262 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.226270 min 
2022-12-20T23:57:06.262Z,1671580626.262 [Default:CheckIn:E] Stopped
2022-12-20T23:57:06.262Z,1671580626.262 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-20T23:57:06.262Z,1671580626.262 [Default:CheckIn] Stopped
2022-12-20T23:57:06.262Z,1671580626.262 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-20T23:57:06.263Z,1671580626.263 [Default:CheckIn](INFO): Running loop #26
2022-12-20T23:57:06.263Z,1671580626.263 [Default:CheckIn] Running Loop=26
2022-12-20T23:57:06.263Z,1671580626.263 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-20T23:57:06.263Z,1671580626.263 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-20T23:57:08.239Z,1671580628.239 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235707.00,A,3648.13263,N,12147.21587,W,0.019,0.00,201222,,,D*70
2022-12-20T23:57:08.242Z,1671580628.242 [NAL9602](INFO): GPS fix at 20221220T235707: (36.802211, -121.786931)
2022-12-20T23:57:08.260Z,1671580628.260 [Default:CheckIn:Read_GPS] Stopped
2022-12-20T23:57:08.260Z,1671580628.260 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-20T23:57:15.879Z,1671580635.879 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0082.lzma
2022-12-20T23:57:16.882Z,1671580636.882 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0082.lzma.bak
2022-12-20T23:57:16.882Z,1671580636.882 [DataOverHttps](INFO): SBD MOMSN=17400355
2022-12-20T23:57:33.176Z,1671580653.176 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20221220T212805/Express0083.lzma
2022-12-20T23:57:34.178Z,1671580654.178 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0083.lzma.bak
2022-12-20T23:57:34.178Z,1671580654.178 [DataOverHttps](INFO): SBD MOMSN=17400359
2022-12-20T23:57:35.329Z,1671580655.329 [Default:CheckIn:Read_Iridium] Stopped
2022-12-20T23:57:35.329Z,1671580655.329 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-20T23:57:35.329Z,1671580655.329 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-20T23:57:40.557Z,1671580660.557 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-20T23:58:23.818Z,1671580703.818 [CBIT](INFO): Clearing failed state for component DropWeight
2022-12-20T23:58:23.818Z,1671580703.818 [DropWeight] No Fault, FailCount= 1
2022-12-20T23:58:29.853Z,1671580709.853 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T23:58:43.580Z,1671580723.580 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T23:58:56.913Z,1671580736.913 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T23:59:10.648Z,1671580750.648 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T23:59:23.577Z,1671580763.577 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T23:59:36.908Z,1671580776.908 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-20T23:59:50.268Z,1671580790.268 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:00:03.573Z,1671580803.573 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:00:16.904Z,1671580816.904 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:00:30.641Z,1671580830.641 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:00:44.036Z,1671580844.036 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:00:57.368Z,1671580857.368 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:02:36.014Z,1671580956.014 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-21T00:02:36.015Z,1671580956.015 [Default:CheckIn:C.Wait] Stopped
2022-12-21T00:02:36.015Z,1671580956.015 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-21T00:02:36.015Z,1671580956.015 [Default:CheckIn:D] Running Loop=1
2022-12-21T00:02:36.399Z,1671580956.399 [Default:CheckIn:D] Stopped
2022-12-21T00:02:36.399Z,1671580956.399 [Default:CheckIn:E] Running Loop=1
2022-12-21T00:02:36.799Z,1671580956.799 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.735612 min 
2022-12-21T00:02:36.799Z,1671580956.799 [Default:CheckIn:E] Stopped
2022-12-21T00:02:36.799Z,1671580956.799 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-21T00:02:36.799Z,1671580956.799 [Default:CheckIn] Stopped
2022-12-21T00:02:36.799Z,1671580956.799 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-21T00:02:36.820Z,1671580956.820 [Default:CheckIn](INFO): Running loop #27
2022-12-21T00:02:36.820Z,1671580956.820 [Default:CheckIn] Running Loop=27
2022-12-21T00:02:36.820Z,1671580956.820 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-21T00:02:36.820Z,1671580956.820 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-21T00:02:38.794Z,1671580958.794 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000238.00,A,3648.12751,N,12147.21687,W,0.039,0.00,211222,,,D*78
2022-12-21T00:02:38.812Z,1671580958.812 [NAL9602](INFO): GPS fix at 20221221T000238: (36.802125, -121.786948)
2022-12-21T00:02:38.857Z,1671580958.857 [Default:CheckIn:Read_GPS] Stopped
2022-12-21T00:02:38.857Z,1671580958.857 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-21T00:02:45.963Z,1671580965.963 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221220T212805/Courier0085.lzma
2022-12-21T00:02:46.966Z,1671580966.966 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0085.lzma.bak
2022-12-21T00:02:46.966Z,1671580966.966 [DataOverHttps](INFO): SBD MOMSN=17400369
2022-12-21T00:03:03.168Z,1671580983.168 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20221220T212805/Express0086.lzma
2022-12-21T00:03:04.170Z,1671580984.170 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0086.lzma.bak
2022-12-21T00:03:04.170Z,1671580984.170 [DataOverHttps](INFO): SBD MOMSN=17400372
2022-12-21T00:03:05.657Z,1671580985.657 [Default:CheckIn:Read_Iridium] Stopped
2022-12-21T00:03:05.657Z,1671580985.657 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-21T00:03:05.657Z,1671580985.657 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-21T00:07:41.327Z,1671581261.327 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-21T00:08:06.318Z,1671581286.318 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-21T00:08:06.319Z,1671581286.319 [Default:CheckIn:C.Wait] Stopped
2022-12-21T00:08:06.319Z,1671581286.319 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-21T00:08:06.319Z,1671581286.319 [Default:CheckIn:D] Running Loop=1
2022-12-21T00:08:06.700Z,1671581286.700 [Default:CheckIn:D] Stopped
2022-12-21T00:08:06.700Z,1671581286.700 [Default:CheckIn:E] Running Loop=1
2022-12-21T00:08:07.115Z,1671581287.115 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.240625 min 
2022-12-21T00:08:07.116Z,1671581287.116 [Default:CheckIn:E] Stopped
2022-12-21T00:08:07.116Z,1671581287.116 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-21T00:08:07.116Z,1671581287.116 [Default:CheckIn] Stopped
2022-12-21T00:08:07.116Z,1671581287.116 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-21T00:08:07.116Z,1671581287.116 [Default:CheckIn](INFO): Running loop #28
2022-12-21T00:08:07.116Z,1671581287.116 [Default:CheckIn] Running Loop=28
2022-12-21T00:08:07.116Z,1671581287.116 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-21T00:08:07.116Z,1671581287.116 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-21T00:08:09.127Z,1671581289.127 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000808.00,A,3648.13046,N,12147.21620,W,0.019,0.00,211222,,,D*7E
2022-12-21T00:08:09.129Z,1671581289.129 [NAL9602](INFO): GPS fix at 20221221T000808: (36.802174, -121.786937)
2022-12-21T00:08:09.156Z,1671581289.156 [Default:CheckIn:Read_GPS] Stopped
2022-12-21T00:08:09.156Z,1671581289.156 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-21T00:08:16.883Z,1671581296.883 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221220T212805/Courier0088.lzma
2022-12-21T00:08:17.886Z,1671581297.886 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0088.lzma.bak
2022-12-21T00:08:17.886Z,1671581297.886 [DataOverHttps](INFO): SBD MOMSN=17400376
2022-12-21T00:08:19.204Z,1671581299.204 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:08:32.549Z,1671581312.549 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:08:34.000Z,1671581315.000 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20221220T212805/Express0089.lzma
2022-12-21T00:08:36.002Z,1671581316.002 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0089.lzma.bak
2022-12-21T00:08:36.002Z,1671581316.002 [DataOverHttps](INFO): SBD MOMSN=17400380
2022-12-21T00:08:37.421Z,1671581317.421 [Default:CheckIn:Read_Iridium] Stopped
2022-12-21T00:08:37.421Z,1671581317.421 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-21T00:08:37.421Z,1671581317.421 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-21T00:08:41.465Z,1671581321.465 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-21T00:08:45.889Z,1671581325.889 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:08:59.631Z,1671581339.631 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:09:12.957Z,1671581352.957 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:09:26.290Z,1671581366.290 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:09:39.622Z,1671581379.622 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:09:52.952Z,1671581392.952 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:10:06.689Z,1671581406.689 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:10:20.020Z,1671581420.020 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:10:33.756Z,1671581433.756 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:13:38.030Z,1671581618.030 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-21T00:13:38.031Z,1671581618.031 [Default:CheckIn:C.Wait] Stopped
2022-12-21T00:13:38.031Z,1671581618.031 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-21T00:13:38.031Z,1671581618.031 [Default:CheckIn:D] Running Loop=1
2022-12-21T00:13:38.434Z,1671581618.434 [Default:CheckIn:D] Stopped
2022-12-21T00:13:38.435Z,1671581618.435 [Default:CheckIn:E] Running Loop=1
2022-12-21T00:13:38.842Z,1671581618.842 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.769531 min 
2022-12-21T00:13:38.842Z,1671581618.842 [Default:CheckIn:E] Stopped
2022-12-21T00:13:38.842Z,1671581618.842 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-21T00:13:38.842Z,1671581618.842 [Default:CheckIn] Stopped
2022-12-21T00:13:38.842Z,1671581618.842 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-21T00:13:38.843Z,1671581618.843 [Default:CheckIn](INFO): Running loop #29
2022-12-21T00:13:38.843Z,1671581618.843 [Default:CheckIn] Running Loop=29
2022-12-21T00:13:38.843Z,1671581618.843 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-21T00:13:38.843Z,1671581618.843 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-21T00:13:40.852Z,1671581620.852 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001340.00,A,3648.13198,N,12147.21423,W,0.019,0.00,211222,,,D*7B
2022-12-21T00:13:40.854Z,1671581620.854 [NAL9602](INFO): GPS fix at 20221221T001340: (36.802200, -121.786904)
2022-12-21T00:13:40.889Z,1671581620.889 [Default:CheckIn:Read_GPS] Stopped
2022-12-21T00:13:40.889Z,1671581620.889 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-21T00:13:48.567Z,1671581628.567 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20221220T212805/Courier0091.lzma
2022-12-21T00:13:49.570Z,1671581629.570 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0091.lzma.bak
2022-12-21T00:13:49.570Z,1671581629.570 [DataOverHttps](INFO): SBD MOMSN=17400386
2022-12-21T00:14:05.451Z,1671581645.451 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20221220T212805/Express0092.lzma
2022-12-21T00:14:06.454Z,1671581646.454 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0092.lzma.bak
2022-12-21T00:14:06.454Z,1671581646.454 [DataOverHttps](INFO): SBD MOMSN=17400389
2022-12-21T00:14:07.950Z,1671581647.950 [Default:CheckIn:Read_Iridium] Stopped
2022-12-21T00:14:07.951Z,1671581647.951 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-21T00:14:07.951Z,1671581647.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-21T00:14:11.569Z,1671581651.569 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-21T00:14:11.648Z,1671581651.648 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-21T00:17:55.053Z,1671581875.053 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:18:08.376Z,1671581888.376 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:18:22.114Z,1671581902.114 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:18:35.444Z,1671581915.444 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:18:43.125Z,1671581923.125 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-21T00:18:48.780Z,1671581928.780 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:19:02.116Z,1671581942.116 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:19:08.604Z,1671581948.604 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-21T00:19:08.604Z,1671581948.604 [Default:CheckIn:C.Wait] Stopped
2022-12-21T00:19:08.604Z,1671581948.604 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-21T00:19:08.604Z,1671581948.604 [Default:CheckIn:D] Running Loop=1
2022-12-21T00:19:09.017Z,1671581949.017 [Default:CheckIn:D] Stopped
2022-12-21T00:19:09.017Z,1671581949.017 [Default:CheckIn:E] Running Loop=1
2022-12-21T00:19:09.407Z,1671581949.407 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 166.279248 min 
2022-12-21T00:19:09.407Z,1671581949.407 [Default:CheckIn:E] Stopped
2022-12-21T00:19:09.407Z,1671581949.407 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-21T00:19:09.407Z,1671581949.407 [Default:CheckIn] Stopped
2022-12-21T00:19:09.407Z,1671581949.407 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-21T00:19:09.408Z,1671581949.408 [Default:CheckIn](INFO): Running loop #30
2022-12-21T00:19:09.408Z,1671581949.408 [Default:CheckIn] Running Loop=30
2022-12-21T00:19:09.408Z,1671581949.408 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-21T00:19:09.408Z,1671581949.408 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-21T00:19:11.422Z,1671581951.422 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001910.00,A,3648.12992,N,12147.21719,W,0.058,0.00,211222,,,D*78
2022-12-21T00:19:11.424Z,1671581951.424 [NAL9602](INFO): GPS fix at 20221221T001910: (36.802165, -121.786953)
2022-12-21T00:19:11.434Z,1671581951.434 [Default:CheckIn:Read_GPS] Stopped
2022-12-21T00:19:11.434Z,1671581951.434 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-21T00:19:15.448Z,1671581955.448 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:19:18.959Z,1671581958.959 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0094.lzma
2022-12-21T00:19:19.962Z,1671581959.962 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0094.lzma.bak
2022-12-21T00:19:19.962Z,1671581959.962 [DataOverHttps](INFO): SBD MOMSN=17400395
2022-12-21T00:19:28.376Z,1671581968.376 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:19:35.668Z,1671581975.668 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20221220T212805/Express0095.lzma
2022-12-21T00:19:36.670Z,1671581976.670 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0095.lzma.bak
2022-12-21T00:19:36.670Z,1671581976.670 [DataOverHttps](INFO): SBD MOMSN=17400398
2022-12-21T00:19:38.085Z,1671581978.085 [Default:CheckIn:Read_Iridium] Stopped
2022-12-21T00:19:38.085Z,1671581978.085 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-21T00:19:38.085Z,1671581978.085 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-21T00:19:41.708Z,1671581981.708 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:19:43.325Z,1671581983.325 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-21T00:19:55.444Z,1671581995.444 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:20:08.386Z,1671582008.386 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-21T00:21:42.099Z,1671582102.099 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-12-21T00:24:38.706Z,1671582278.706 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-21T00:24:38.706Z,1671582278.706 [Default:CheckIn:C.Wait] Stopped
2022-12-21T00:24:38.706Z,1671582278.706 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-21T00:24:38.706Z,1671582278.706 [Default:CheckIn:D] Running Loop=1
2022-12-21T00:24:39.109Z,1671582279.109 [Default:CheckIn:D] Stopped
2022-12-21T00:24:39.109Z,1671582279.109 [Default:CheckIn:E] Running Loop=1
2022-12-21T00:24:39.518Z,1671582279.518 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 171.780778 min 
2022-12-21T00:24:39.518Z,1671582279.518 [Default:CheckIn:E] Stopped
2022-12-21T00:24:39.518Z,1671582279.518 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-21T00:24:39.518Z,1671582279.518 [Default:CheckIn] Stopped
2022-12-21T00:24:39.518Z,1671582279.518 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-21T00:24:39.518Z,1671582279.518 [Default:CheckIn](INFO): Running loop #31
2022-12-21T00:24:39.518Z,1671582279.518 [Default:CheckIn] Running Loop=31
2022-12-21T00:24:39.518Z,1671582279.518 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-21T00:24:39.518Z,1671582279.518 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-21T00:24:41.539Z,1671582281.539 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002440.00,A,3648.13165,N,12147.21231,W,0.000,0.00,211222,,,D*70
2022-12-21T00:24:41.542Z,1671582281.542 [NAL9602](INFO): GPS fix at 20221221T002440: (36.802194, -121.786872)
2022-12-21T00:24:41.552Z,1671582281.552 [Default:CheckIn:Read_GPS] Stopped
2022-12-21T00:24:41.552Z,1671582281.552 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-21T00:24:48.827Z,1671582288.827 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221220T212805/Courier0097.lzma
2022-12-21T00:24:49.830Z,1671582289.830 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0097.lzma.bak
2022-12-21T00:24:49.830Z,1671582289.830 [DataOverHttps](INFO): SBD MOMSN=17400402
2022-12-21T00:25:08.568Z,1671582308.568 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20221220T212805/Express0098.lzma
2022-12-21T00:25:09.570Z,1671582309.570 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0098.lzma.bak
2022-12-21T00:25:09.570Z,1671582309.570 [DataOverHttps](INFO): SBD MOMSN=17400405
2022-12-21T00:25:11.068Z,1671582311.068 [Default:CheckIn:Read_Iridium] Stopped
2022-12-21T00:25:11.068Z,1671582311.068 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-21T00:25:11.068Z,1671582311.068 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-21T00:25:12.261Z,1671582312.261 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-12-21T00:25:12.340Z,1671582312.340 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-12-21T00:27:03.863Z,1671582423.863 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2022-12-21T00:27:03.866Z,1671582423.866 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2022-12-21T00:28:25.378Z,1671582505.378 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-12-21T00:28:25.378Z,1671582505.378 [DropWeight] Hardware Fault, FailCount= 1
2022-12-21T00:28:25.379Z,1671582505.379 [DropWeight](ERROR): Hardware Fault
2022-12-21T00:28:25.396Z,1671582505.396 [CBIT](INFO): Critical error at 20221221T002825
2022-12-21T00:28:25.399Z,1671582505.399 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-12-21T00:28:25.399Z,1671582505.399 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-12-21T00:28:25.794Z,1671582505.794 [CBIT](INFO): Critical error at 20221221T002825
2022-12-21T00:29:43.349Z,1671582583.349 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-21T00:30:11.639Z,1671582611.639 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-21T00:30:11.639Z,1671582611.639 [Default:CheckIn:C.Wait] Stopped
2022-12-21T00:30:11.639Z,1671582611.639 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-21T00:30:11.639Z,1671582611.639 [Default:CheckIn:D] Running Loop=1
2022-12-21T00:30:12.052Z,1671582612.052 [Default:CheckIn:D] Stopped
2022-12-21T00:30:12.052Z,1671582612.052 [Default:CheckIn:E] Running Loop=1
2022-12-21T00:30:12.447Z,1671582612.447 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 177.329834 min 
2022-12-21T00:30:12.447Z,1671582612.447 [Default:CheckIn:E] Stopped
2022-12-21T00:30:12.447Z,1671582612.447 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-21T00:30:12.447Z,1671582612.447 [Default:CheckIn] Stopped
2022-12-21T00:30:12.447Z,1671582612.447 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-21T00:30:12.447Z,1671582612.447 [Default:CheckIn](INFO): Running loop #32
2022-12-21T00:30:12.447Z,1671582612.447 [Default:CheckIn] Running Loop=32
2022-12-21T00:30:12.447Z,1671582612.447 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-21T00:30:12.448Z,1671582612.448 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-21T00:30:14.460Z,1671582614.460 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003014.00,A,3648.13279,N,12147.21599,W,0.039,0.00,211222,,,D*75
2022-12-21T00:30:14.463Z,1671582614.463 [NAL9602](INFO): GPS fix at 20221221T003014: (36.802213, -121.786933)
2022-12-21T00:30:14.481Z,1671582614.481 [Default:CheckIn:Read_GPS] Stopped
2022-12-21T00:30:14.481Z,1671582614.481 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-21T00:30:22.151Z,1671582622.151 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20221220T212805/Courier0100.lzma
2022-12-21T00:30:23.154Z,1671582623.154 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0100.lzma.bak
2022-12-21T00:30:23.154Z,1671582623.154 [DataOverHttps](INFO): SBD MOMSN=17400408
2022-12-21T00:30:41.656Z,1671582641.656 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20221220T212805/Express0101.lzma
2022-12-21T00:30:42.654Z,1671582642.654 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0101.lzma.bak
2022-12-21T00:30:42.654Z,1671582642.654 [DataOverHttps](INFO): SBD MOMSN=17400413
2022-12-21T00:30:44.025Z,1671582644.025 [Default:CheckIn:Read_Iridium] Stopped
2022-12-21T00:30:44.025Z,1671582644.025 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-21T00:30:44.025Z,1671582644.025 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-21T00:30:46.433Z,1671582646.433 [NAL9602](INFO): Not Powering down - fast GPS
2022-12-21T00:35:44.701Z,1671582944.701 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-21T00:35:44.701Z,1671582944.701 [Default:CheckIn:C.Wait] Stopped
2022-12-21T00:35:44.701Z,1671582944.701 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-21T00:35:44.701Z,1671582944.701 [Default:CheckIn:D] Running Loop=1
2022-12-21T00:35:45.113Z,1671582945.113 [Default:CheckIn:D] Stopped
2022-12-21T00:35:45.113Z,1671582945.113 [Default:CheckIn:E] Running Loop=1
2022-12-21T00:35:45.519Z,1671582945.519 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.880843 min 
2022-12-21T00:35:45.519Z,1671582945.519 [Default:CheckIn:E] Stopped
2022-12-21T00:35:45.519Z,1671582945.519 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-21T00:35:45.519Z,1671582945.519 [Default:CheckIn] Stopped
2022-12-21T00:35:45.519Z,1671582945.519 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-21T00:35:45.519Z,1671582945.519 [Default:CheckIn](INFO): Running loop #33
2022-12-21T00:35:45.519Z,1671582945.519 [Default:CheckIn] Running Loop=33
2022-12-21T00:35:45.519Z,1671582945.519 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-21T00:35:45.520Z,1671582945.520 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-21T00:35:47.526Z,1671582947.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003547.00,A,3648.13272,N,12147.21291,W,0.019,0.00,211222,,,D*70
2022-12-21T00:35:47.529Z,1671582947.529 [NAL9602](INFO): GPS fix at 20221221T003547: (36.802212, -121.786882)
2022-12-21T00:35:47.539Z,1671582947.539 [Default:CheckIn:Read_GPS] Stopped
2022-12-21T00:35:47.539Z,1671582947.539 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-21T00:35:55.315Z,1671582955.315 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221220T212805/Courier0103.lzma
2022-12-21T00:35:56.318Z,1671582956.318 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Courier0103.lzma.bak
2022-12-21T00:35:56.318Z,1671582956.318 [DataOverHttps](INFO): SBD MOMSN=17400417
2022-12-21T00:36:12.096Z,1671582972.096 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20221220T212805/Express0104.lzma
2022-12-21T00:36:13.098Z,1671582973.098 [DataOverHttps](INFO): Moved sent file to Logs/20221220T212805/Express0104.lzma.bak
2022-12-21T00:36:13.098Z,1671582973.098 [DataOverHttps](INFO): SBD MOMSN=17400420
2022-12-21T00:36:14.201Z,1671582974.201 [Default:CheckIn:Read_Iridium] Stopped
2022-12-21T00:36:14.201Z,1671582974.201 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-21T00:36:14.201Z,1671582974.201 [Default:CheckIn:C.Wait](DEBUG): Initi