2026-03-02T22:50:43.606Z,1772491843.606 [Supervisor](DEBUG): Initializing supervisor.
2026-03-02T22:50:43.610Z,1772491843.610 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2026-03-02T22:50:43.610Z,1772491843.610 [SyncHandler](INFO): Protected caller Thread ID is 837
2026-03-02T22:50:43.611Z,1772491843.611 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2026-03-02T22:50:43.612Z,1772491843.612 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2026-03-02T22:50:43.612Z,1772491843.612 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2026-03-02T22:50:43.616Z,1772491843.616 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2026-03-02T22:50:43.635Z,1772491843.635 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2026-03-02T22:50:43.636Z,1772491843.636 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2026-03-02T22:50:43.637Z,1772491843.637 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2026-03-02T22:50:43.641Z,1772491843.641 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2026-03-02T22:50:43.642Z,1772491843.642 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2026-03-02T22:50:43.642Z,1772491843.642 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2026-03-02T22:50:43.644Z,1772491843.644 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2026-03-02T22:50:43.645Z,1772491843.645 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2026-03-02T22:50:43.645Z,1772491843.645 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2026-03-02T22:50:43.649Z,1772491843.649 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2026-03-02T22:50:43.649Z,1772491843.649 [Supervisor](INFO): Looking for Config files in directory: Config/
2026-03-02T22:50:43.654Z,1772491843.654 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2026-03-02T22:50:44.128Z,1772491844.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2026-03-02T22:50:44.130Z,1772491844.130 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2026-03-02T22:50:44.344Z,1772491844.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2026-03-02T22:50:44.345Z,1772491844.345 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2026-03-02T22:50:44.444Z,1772491844.444 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2026-03-02T22:50:44.446Z,1772491844.446 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2026-03-02T22:50:44.683Z,1772491844.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2026-03-02T22:50:44.685Z,1772491844.685 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2026-03-02T22:50:44.773Z,1772491844.773 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2026-03-02T22:50:44.878Z,1772491844.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2026-03-02T22:50:44.879Z,1772491844.879 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2026-03-02T22:50:45.233Z,1772491845.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2026-03-02T22:50:45.235Z,1772491845.235 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2026-03-02T22:50:45.376Z,1772491845.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2026-03-02T22:50:45.377Z,1772491845.377 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2026-03-02T22:50:45.935Z,1772491845.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2026-03-02T22:50:45.936Z,1772491845.936 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2026-03-02T22:50:46.121Z,1772491846.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2026-03-02T22:50:46.123Z,1772491846.123 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2026-03-02T22:50:46.556Z,1772491846.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2026-03-02T22:50:46.557Z,1772491846.557 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2026-03-02T22:50:47.660Z,1772491847.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2026-03-02T22:50:47.662Z,1772491847.662 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2026-03-02T22:50:47.779Z,1772491847.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2026-03-02T22:50:47.780Z,1772491847.780 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2026-03-02T22:50:48.614Z,1772491848.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2026-03-02T22:50:48.616Z,1772491848.616 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2026-03-02T22:50:48.963Z,1772491848.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2026-03-02T22:50:48.965Z,1772491848.965 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2026-03-02T22:50:49.212Z,1772491849.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2026-03-02T22:50:49.214Z,1772491849.214 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2026-03-02T22:50:49.218Z,1772491849.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2026-03-02T22:50:49.348Z,1772491849.348 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2026-03-02T22:50:49.440Z,1772491849.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2026-03-02T22:50:49.584Z,1772491849.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2026-03-02T22:50:49.678Z,1772491849.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2026-03-02T22:50:49.951Z,1772491849.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2026-03-02T22:50:49.953Z,1772491849.953 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2026-03-02T22:50:50.064Z,1772491850.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2026-03-02T22:50:50.179Z,1772491850.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2026-03-02T22:50:50.285Z,1772491850.285 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2026-03-02T22:50:50.446Z,1772491850.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2026-03-02T22:50:50.551Z,1772491850.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2026-03-02T22:50:50.646Z,1772491850.646 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2026-03-02T22:50:50.658Z,1772491850.658 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2026-03-02T22:50:50.674Z,1772491850.674 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2026-03-02T22:50:50.676Z,1772491850.676 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2026-03-02T22:50:50.841Z,1772491850.841 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2026-03-02T22:50:50.841Z,1772491850.841 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2026-03-02T22:50:50.911Z,1772491850.911 [VerticalControl](DEBUG): Construct VerticalControl.
2026-03-02T22:50:50.972Z,1772491850.972 [VerticalControl] Loaded
2026-03-02T22:50:50.972Z,1772491850.972 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2026-03-02T22:50:50.975Z,1772491850.975 [HorizontalControl](DEBUG): Construct HorizontalControl.
2026-03-02T22:50:51.022Z,1772491851.022 [HorizontalControl] Loaded
2026-03-02T22:50:51.022Z,1772491851.022 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2026-03-02T22:50:51.024Z,1772491851.024 [SpeedControl](DEBUG): Construct SpeedControl.
2026-03-02T22:50:51.027Z,1772491851.027 [SpeedControl] Loaded
2026-03-02T22:50:51.027Z,1772491851.027 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2026-03-02T22:50:51.030Z,1772491851.030 [LoopControl](DEBUG): Construct LoopControl.
2026-03-02T22:50:51.031Z,1772491851.031 [LoopControl] Loaded
2026-03-02T22:50:51.031Z,1772491851.031 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2026-03-02T22:50:51.031Z,1772491851.031 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2026-03-02T22:50:51.033Z,1772491851.033 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2026-03-02T22:50:51.220Z,1772491851.220 [BuoyancyServo] Loaded
2026-03-02T22:50:51.221Z,1772491851.221 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2026-03-02T22:50:51.242Z,1772491851.242 [ElevatorServo] Loaded
2026-03-02T22:50:51.243Z,1772491851.243 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2026-03-02T22:50:51.263Z,1772491851.263 [MassServo] Loaded
2026-03-02T22:50:51.264Z,1772491851.264 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2026-03-02T22:50:51.284Z,1772491851.284 [RudderServo] Loaded
2026-03-02T22:50:51.284Z,1772491851.284 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2026-03-02T22:50:51.300Z,1772491851.300 [ThrusterHE] Loaded
2026-03-02T22:50:51.300Z,1772491851.300 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2026-03-02T22:50:51.301Z,1772491851.301 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2026-03-02T22:50:51.302Z,1772491851.302 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2026-03-02T22:50:51.373Z,1772491851.373 [DepthRateCalculator] Loaded
2026-03-02T22:50:51.373Z,1772491851.373 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2026-03-02T22:50:51.378Z,1772491851.378 [PitchRateCalculator] Loaded
2026-03-02T22:50:51.378Z,1772491851.378 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2026-03-02T22:50:51.388Z,1772491851.388 [SpeedCalculator] Loaded
2026-03-02T22:50:51.388Z,1772491851.388 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2026-03-02T22:50:51.393Z,1772491851.393 [YawRateCalculator] Loaded
2026-03-02T22:50:51.393Z,1772491851.393 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2026-03-02T22:50:51.411Z,1772491851.411 [ElevatorOffsetCalculator] Loaded
2026-03-02T22:50:51.411Z,1772491851.411 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2026-03-02T22:50:51.412Z,1772491851.412 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2026-03-02T22:50:51.412Z,1772491851.412 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2026-03-02T22:50:51.553Z,1772491851.553 [DeadReckonUsingMultipleVelocitySources] Loaded
2026-03-02T22:50:51.553Z,1772491851.553 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2026-03-02T22:50:51.567Z,1772491851.567 [NavChart] Loaded
2026-03-02T22:50:51.567Z,1772491851.567 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2026-03-02T22:50:51.573Z,1772491851.573 [UniversalFixResidualReporter] Loaded
2026-03-02T22:50:51.573Z,1772491851.573 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2026-03-02T22:50:51.573Z,1772491851.573 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2026-03-02T22:50:51.575Z,1772491851.575 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2026-03-02T22:50:52.962Z,1772491852.962 [AHRS_M2] Loaded
2026-03-02T22:50:52.962Z,1772491852.962 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2026-03-02T22:50:53.216Z,1772491853.216 [BackseatComponent] Loaded
2026-03-02T22:50:53.216Z,1772491853.216 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2026-03-02T22:50:53.217Z,1772491853.217 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4092B4E0
2026-03-02T22:50:53.218Z,1772491853.218 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923
2026-03-02T22:50:53.221Z,1772491853.221 [LcmPublisher] Loaded
2026-03-02T22:50:53.221Z,1772491853.221 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2026-03-02T22:50:53.228Z,1772491853.228 [BioacousticsDataBridge] Loaded
2026-03-02T22:50:53.228Z,1772491853.228 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2026-03-02T22:50:54.059Z,1772491854.059 [BPC1] Loaded
2026-03-02T22:50:54.060Z,1772491854.060 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2026-03-02T22:50:54.158Z,1772491854.158 [DAT] Loaded
2026-03-02T22:50:54.159Z,1772491854.159 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2026-03-02T22:50:54.160Z,1772491854.160 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4095B4E0
2026-03-02T22:50:54.160Z,1772491854.160 [DAT ThreadHandler](INFO): Protected caller Thread ID is 924
2026-03-02T22:50:54.303Z,1772491854.303 [DataOverHttps] Loaded
2026-03-02T22:50:54.304Z,1772491854.304 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2026-03-02T22:50:54.305Z,1772491854.305 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4098B4E0
2026-03-02T22:50:54.305Z,1772491854.305 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2026-03-02T22:50:54.328Z,1772491854.328 [Depth_Keller] Loaded
2026-03-02T22:50:54.328Z,1772491854.328 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2026-03-02T22:50:54.393Z,1772491854.393 [NAL9602] Loaded
2026-03-02T22:50:54.394Z,1772491854.394 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2026-03-02T22:50:54.423Z,1772491854.423 [Onboard] Loaded
2026-03-02T22:50:54.423Z,1772491854.423 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2026-03-02T22:50:54.424Z,1772491854.424 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409BB4E0
2026-03-02T22:50:54.425Z,1772491854.425 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2026-03-02T22:50:54.431Z,1772491854.431 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2026-03-02T22:50:54.444Z,1772491854.444 [PowerOnly] Loaded
2026-03-02T22:50:54.445Z,1772491854.445 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2026-03-02T22:50:54.459Z,1772491854.459 [Power24vConverter] Loaded
2026-03-02T22:50:54.460Z,1772491854.460 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2026-03-02T22:50:54.473Z,1772491854.473 [Radio_Surface] Loaded
2026-03-02T22:50:54.474Z,1772491854.474 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2026-03-02T22:50:54.475Z,1772491854.475 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409EB4E0
2026-03-02T22:50:54.475Z,1772491854.475 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2026-03-02T22:50:54.490Z,1772491854.490 [Sonardyne_Nano] Loaded
2026-03-02T22:50:54.490Z,1772491854.490 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2026-03-02T22:50:54.514Z,1772491854.514 [Waterlinked] Loaded
2026-03-02T22:50:54.514Z,1772491854.514 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2026-03-02T22:50:54.514Z,1772491854.514 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2026-03-02T22:50:54.515Z,1772491854.515 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2026-03-02T22:50:54.602Z,1772491854.602 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2026-03-02T22:50:54.602Z,1772491854.602 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2026-03-02T22:50:54.818Z,1772491854.818 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2026-03-02T22:50:54.819Z,1772491854.819 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2026-03-02T22:50:54.867Z,1772491854.867 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2026-03-02T22:50:54.869Z,1772491854.869 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2026-03-02T22:50:55.096Z,1772491855.096 [CTD_Seabird] Loaded
2026-03-02T22:50:55.097Z,1772491855.097 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2026-03-02T22:50:55.098Z,1772491855.098 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B344E0
2026-03-02T22:50:55.098Z,1772491855.098 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2026-03-02T22:50:55.120Z,1772491855.120 [PAR_Licor] Loaded
2026-03-02T22:50:55.121Z,1772491855.121 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2026-03-02T22:50:55.165Z,1772491855.165 [WetLabsBB2FL] Loaded
2026-03-02T22:50:55.165Z,1772491855.165 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2026-03-02T22:50:55.166Z,1772491855.166 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B644E0
2026-03-02T22:50:55.166Z,1772491855.166 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2026-03-02T22:50:55.167Z,1772491855.167 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2026-03-02T22:50:55.168Z,1772491855.168 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2026-03-02T22:50:55.571Z,1772491855.571 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2026-03-02T22:50:55.572Z,1772491855.572 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2026-03-02T22:50:55.763Z,1772491855.763 [SBIT](DEBUG): Construct Startup Built In Test.
2026-03-02T22:50:55.772Z,1772491855.772 [SBIT] Loaded
2026-03-02T22:50:55.773Z,1772491855.773 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2026-03-02T22:50:55.776Z,1772491855.776 [IBIT](DEBUG): Construct Initiated Built In Test.
2026-03-02T22:50:55.789Z,1772491855.789 [IBIT] Loaded
2026-03-02T22:50:55.789Z,1772491855.789 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2026-03-02T22:50:55.794Z,1772491855.794 [CBIT](DEBUG): Construct Continuous Built In Test.
2026-03-02T22:50:55.874Z,1772491855.874 [CBIT] Loaded
2026-03-02T22:50:55.874Z,1772491855.874 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2026-03-02T22:50:55.907Z,1772491855.907 [GFScanner] Loaded
2026-03-02T22:50:55.907Z,1772491855.907 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2026-03-02T22:50:55.907Z,1772491855.907 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2026-03-02T22:50:55.914Z,1772491855.914 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2026-03-02T22:50:55.917Z,1772491855.917 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2026-03-02T22:50:55.928Z,1772491855.928 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2026-03-02T22:50:55.929Z,1772491855.929 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2026-03-02T22:50:55.929Z,1772491855.929 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2026-03-02T22:50:55.934Z,1772491855.934 [Supervisor](INFO): Main Thread ID is 830
2026-03-02T22:50:55.934Z,1772491855.934 [Supervisor](DEBUG): Running supervisor.
2026-03-02T22:50:55.934Z,1772491855.934 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2026-03-02T22:50:55.935Z,1772491855.935 [CommandExec](INFO): Initializing the command executive.
2026-03-02T22:50:55.937Z,1772491855.937 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2026-03-02T22:50:55.939Z,1772491855.939 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2026-03-02T22:50:55.939Z,1772491855.939 [controlThread](DEBUG): Initializing ControlThread
2026-03-02T22:50:55.940Z,1772491855.940 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2026-03-02T22:50:55.942Z,1772491855.942 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2026-03-02T22:50:55.942Z,1772491855.942 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2026-03-02T22:50:55.943Z,1772491855.943 [LoopControl](DEBUG): Initialize LoopControlComponent.
2026-03-02T22:50:55.944Z,1772491855.944 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2026-03-02T22:50:55.945Z,1772491855.945 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2026-03-02T22:50:55.945Z,1772491855.945 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2026-03-02T22:50:55.945Z,1772491855.945 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2026-03-02T22:50:55.946Z,1772491855.946 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2026-03-02T22:50:55.947Z,1772491855.947 [NavChart](DEBUG): Initialize NavChart Navigation.
2026-03-02T22:50:55.947Z,1772491855.947 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2026-03-02T22:50:55.953Z,1772491855.953 [SBIT](INFO): Initialize SBIT Component.
2026-03-02T22:50:55.953Z,1772491855.953 [SBIT](IMPORTANT): git: 2025-12-15_A
2026-03-02T22:50:55.954Z,1772491855.954 [SBIT](INFO): git hash: 5b50703234a74cb9c609bca727303ff504a0b330
2026-03-02T22:50:55.954Z,1772491855.954 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2026-03-02T22:50:55.955Z,1772491855.955 [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
2026-03-02T22:50:55.956Z,1772491855.956 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2026-03-02T22:50:55.957Z,1772491855.957 [IBIT](INFO): Initialize IBIT Component.
2026-03-02T22:50:55.958Z,1772491855.958 [CBIT](DEBUG): Initialize CBIT Component.
2026-03-02T22:50:55.959Z,1772491855.959 [logger ThreadHandler](INFO): Handler Thread ID is 934
2026-03-02T22:50:55.969Z,1772491855.969 [CBIT](DEBUG): Initialized mux pins.
2026-03-02T22:50:55.969Z,1772491855.969 [CBIT](DEBUG): Initializing the watchdog timer.
2026-03-02T22:50:55.977Z,1772491855.977 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935
2026-03-02T22:50:55.989Z,1772491855.989 [DAT ThreadHandler](INFO): Handler Thread ID is 936
2026-03-02T22:50:55.990Z,1772491855.990 [DAT](INFO): Powering up
2026-03-02T22:50:55.990Z,1772491855.990 [DAT](DEBUG): Initializing DAT.
2026-03-02T22:50:55.993Z,1772491855.993 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2026-03-02T22:50:55.993Z,1772491855.993 [CBIT](DEBUG): Initializing heartbeat.
2026-03-02T22:50:55.995Z,1772491855.995 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 938
2026-03-02T22:50:55.997Z,1772491855.997 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2026-03-02T22:50:56.005Z,1772491856.005 [Onboard ThreadHandler](INFO): Handler Thread ID is 939
2026-03-02T22:50:56.023Z,1772491856.023 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 940
2026-03-02T22:50:56.042Z,1772491856.042 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941
2026-03-02T22:50:56.042Z,1772491856.042 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-02T22:50:56.046Z,1772491856.046 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943
2026-03-02T22:50:56.049Z,1772491856.049 [WetLabsBB2FL](INFO): Powering up
2026-03-02T22:50:56.050Z,1772491856.050 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945
2026-03-02T22:50:56.059Z,1772491856.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2026-03-02T22:50:56.059Z,1772491856.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2026-03-02T22:50:56.059Z,1772491856.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000
2026-03-02T22:50:56.059Z,1772491856.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000
2026-03-02T22:50:56.059Z,1772491856.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000
2026-03-02T22:50:56.059Z,1772491856.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000
2026-03-02T22:50:56.060Z,1772491856.060 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000
2026-03-02T22:50:56.060Z,1772491856.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000
2026-03-02T22:50:56.060Z,1772491856.060 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000
2026-03-02T22:50:56.060Z,1772491856.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000
2026-03-02T22:50:56.061Z,1772491856.061 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000
2026-03-02T22:50:56.061Z,1772491856.061 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000
2026-03-02T22:50:56.061Z,1772491856.061 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000
2026-03-02T22:50:56.061Z,1772491856.061 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000
2026-03-02T22:50:56.061Z,1772491856.061 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000
2026-03-02T22:50:56.061Z,1772491856.061 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000
2026-03-02T22:50:56.062Z,1772491856.062 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000
2026-03-02T22:50:56.062Z,1772491856.062 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000
2026-03-02T22:50:56.062Z,1772491856.062 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000
2026-03-02T22:50:56.062Z,1772491856.062 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000
2026-03-02T22:50:56.065Z,1772491856.065 [CBIT](DEBUG): Deactivating emergency mode.
2026-03-02T22:50:56.105Z,1772491856.105 [CBIT](DEBUG): Backplane powered.
2026-03-02T22:50:56.105Z,1772491856.105 [GFScanner](DEBUG): Initializing GFScanner
2026-03-02T22:50:56.105Z,1772491856.105 [GFScanner](DEBUG): Deactivating GF circuits.
2026-03-02T22:50:56.110Z,1772491856.110 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2026-03-02T22:50:56.110Z,1772491856.110 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2026-03-02T22:50:56.111Z,1772491856.111 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2026-03-02T22:50:56.121Z,1772491856.121 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2026-03-02T22:50:56.190Z,1772491856.190 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2026-03-02T22:50:56.194Z,1772491856.194 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-02T22:50:56.235Z,1772491856.235 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2026-03-02T22:50:56.236Z,1772491856.236 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2026-03-02T22:50:56.236Z,1772491856.236 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2026-03-02T22:50:56.239Z,1772491856.239 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2026-03-02T22:50:56.429Z,1772491856.429 [Radio_Surface](INFO): Powering up
2026-03-02T22:50:56.542Z,1772491856.542 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2026-03-02T22:50:56.576Z,1772491856.576 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2026-03-02T22:50:56.577Z,1772491856.577 [Default:A.Wait](DEBUG): Construct Wait.
2026-03-02T22:50:56.579Z,1772491856.579 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-02T22:50:56.632Z,1772491856.632 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2026-03-02T22:50:56.634Z,1772491856.634 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2026-03-02T22:50:56.648Z,1772491856.648 [Default:E.Execute](DEBUG): Construct Execute.
2026-03-02T22:50:56.651Z,1772491856.651 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2026-03-02T22:50:56.658Z,1772491856.658 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,Waterlinked,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,GFScanner,LcmPublisher,Reporter,LogSplitter,
2026-03-02T22:50:56.685Z,1772491856.685 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2026-03-02T22:50:56.845Z,1772491856.845 [Depth_Keller](INFO): Initializing.
2026-03-02T22:50:56.847Z,1772491856.847 [Power24vConverter](INFO): Powering up.
2026-03-02T22:50:56.848Z,1772491856.848 [Sonardyne_Nano](INFO): Initializing.
2026-03-02T22:50:56.875Z,1772491856.875 [DepthRateCalculator](ERROR): Depth measurement is not active
2026-03-02T22:50:56.918Z,1772491856.918 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2026-03-02T22:50:56.925Z,1772491856.925 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2026-03-02T22:50:56.926Z,1772491856.926 [ElevatorServo](DEBUG): Initializing EZServoServo.
2026-03-02T22:50:56.933Z,1772491856.933 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2026-03-02T22:50:56.934Z,1772491856.934 [MassServo](DEBUG): Initializing EZServoServo.
2026-03-02T22:50:56.941Z,1772491856.941 [MassServo](DEBUG): Initializing MassServo.
2026-03-02T22:50:56.942Z,1772491856.942 [RudderServo](DEBUG): Initializing EZServoServo.
2026-03-02T22:50:56.949Z,1772491856.949 [RudderServo](DEBUG): Initializing RudderServo.
2026-03-02T22:50:56.950Z,1772491856.950 [ThrusterHE](DEBUG): Initializing EZServoServo.
2026-03-02T22:50:56.957Z,1772491856.957 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2026-03-02T22:50:58.661Z,1772491858.661 [WetLabsBB2FL](INFO): Powering down
2026-03-02T22:50:59.091Z,1772491859.091 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2026-03-02T22:51:00.341Z,1772491860.341 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2026-03-02T22:51:02.570Z,1772491862.570 [ThrusterHE](ERROR): Zero Speed Commanded.
2026-03-02T22:51:07.837Z,1772491867.837 [DAT](INFO): DAT read:
2026-03-02T22:51:07.838Z,1772491867.838 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2026-03-02T22:51:09.602Z,1772491869.602 [DAT](INFO): DAT read: MF Frequency Band 5120 bandwidth
2026-03-02T22:51:09.603Z,1772491869.603 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.19.1
2026-03-02T22:51:09.604Z,1772491869.604 [DAT](INFO): DAT read: Mar 2 2026 22:51:05
2026-03-02T22:51:10.358Z,1772491870.358 [DAT](INFO): DAT read: Features enabled [Bearing]
2026-03-02T22:51:10.359Z,1772491870.359 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2026-03-02T22:51:10.359Z,1772491870.359 [DAT](INFO): commRate: 800
2026-03-02T22:51:12.425Z,1772491872.425 [DAT](INFO): entering command mode
2026-03-02T22:51:12.625Z,1772491872.625 [DAT](INFO): DAT read:
2026-03-02T22:51:12.626Z,1772491872.626 [DAT](INFO): DAT read: user:1>
2026-03-02T22:51:12.626Z,1772491872.626 [DAT](INFO): setting verbose to 3
2026-03-02T22:51:12.877Z,1772491872.877 [DAT](INFO): DAT read: user:1>
2026-03-02T22:51:12.878Z,1772491872.878 [DAT](INFO): DAT read: Verbose | 3
2026-03-02T22:51:12.879Z,1772491872.879 [DAT](INFO): set verbose to 3
2026-03-02T22:51:12.879Z,1772491872.879 [DAT](INFO): setting DatVerbose to 27440
2026-03-02T22:51:13.129Z,1772491873.129 [DAT](INFO): DAT read: user:2>
2026-03-02T22:51:13.130Z,1772491873.130 [DAT](INFO): DAT read: DatVerbose | 27440
2026-03-02T22:51:13.131Z,1772491873.131 [DAT](INFO): set DatVerbose to 27440
2026-03-02T22:51:13.131Z,1772491873.131 [DAT](INFO): setting transmit power to 8
2026-03-02T22:51:13.381Z,1772491873.381 [DAT](INFO): DAT read: user:3>
2026-03-02T22:51:13.382Z,1772491873.382 [DAT](INFO): DAT read: TxPower | 8 (Max)
2026-03-02T22:51:13.383Z,1772491873.383 [DAT](INFO): set transmit power to 8
2026-03-02T22:51:13.383Z,1772491873.383 [DAT](INFO): setting local address to 12
2026-03-02T22:51:13.633Z,1772491873.633 [DAT](INFO): DAT read: user:4>
2026-03-02T22:51:13.634Z,1772491873.634 [DAT](INFO): DAT read: LocalAddr | 12
2026-03-02T22:51:13.635Z,1772491873.635 [DAT](INFO): set local address to 12
2026-03-02T22:51:13.636Z,1772491873.636 [DAT](INFO): Setting time to: 22:51:13 And date to:3/2/2026
2026-03-02T22:51:13.885Z,1772491873.885 [DAT](INFO): DAT read: user:5>
2026-03-02T22:51:13.886Z,1772491873.886 [DAT](INFO): DAT read: Mon Mar 2, 2026 22:51:13
2026-03-02T22:51:13.887Z,1772491873.887 [DAT](INFO): Local DAT time set to Mon Mar 2, 2026 22:51:13
2026-03-02T22:51:24.597Z,1772491884.597 [NAL9602](INFO): Powering up NAL9602
2026-03-02T22:51:35.514Z,1772491895.514 [NAL9602](INFO): NAL9602 initialized
2026-03-02T22:51:45.676Z,1772491905.676 [SBIT](IMPORTANT): Beginning Startup BIT
2026-03-02T22:51:45.682Z,1772491905.682 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-02T22:51:50.145Z,1772491910.145 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000901
CHAN A1 (24V): -0.003092
CHAN A2 (12V): -0.005004
CHAN A3 (5V): -0.005346
CHAN B0 (3.3V): -0.008627
CHAN B1 (3.15aV): -0.009215
CHAN B2 (3.15bV): -0.011199
CHAN B3 (GND): -0.016554
OPEN: 0.004968
Full Scale: +/- 1 mA
2026-03-02T22:52:39.459Z,1772491959.459 [SBIT](IMPORTANT): SBIT PASSED
2026-03-02T22:52:39.459Z,1772491959.459 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2026-03-02T22:52:39.460Z,1772491959.460 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2026-03-02T22:52:39.460Z,1772491959.460 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2026-03-02T22:52:39.460Z,1772491959.460 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter;
2026-03-02T22:52:39.461Z,1772491959.461 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 15.000000 meter;
2026-03-02T22:52:39.461Z,1772491959.461 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;
2026-03-02T22:52:39.461Z,1772491959.461 [SBIT](IMPORTANT): PowerOnly.simulateHardware=0 bool;
2026-03-02T22:52:39.461Z,1772491959.461 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=170.891507 cubic_centimeter;
2026-03-02T22:52:39.461Z,1772491959.461 [SBIT](IMPORTANT): VerticalControl.massDefault=7.460123 millimeter;
2026-03-02T22:52:39.896Z,1772491959.896 [MissionManager](IMPORTANT): Started mission Startup
2026-03-02T22:52:39.898Z,1772491959.898 [Startup] Running Loop=1
2026-03-02T22:52:39.898Z,1772491959.898 [Startup](DEBUG): Aggregate::initialize Startup
2026-03-02T22:52:39.898Z,1772491959.898 [Startup:A.GoToSurface] Running Loop=1
2026-03-02T22:52:39.898Z,1772491959.898 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-02T22:52:39.898Z,1772491959.898 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-03-02T22:52:39.899Z,1772491959.899 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-03-02T22:52:39.899Z,1772491959.899 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-03-02T22:52:39.899Z,1772491959.899 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-03-02T22:52:39.900Z,1772491959.900 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-02T22:52:39.900Z,1772491959.900 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-02T22:52:39.902Z,1772491959.902 [Startup:StartupSatComms] Running Loop=1
2026-03-02T22:52:39.902Z,1772491959.902 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2026-03-02T22:52:39.902Z,1772491959.902 [Startup:StartupSatComms:A] Running Loop=1
2026-03-02T22:52:40.332Z,1772491960.332 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2026-03-02T22:53:40.042Z,1772492020.042 [Startup:StartupSatComms:A](INFO): Timed out from 2026-03-02T22:52:39.9Z
2026-03-02T22:53:40.042Z,1772492020.042 [Startup:StartupSatComms:A] Stopped
2026-03-02T22:53:40.042Z,1772492020.042 [Startup:StartupSatComms:B] Running Loop=1
2026-03-02T22:53:40.460Z,1772492020.460 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-03-02T22:53:56.244Z,1772492036.244 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2026-03-02T22:53:56.244Z,1772492036.244 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-03-02T22:53:56.275Z,1772492036.275 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-03-02T22:53:56.653Z,1772492036.653 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-03-02T22:53:56.653Z,1772492036.653 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2026-03-02T22:54:23.146Z,1772492063.146 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.009871
2026-03-02T22:53:20.978Z,1772492000.978 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20260302T211013/Courier0034.lzma
2026-03-02T22:53:21.980Z,1772492001.980 [DataOverHttps](INFO): Moved sent file to Logs/20260302T211013/Courier0034.lzma.bak
2026-03-02T22:53:21.980Z,1772492001.980 [DataOverHttps](INFO): SBD MOMSN=26692910
2026-03-02T22:53:42.187Z,1772492022.187 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20260302T224518/Courier0000.lzma
2026-03-02T22:53:42.872Z,1772492022.872 [DataOverHttps](INFO): Moved sent file to Logs/20260302T224518/Courier0000.lzma.bak
2026-03-02T22:53:42.872Z,1772492022.872 [DataOverHttps](INFO): SBD MOMSN=26692912
2026-03-02T22:54:02.587Z,1772492042.587 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20260302T224518/Courier0004.lzma
2026-03-02T22:54:03.569Z,1772492043.569 [DataOverHttps](INFO): Moved sent file to Logs/20260302T224518/Courier0004.lzma.bak
2026-03-02T22:54:03.569Z,1772492043.569 [DataOverHttps](INFO): SBD MOMSN=26692918
2026-03-02T22:54:23.314Z,1772492063.314 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20260302T225043/Courier0000.lzma
2026-03-02T22:54:24.316Z,1772492064.316 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0000.lzma.bak
2026-03-02T22:54:24.316Z,1772492064.316 [DataOverHttps](INFO): SBD MOMSN=26692920
2026-03-02T22:54:40.228Z,1772492080.228 [Startup:StartupSatComms:B](INFO): Timed out from 2026-03-02T22:53:40.0Z
2026-03-02T22:54:40.228Z,1772492080.228 [Startup:StartupSatComms:B] Stopped
2026-03-02T22:54:40.228Z,1772492080.228 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2026-03-02T22:54:40.228Z,1772492080.228 [Startup:StartupSatComms] Stopped
2026-03-02T22:54:40.229Z,1772492080.229 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2026-03-02T22:54:40.267Z,1772492080.267 [Startup](INFO): Completed Startup
2026-03-02T22:54:40.267Z,1772492080.267 [MissionManager](INFO): Startup is completed.
2026-03-02T22:54:40.267Z,1772492080.267 [MissionManager](INFO): Uninitializing Mission Startup
2026-03-02T22:54:40.268Z,1772492080.268 [Startup] Stopped
2026-03-02T22:54:40.268Z,1772492080.268 [Startup](DEBUG): Aggregate::uninitialize Startup
2026-03-02T22:54:40.268Z,1772492080.268 [Startup:A.GoToSurface] Stopped
2026-03-02T22:54:40.268Z,1772492080.268 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-02T22:54:40.660Z,1772492080.660 [MissionManager](IMPORTANT): Started mission Default
2026-03-02T22:54:40.660Z,1772492080.660 [Default] Running Loop=1
2026-03-02T22:54:40.660Z,1772492080.660 [Default](DEBUG): Aggregate::initialize Default
2026-03-02T22:54:40.660Z,1772492080.660 [Default:B.GoToSurface] Running Loop=1
2026-03-02T22:54:40.660Z,1772492080.660 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-02T22:54:40.661Z,1772492080.661 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-03-02T22:54:40.661Z,1772492080.661 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-03-02T22:54:40.661Z,1772492080.661 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-03-02T22:54:40.661Z,1772492080.661 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-03-02T22:54:40.662Z,1772492080.662 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-02T22:54:40.670Z,1772492080.670 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-02T22:54:40.671Z,1772492080.671 [Default:A.Wait] Running Loop=1
2026-03-02T22:54:40.671Z,1772492080.671 [Default:A.Wait](DEBUG): Initialize Wait Component.
2026-03-02T22:54:47.118Z,1772492087.118 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20260302T211013/Express0035.lzma
2026-03-02T22:54:48.122Z,1772492088.122 [DataOverHttps](INFO): Moved sent file to Logs/20260302T211013/Express0035.lzma.bak
2026-03-02T22:54:48.123Z,1772492088.123 [DataOverHttps](INFO): SBD MOMSN=26692923
2026-03-02T22:54:54.031Z,1772492094.031 [Default:A.Wait](INFO): Done Waiting.
2026-03-02T22:54:54.031Z,1772492094.031 [Default:A.Wait] Stopped
2026-03-02T22:54:54.031Z,1772492094.031 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T22:54:54.380Z,1772492094.380 [Default:CheckIn] Running Loop=1
2026-03-02T22:54:54.380Z,1772492094.380 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T22:54:54.380Z,1772492094.380 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T22:54:54.893Z,1772492094.893 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2026-03-02T22:56:39.012Z,1772492199.012 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-02T22:56:43.327Z,1772492203.327 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225641.00,A,3648.16079,N,12147.27854,W,0.505,117.27,020326,,,A*70
2026-03-02T22:56:43.330Z,1772492203.330 [NAL9602](INFO): GPS fix at 20260302T225641: (36.802680, -121.787976)
2026-03-02T22:56:43.465Z,1772492203.465 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T22:56:43.466Z,1772492203.466 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T22:56:43.916Z,1772492203.916 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-03-02T22:56:48.446Z,1772492208.446 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20260302T225043/Courier0004.lzma
2026-03-02T22:56:49.480Z,1772492209.480 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0004.lzma.bak
2026-03-02T22:56:49.480Z,1772492209.480 [DataOverHttps](INFO): SBD MOMSN=26692925
2026-03-02T22:56:55.163Z,1772492215.163 [CommandExec](IMPORTANT): got command gfscan
2026-03-02T22:56:55.308Z,1772492215.308 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-02T22:56:59.809Z,1772492219.809 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005686
CHAN A1 (24V): 0.001692
CHAN A2 (12V): -0.001545
CHAN A3 (5V): -0.008260
CHAN B0 (3.3V): -0.002132
CHAN B1 (3.15aV): -0.007529
CHAN B2 (3.15bV): -0.005311
CHAN B3 (GND): -0.011742
OPEN: 0.003469
Full Scale: +/- 1 mA
2026-03-02T22:57:05.583Z,1772492225.583 [CommandExec](IMPORTANT): got command gfscan
2026-03-02T22:57:05.862Z,1772492225.862 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-02T22:57:10.449Z,1772492230.449 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004483
CHAN A1 (24V): 0.001132
CHAN A2 (12V): -0.002366
CHAN A3 (5V): -0.007801
CHAN B0 (3.3V): -0.005034
CHAN B1 (3.15aV): -0.007894
CHAN B2 (3.15bV): -0.004839
CHAN B3 (GND): -0.006852
OPEN: 0.003682
Full Scale: +/- 1 mA
2026-03-02T22:57:11.150Z,1772492231.150 [DataOverHttps](INFO): Sending 737 bytes from file Logs/20260302T224518/Express0001.lzma
2026-03-02T22:57:12.144Z,1772492232.144 [DataOverHttps](INFO): Moved sent file to Logs/20260302T224518/Express0001.lzma.bak
2026-03-02T22:57:12.145Z,1772492232.145 [DataOverHttps](INFO): SBD MOMSN=26692928
2026-03-02T22:57:15.548Z,1772492235.548 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-02T22:57:17.564Z,1772492237.564 [CommandExec](IMPORTANT): got command gfscan
2026-03-02T22:57:17.868Z,1772492237.868 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-02T22:57:22.865Z,1772492242.865 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-02T22:57:23.096Z,1772492243.096 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000146
CHAN A1 (24V): 0.007831
CHAN A2 (12V): -0.000359
CHAN A3 (5V): -0.007936
CHAN B0 (3.3V): -0.005439
CHAN B1 (3.15aV): -0.009057
CHAN B2 (3.15bV): -0.005310
CHAN B3 (GND): -0.008796
OPEN: 0.002436
Full Scale: +/- 1 mA
2026-03-02T22:57:29.680Z,1772492249.680 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-02T22:57:29.687Z,1772492249.687 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 4.
2026-03-02T22:57:29.691Z,1772492249.691 [BPC1](INFO): Received data from all battery sticks.
2026-03-02T22:57:36.838Z,1772492256.838 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20260302T224518/Express0005.lzma
2026-03-02T22:57:37.840Z,1772492257.840 [DataOverHttps](INFO): Moved sent file to Logs/20260302T224518/Express0005.lzma.bak
2026-03-02T22:57:37.840Z,1772492257.840 [DataOverHttps](INFO): SBD MOMSN=26692943
2026-03-02T22:57:44.279Z,1772492264.279 [CommandExec](IMPORTANT): got command ! echo d > /dev/loadB4
2026-03-02T22:57:48.986Z,1772492268.986 [CommandExec](IMPORTANT): got command ! echo d > /dev/loadC3
2026-03-02T22:57:51.684Z,1772492271.684 [CommandExec](IMPORTANT): got command gfscan
2026-03-02T22:57:51.705Z,1772492271.705 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-02T22:57:56.014Z,1772492276.014 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.038376
CHAN A1 (24V): 0.068233
CHAN A2 (12V): 0.031709
CHAN A3 (5V): 0.008931
CHAN B0 (3.3V): 0.006106
CHAN B1 (3.15aV): 0.003439
CHAN B2 (3.15bV): 0.005978
CHAN B3 (GND): -0.004479
OPEN: 0.007077
Full Scale: +/- 1 mA
2026-03-02T22:57:58.351Z,1772492278.351 [DataOverHttps](INFO): Sending 880 bytes from file Logs/20260302T225043/Express0001.lzma
2026-03-02T22:57:59.340Z,1772492279.340 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0001.lzma.bak
2026-03-02T22:57:59.340Z,1772492279.340 [DataOverHttps](INFO): SBD MOMSN=26692945
2026-03-02T22:58:19.182Z,1772492299.182 [DataOverHttps](INFO): Sending 430 bytes from file Logs/20260302T225043/Express0005.lzma
2026-03-02T22:58:20.184Z,1772492300.184 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0005.lzma.bak
2026-03-02T22:58:20.184Z,1772492300.184 [DataOverHttps](INFO): SBD MOMSN=26692966
2026-03-02T22:58:22.304Z,1772492302.304 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadC3
2026-03-02T22:58:22.472Z,1772492302.472 [Default:CheckIn:Read_Iridium] Stopped
2026-03-02T22:58:22.472Z,1772492302.472 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-02T22:58:22.472Z,1772492302.472 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-02T22:58:25.779Z,1772492305.779 [CommandExec](IMPORTANT): got command gfscan
2026-03-02T22:58:25.926Z,1772492305.926 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-02T22:58:30.357Z,1772492310.357 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001927
CHAN A1 (24V): 0.009996
CHAN A2 (12V): -0.001259
CHAN A3 (5V): -0.006897
CHAN B0 (3.3V): -0.001013
CHAN B1 (3.15aV): -0.005687
CHAN B2 (3.15bV): -0.003739
CHAN B3 (GND): -0.008232
OPEN: 0.002893
Full Scale: +/- 1 mA
2026-03-02T22:58:37.871Z,1772492317.871 [CommandExec](IMPORTANT): got command gfscan
2026-03-02T22:58:38.127Z,1772492318.127 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-02T22:58:42.782Z,1772492322.782 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000588
CHAN A1 (24V): -0.006472
CHAN A2 (12V): -0.003001
CHAN A3 (5V): -0.005547
CHAN B0 (3.3V): -0.006019
CHAN B1 (3.15aV): -0.008280
CHAN B2 (3.15bV): -0.005334
CHAN B3 (GND): -0.007686
OPEN: 0.002602
Full Scale: +/- 1 mA
2026-03-02T23:03:10.362Z,1772492590.362 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,24052713152250, 0.0, 0.0,1475.0, 0
2026-03-02T23:03:22.990Z,1772492602.990 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-02T23:03:22.991Z,1772492602.991 [Default:CheckIn:C.Wait] Stopped
2026-03-02T23:03:22.991Z,1772492602.991 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T23:03:22.991Z,1772492602.991 [Default:CheckIn:D] Running Loop=1
2026-03-02T23:03:23.356Z,1772492603.356 [Default:CheckIn:D] Stopped
2026-03-02T23:03:23.356Z,1772492603.356 [Default:CheckIn:E] Running Loop=1
2026-03-02T23:03:23.763Z,1772492603.763 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.711590 min
2026-03-02T23:03:23.763Z,1772492603.763 [Default:CheckIn:E] Stopped
2026-03-02T23:03:23.763Z,1772492603.763 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-02T23:03:23.763Z,1772492603.763 [Default:CheckIn] Stopped
2026-03-02T23:03:23.763Z,1772492603.763 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-02T23:03:23.763Z,1772492603.763 [Default:CheckIn](INFO): Running loop #2
2026-03-02T23:03:23.763Z,1772492603.763 [Default:CheckIn] Running Loop=2
2026-03-02T23:03:23.763Z,1772492603.763 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T23:03:23.763Z,1772492603.763 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T23:03:25.725Z,1772492605.725 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230325.00,A,3648.16008,N,12147.28342,W,0.078,117.27,020326,,,A*79
2026-03-02T23:03:25.727Z,1772492605.727 [NAL9602](INFO): GPS fix at 20260302T230325: (36.802668, -121.788057)
2026-03-02T23:03:25.781Z,1772492605.781 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T23:03:25.781Z,1772492605.781 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T23:03:34.702Z,1772492614.702 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20260302T225043/Courier0007.lzma
2026-03-02T23:03:35.705Z,1772492615.705 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0007.lzma.bak
2026-03-02T23:03:35.705Z,1772492615.705 [DataOverHttps](INFO): SBD MOMSN=26692976
2026-03-02T23:03:56.148Z,1772492636.148 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-02T23:03:56.222Z,1772492636.222 [NAL9602](ERROR): received:
+CSQ:0
OK
2026-03-02T23:03:57.360Z,1772492637.360 [DataOverHttps](INFO): Sending 703 bytes from file Logs/20260302T225043/Express0008.lzma
2026-03-02T23:03:58.360Z,1772492638.360 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0008.lzma.bak
2026-03-02T23:03:58.360Z,1772492638.360 [DataOverHttps](INFO): SBD MOMSN=26692979
2026-03-02T23:04:00.255Z,1772492640.255 [Default:CheckIn:Read_Iridium] Stopped
2026-03-02T23:04:00.255Z,1772492640.255 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-02T23:04:00.255Z,1772492640.255 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-02T23:08:28.462Z,1772492908.462 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-02T23:08:59.171Z,1772492939.171 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-02T23:09:00.816Z,1772492940.816 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-02T23:09:00.816Z,1772492940.816 [Default:CheckIn:C.Wait] Stopped
2026-03-02T23:09:00.816Z,1772492940.816 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T23:09:00.816Z,1772492940.816 [Default:CheckIn:D] Running Loop=1
2026-03-02T23:09:01.298Z,1772492941.298 [Default:CheckIn:D] Stopped
2026-03-02T23:09:01.298Z,1772492941.298 [Default:CheckIn:E] Running Loop=1
2026-03-02T23:09:01.683Z,1772492941.683 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.343963 min
2026-03-02T23:09:01.683Z,1772492941.683 [Default:CheckIn:E] Stopped
2026-03-02T23:09:01.683Z,1772492941.683 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-02T23:09:01.683Z,1772492941.683 [Default:CheckIn] Stopped
2026-03-02T23:09:01.683Z,1772492941.683 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-02T23:09:01.684Z,1772492941.684 [Default:CheckIn](INFO): Running loop #3
2026-03-02T23:09:01.684Z,1772492941.684 [Default:CheckIn] Running Loop=3
2026-03-02T23:09:01.684Z,1772492941.684 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T23:09:01.684Z,1772492941.684 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T23:09:03.611Z,1772492943.611 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230902.00,A,3648.15902,N,12147.28256,W,0.117,68.88,020326,,,A*46
2026-03-02T23:09:03.613Z,1772492943.613 [NAL9602](INFO): GPS fix at 20260302T230902: (36.802650, -121.788043)
2026-03-02T23:09:03.676Z,1772492943.676 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T23:09:03.676Z,1772492943.676 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T23:09:13.255Z,1772492953.255 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0010.lzma
2026-03-02T23:09:14.252Z,1772492954.252 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0010.lzma.bak
2026-03-02T23:09:14.252Z,1772492954.252 [DataOverHttps](INFO): SBD MOMSN=26693003
2026-03-02T23:09:34.147Z,1772492974.147 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20260302T225043/Express0011.lzma
2026-03-02T23:09:34.336Z,1772492974.336 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-02T23:09:34.414Z,1772492974.414 [NAL9602](ERROR): received:
+CSQ:0
OK
2026-03-02T23:09:35.146Z,1772492975.146 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0011.lzma.bak
2026-03-02T23:09:35.147Z,1772492975.147 [DataOverHttps](INFO): SBD MOMSN=26693006
2026-03-02T23:09:37.163Z,1772492977.163 [Default:CheckIn:Read_Iridium] Stopped
2026-03-02T23:09:37.163Z,1772492977.163 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-02T23:09:37.163Z,1772492977.163 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-02T23:14:06.352Z,1772493246.352 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-02T23:14:37.031Z,1772493277.031 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-02T23:14:37.894Z,1772493277.894 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-02T23:14:37.894Z,1772493277.894 [Default:CheckIn:C.Wait] Stopped
2026-03-02T23:14:37.894Z,1772493277.894 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T23:14:37.895Z,1772493277.895 [Default:CheckIn:D] Running Loop=1
2026-03-02T23:14:38.345Z,1772493278.345 [Default:CheckIn:D] Stopped
2026-03-02T23:14:38.345Z,1772493278.345 [Default:CheckIn:E] Running Loop=1
2026-03-02T23:14:38.712Z,1772493278.712 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.961414 min
2026-03-02T23:14:38.712Z,1772493278.712 [Default:CheckIn:E] Stopped
2026-03-02T23:14:38.712Z,1772493278.712 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-02T23:14:38.712Z,1772493278.712 [Default:CheckIn] Stopped
2026-03-02T23:14:38.713Z,1772493278.713 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-02T23:14:38.713Z,1772493278.713 [Default:CheckIn](INFO): Running loop #4
2026-03-02T23:14:38.713Z,1772493278.713 [Default:CheckIn] Running Loop=4
2026-03-02T23:14:38.713Z,1772493278.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T23:14:38.714Z,1772493278.714 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T23:14:40.717Z,1772493280.717 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231439.00,A,3648.16084,N,12147.28309,W,0.233,68.88,020326,,,A*48
2026-03-02T23:14:40.720Z,1772493280.720 [NAL9602](INFO): GPS fix at 20260302T231439: (36.802681, -121.788051)
2026-03-02T23:14:40.837Z,1772493280.837 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T23:14:40.838Z,1772493280.838 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T23:14:50.798Z,1772493290.798 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260302T225043/Courier0013.lzma
2026-03-02T23:14:51.803Z,1772493291.803 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0013.lzma.bak
2026-03-02T23:14:51.803Z,1772493291.803 [DataOverHttps](INFO): SBD MOMSN=26693018
2026-03-02T23:15:11.388Z,1772493311.388 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-02T23:15:11.463Z,1772493311.463 [NAL9602](ERROR): received:
+CSQ:0
OK
2026-03-02T23:15:12.710Z,1772493312.710 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20260302T225043/Express0014.lzma
2026-03-02T23:15:13.713Z,1772493313.713 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0014.lzma.bak
2026-03-02T23:15:13.714Z,1772493313.714 [DataOverHttps](INFO): SBD MOMSN=26693021
2026-03-02T23:15:15.917Z,1772493315.917 [Default:CheckIn:Read_Iridium] Stopped
2026-03-02T23:15:15.917Z,1772493315.917 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-02T23:15:15.917Z,1772493315.917 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-02T23:16:16.437Z,1772493376.437 [NAL9602](INFO): SBD MO Status=2, MOMSN=12617, MT Status=2, MTMSN=0
2026-03-02T23:16:16.437Z,1772493376.437 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-02T23:19:43.299Z,1772493583.299 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-02T23:20:13.999Z,1772493613.999 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-02T23:20:16.481Z,1772493616.481 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-02T23:20:16.481Z,1772493616.481 [Default:CheckIn:C.Wait] Stopped
2026-03-02T23:20:16.481Z,1772493616.481 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T23:20:16.481Z,1772493616.481 [Default:CheckIn:D] Running Loop=1
2026-03-02T23:20:16.904Z,1772493616.904 [Default:CheckIn:D] Stopped
2026-03-02T23:20:16.904Z,1772493616.904 [Default:CheckIn:E] Running Loop=1
2026-03-02T23:20:17.289Z,1772493617.289 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.604067 min
2026-03-02T23:20:17.289Z,1772493617.289 [Default:CheckIn:E] Stopped
2026-03-02T23:20:17.290Z,1772493617.290 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-02T23:20:17.290Z,1772493617.290 [Default:CheckIn] Stopped
2026-03-02T23:20:17.290Z,1772493617.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-02T23:20:17.290Z,1772493617.290 [Default:CheckIn](INFO): Running loop #5
2026-03-02T23:20:17.290Z,1772493617.290 [Default:CheckIn] Running Loop=5
2026-03-02T23:20:17.290Z,1772493617.290 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T23:20:17.290Z,1772493617.290 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T23:20:19.256Z,1772493619.256 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232018.00,A,3648.16237,N,12147.28392,W,1.400,135.90,020326,,,A*73
2026-03-02T23:20:19.259Z,1772493619.259 [NAL9602](INFO): GPS fix at 20260302T232018: (36.802706, -121.788065)
2026-03-02T23:20:19.270Z,1772493619.270 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T23:20:19.270Z,1772493619.270 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T23:20:28.330Z,1772493628.330 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0016.lzma
2026-03-02T23:20:29.332Z,1772493629.332 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0016.lzma.bak
2026-03-02T23:20:29.332Z,1772493629.332 [DataOverHttps](INFO): SBD MOMSN=26693025
2026-03-02T23:20:50.028Z,1772493650.028 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-02T23:20:50.102Z,1772493650.102 [NAL9602](ERROR): received:
+CSQ:0
OK617, 2, 0, 0, 0
OK
2026-03-02T23:20:50.735Z,1772493650.735 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20260302T225043/Express0017.lzma
2026-03-02T23:20:51.732Z,1772493651.732 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0017.lzma.bak
2026-03-02T23:20:51.732Z,1772493651.732 [DataOverHttps](INFO): SBD MOMSN=26693028
2026-03-02T23:20:53.715Z,1772493653.715 [Default:CheckIn:Read_Iridium] Stopped
2026-03-02T23:20:53.715Z,1772493653.715 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-02T23:20:53.715Z,1772493653.715 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-02T23:24:59.319Z,1772493899.319 [NAL9602](INFO): SBD MO Status=2, MOMSN=12617, MT Status=2, MTMSN=0
2026-03-02T23:24:59.319Z,1772493899.319 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-02T23:25:16.284Z,1772493916.284 [NAL9602](INFO): SBD MO Status=2, MOMSN=12617, MT Status=2, MTMSN=0
2026-03-02T23:25:16.284Z,1772493916.284 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-02T23:25:21.548Z,1772493921.548 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-02T23:25:52.234Z,1772493952.234 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-02T23:25:54.306Z,1772493954.306 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-02T23:25:54.306Z,1772493954.306 [Default:CheckIn:C.Wait] Stopped
2026-03-02T23:25:54.307Z,1772493954.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T23:25:54.307Z,1772493954.307 [Default:CheckIn:D] Running Loop=1
2026-03-02T23:25:54.737Z,1772493954.737 [Default:CheckIn:D] Stopped
2026-03-02T23:25:54.738Z,1772493954.738 [Default:CheckIn:E] Running Loop=1
2026-03-02T23:25:55.095Z,1772493955.095 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.234615 min
2026-03-02T23:25:55.095Z,1772493955.095 [Default:CheckIn:E] Stopped
2026-03-02T23:25:55.096Z,1772493955.096 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-02T23:25:55.096Z,1772493955.096 [Default:CheckIn] Stopped
2026-03-02T23:25:55.096Z,1772493955.096 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-02T23:25:55.096Z,1772493955.096 [Default:CheckIn](INFO): Running loop #6
2026-03-02T23:25:55.097Z,1772493955.097 [Default:CheckIn] Running Loop=6
2026-03-02T23:25:55.097Z,1772493955.097 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T23:25:55.097Z,1772493955.097 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T23:25:57.101Z,1772493957.101 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232556.00,A,3648.16148,N,12147.28195,W,0.408,161.28,020326,,,A*79
2026-03-02T23:25:57.104Z,1772493957.104 [NAL9602](INFO): GPS fix at 20260302T232556: (36.802691, -121.788032)
2026-03-02T23:25:57.141Z,1772493957.141 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T23:25:57.141Z,1772493957.141 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T23:26:06.494Z,1772493966.494 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260302T225043/Courier0019.lzma
2026-03-02T23:26:07.496Z,1772493967.496 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0019.lzma.bak
2026-03-02T23:26:07.496Z,1772493967.496 [DataOverHttps](INFO): SBD MOMSN=26693038
2026-03-02T23:26:27.279Z,1772493987.279 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20260302T225043/Express0020.lzma
2026-03-02T23:26:27.788Z,1772493987.788 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-02T23:26:27.870Z,1772493987.870 [NAL9602](ERROR): received:
+CSQ:1
OK617, 2, 0, 0, 0
OK
2026-03-02T23:26:28.283Z,1772493988.283 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0020.lzma.bak
2026-03-02T23:26:28.284Z,1772493988.284 [DataOverHttps](INFO): SBD MOMSN=26693041
2026-03-02T23:26:30.283Z,1772493990.283 [Default:CheckIn:Read_Iridium] Stopped
2026-03-02T23:26:30.283Z,1772493990.283 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-02T23:26:30.283Z,1772493990.283 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-02T23:30:59.301Z,1772494259.301 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-02T23:31:29.991Z,1772494289.991 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-02T23:31:30.912Z,1772494290.912 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-02T23:31:30.912Z,1772494290.912 [Default:CheckIn:C.Wait] Stopped
2026-03-02T23:31:30.912Z,1772494290.912 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T23:31:30.912Z,1772494290.912 [Default:CheckIn:D] Running Loop=1
2026-03-02T23:31:31.256Z,1772494291.256 [Default:CheckIn:D] Stopped
2026-03-02T23:31:31.257Z,1772494291.257 [Default:CheckIn:E] Running Loop=1
2026-03-02T23:31:31.669Z,1772494291.669 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.843266 min
2026-03-02T23:31:31.669Z,1772494291.669 [Default:CheckIn:E] Stopped
2026-03-02T23:31:31.670Z,1772494291.670 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-02T23:31:31.670Z,1772494291.670 [Default:CheckIn] Stopped
2026-03-02T23:31:31.675Z,1772494291.675 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-02T23:31:31.675Z,1772494291.675 [Default:CheckIn](INFO): Running loop #7
2026-03-02T23:31:31.675Z,1772494291.675 [Default:CheckIn] Running Loop=7
2026-03-02T23:31:31.675Z,1772494291.675 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T23:31:31.676Z,1772494291.676 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T23:31:33.633Z,1772494293.633 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233132.00,A,3648.15633,N,12147.28317,W,0.292,161.28,020326,,,A*7B
2026-03-02T23:31:33.635Z,1772494293.635 [NAL9602](INFO): GPS fix at 20260302T233132: (36.802605, -121.788053)
2026-03-02T23:31:33.693Z,1772494293.693 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T23:31:33.693Z,1772494293.693 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T23:31:43.342Z,1772494303.342 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260302T225043/Courier0022.lzma
2026-03-02T23:31:44.344Z,1772494304.344 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0022.lzma.bak
2026-03-02T23:31:44.344Z,1772494304.344 [DataOverHttps](INFO): SBD MOMSN=26693045
2026-03-02T23:32:04.331Z,1772494324.331 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-02T23:32:04.406Z,1772494324.406 [NAL9602](ERROR): received:
+CSQ:0
OK617, 2, 0, 0, 0
OK
2026-03-02T23:32:04.587Z,1772494324.587 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20260302T225043/Express0023.lzma
2026-03-02T23:32:05.587Z,1772494325.587 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0023.lzma.bak
2026-03-02T23:32:05.587Z,1772494325.587 [DataOverHttps](INFO): SBD MOMSN=26693048
2026-03-02T23:32:07.701Z,1772494327.701 [Default:CheckIn:Read_Iridium] Stopped
2026-03-02T23:32:07.701Z,1772494327.701 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-02T23:32:07.701Z,1772494327.701 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-02T23:33:23.529Z,1772494403.529 [NAL9602](INFO): SBD MO Status=2, MOMSN=12617, MT Status=2, MTMSN=0
2026-03-02T23:33:23.529Z,1772494403.529 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-02T23:33:58.277Z,1772494438.277 [NAL9602](INFO): SBD MO Status=2, MOMSN=12617, MT Status=2, MTMSN=0
2026-03-02T23:33:58.277Z,1772494438.277 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-02T23:34:37.881Z,1772494477.881 [NAL9602](INFO): SBD MO Status=2, MOMSN=12617, MT Status=2, MTMSN=0
2026-03-02T23:34:37.881Z,1772494477.881 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-02T23:36:36.284Z,1772494596.284 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-02T23:37:06.971Z,1772494626.971 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-02T23:37:08.288Z,1772494628.288 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-02T23:37:08.288Z,1772494628.288 [Default:CheckIn:C.Wait] Stopped
2026-03-02T23:37:08.288Z,1772494628.288 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T23:37:08.288Z,1772494628.288 [Default:CheckIn:D] Running Loop=1
2026-03-02T23:37:08.709Z,1772494628.709 [Default:CheckIn:D] Stopped
2026-03-02T23:37:08.709Z,1772494628.709 [Default:CheckIn:E] Running Loop=1
2026-03-02T23:37:09.014Z,1772494629.014 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.467485 min
2026-03-02T23:37:09.014Z,1772494629.014 [Default:CheckIn:E] Stopped
2026-03-02T23:37:09.014Z,1772494629.014 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-02T23:37:09.018Z,1772494629.018 [Default:CheckIn] Stopped
2026-03-02T23:37:09.018Z,1772494629.018 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-02T23:37:09.018Z,1772494629.018 [Default:CheckIn](INFO): Running loop #8
2026-03-02T23:37:09.019Z,1772494629.019 [Default:CheckIn] Running Loop=8
2026-03-02T23:37:09.019Z,1772494629.019 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T23:37:09.019Z,1772494629.019 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T23:37:11.021Z,1772494631.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233710.00,A,3648.16261,N,12147.28461,W,0.058,208.38,020326,,,A*72
2026-03-02T23:37:11.023Z,1772494631.023 [NAL9602](INFO): GPS fix at 20260302T233710: (36.802710, -121.788077)
2026-03-02T23:37:11.108Z,1772494631.108 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T23:37:11.108Z,1772494631.108 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T23:37:20.215Z,1772494640.215 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0025.lzma
2026-03-02T23:37:21.214Z,1772494641.214 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0025.lzma.bak
2026-03-02T23:37:21.215Z,1772494641.215 [DataOverHttps](INFO): SBD MOMSN=26693059
2026-03-02T23:37:40.458Z,1772494660.458 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20260302T225043/Express0026.lzma
2026-03-02T23:37:41.463Z,1772494661.463 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0026.lzma.bak
2026-03-02T23:37:41.463Z,1772494661.463 [DataOverHttps](INFO): SBD MOMSN=26693062
2026-03-02T23:37:41.881Z,1772494661.881 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-02T23:37:41.958Z,1772494661.958 [NAL9602](ERROR): received:
+CSQ:1
OK617, 2, 0, 0, 0
OK
2026-03-02T23:37:43.587Z,1772494663.587 [Default:CheckIn:Read_Iridium] Stopped
2026-03-02T23:37:43.587Z,1772494663.587 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-02T23:37:43.587Z,1772494663.587 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-02T23:39:54.777Z,1772494794.777 [NAL9602](INFO): SBD MO Status=0, MOMSN=12617, MT Status=0, MTMSN=0
2026-03-02T23:39:54.777Z,1772494794.777 [NAL9602](INFO): No messages in MT queue
2026-03-02T23:40:25.497Z,1772494825.497 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-02T23:42:44.109Z,1772494964.109 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-02T23:42:44.109Z,1772494964.109 [Default:CheckIn:C.Wait] Stopped
2026-03-02T23:42:44.110Z,1772494964.110 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T23:42:44.110Z,1772494964.110 [Default:CheckIn:D] Running Loop=1
2026-03-02T23:42:44.635Z,1772494964.635 [Default:CheckIn:D] Stopped
2026-03-02T23:42:44.635Z,1772494964.635 [Default:CheckIn:E] Running Loop=1
2026-03-02T23:42:45.071Z,1772494965.071 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.066252 min
2026-03-02T23:42:45.071Z,1772494965.071 [Default:CheckIn:E] Stopped
2026-03-02T23:42:45.071Z,1772494965.071 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-02T23:42:45.071Z,1772494965.071 [Default:CheckIn] Stopped
2026-03-02T23:42:45.071Z,1772494965.071 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-02T23:42:45.071Z,1772494965.071 [Default:CheckIn](INFO): Running loop #9
2026-03-02T23:42:45.071Z,1772494965.071 [Default:CheckIn] Running Loop=9
2026-03-02T23:42:45.072Z,1772494965.072 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T23:42:45.072Z,1772494965.072 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T23:42:46.948Z,1772494966.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234246.00,A,3648.15893,N,12147.28200,W,0.408,143.14,020326,,,A*75
2026-03-02T23:42:46.968Z,1772494966.968 [NAL9602](INFO): GPS fix at 20260302T234246: (36.802649, -121.788033)
2026-03-02T23:42:47.185Z,1772494967.185 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T23:42:47.185Z,1772494967.185 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T23:42:49.370Z,1772494969.370 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0028.lzma
2026-03-02T23:42:49.836Z,1772494969.836 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0028.lzma.bak
2026-03-02T23:42:49.836Z,1772494969.836 [DataOverHttps](INFO): SBD MOMSN=26693067
2026-03-02T23:43:10.058Z,1772494990.058 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20260302T225043/Express0029.lzma
2026-03-02T23:43:11.060Z,1772494991.060 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0029.lzma.bak
2026-03-02T23:43:11.060Z,1772494991.060 [DataOverHttps](INFO): SBD MOMSN=26693072
2026-03-02T23:43:12.785Z,1772494992.785 [Default:CheckIn:Read_Iridium] Stopped
2026-03-02T23:43:12.785Z,1772494992.785 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-02T23:43:12.785Z,1772494992.785 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-02T23:44:21.833Z,1772495061.833 [NAL9602](INFO): SBD MO Status=2, MOMSN=12618, MT Status=2, MTMSN=0
2026-03-02T23:44:21.833Z,1772495061.833 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-02T23:44:53.338Z,1772495093.338 [NAL9602](INFO): SBD MO Status=2, MOMSN=12618, MT Status=2, MTMSN=0
2026-03-02T23:44:53.339Z,1772495093.339 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-02T23:47:49.474Z,1772495269.474 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-02T23:48:13.383Z,1772495293.383 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-02T23:48:13.383Z,1772495293.383 [Default:CheckIn:C.Wait] Stopped
2026-03-02T23:48:13.383Z,1772495293.383 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T23:48:13.383Z,1772495293.383 [Default:CheckIn:D] Running Loop=1
2026-03-02T23:48:13.777Z,1772495293.777 [Default:CheckIn:D] Stopped
2026-03-02T23:48:13.777Z,1772495293.777 [Default:CheckIn:E] Running Loop=1
2026-03-02T23:48:14.174Z,1772495294.174 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.551949 min
2026-03-02T23:48:14.174Z,1772495294.174 [Default:CheckIn:E] Stopped
2026-03-02T23:48:14.174Z,1772495294.174 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-02T23:48:14.175Z,1772495294.175 [Default:CheckIn] Stopped
2026-03-02T23:48:14.175Z,1772495294.175 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-02T23:48:14.175Z,1772495294.175 [Default:CheckIn](INFO): Running loop #10
2026-03-02T23:48:14.175Z,1772495294.175 [Default:CheckIn] Running Loop=10
2026-03-02T23:48:14.175Z,1772495294.175 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T23:48:14.175Z,1772495294.175 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T23:48:16.145Z,1772495296.145 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234815.00,A,3648.16313,N,12147.28346,W,0.117,147.87,020326,,,A*7F
2026-03-02T23:48:16.147Z,1772495296.147 [NAL9602](INFO): GPS fix at 20260302T234815: (36.802719, -121.788058)
2026-03-02T23:48:16.218Z,1772495296.218 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T23:48:16.222Z,1772495296.222 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T23:48:25.426Z,1772495305.426 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0031.lzma
2026-03-02T23:48:26.428Z,1772495306.428 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0031.lzma.bak
2026-03-02T23:48:26.428Z,1772495306.428 [DataOverHttps](INFO): SBD MOMSN=26693079
2026-03-02T23:48:48.583Z,1772495328.583 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-02T23:48:50.330Z,1772495330.330 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20260302T225043/Express0032.lzma
2026-03-02T23:48:51.332Z,1772495331.332 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0032.lzma.bak
2026-03-02T23:48:51.332Z,1772495331.332 [DataOverHttps](INFO): SBD MOMSN=26693082
2026-03-02T23:48:53.515Z,1772495333.515 [Default:CheckIn:Read_Iridium] Stopped
2026-03-02T23:48:53.515Z,1772495333.515 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-02T23:48:53.515Z,1772495333.515 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-02T23:53:54.205Z,1772495634.205 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-02T23:53:54.205Z,1772495634.205 [Default:CheckIn:C.Wait] Stopped
2026-03-02T23:53:54.205Z,1772495634.205 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T23:53:54.205Z,1772495634.205 [Default:CheckIn:D] Running Loop=1
2026-03-02T23:53:54.590Z,1772495634.590 [Default:CheckIn:D] Stopped
2026-03-02T23:53:54.590Z,1772495634.590 [Default:CheckIn:E] Running Loop=1
2026-03-02T23:53:54.982Z,1772495634.981 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.232161 min
2026-03-02T23:53:54.982Z,1772495634.982 [Default:CheckIn:E] Stopped
2026-03-02T23:53:54.982Z,1772495634.982 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-02T23:53:54.982Z,1772495634.982 [Default:CheckIn] Stopped
2026-03-02T23:53:54.982Z,1772495634.982 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-02T23:53:54.982Z,1772495634.982 [Default:CheckIn](INFO): Running loop #11
2026-03-02T23:53:54.982Z,1772495634.982 [Default:CheckIn] Running Loop=11
2026-03-02T23:53:54.983Z,1772495634.983 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T23:53:54.983Z,1772495634.983 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T23:53:56.952Z,1772495636.952 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235356.00,A,3648.16843,N,12147.28537,W,0.447,181.49,020326,,,A*74
2026-03-02T23:53:56.955Z,1772495636.955 [NAL9602](INFO): GPS fix at 20260302T235356: (36.802807, -121.788089)
2026-03-02T23:53:56.969Z,1772495636.969 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T23:53:56.969Z,1772495636.969 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T23:54:06.943Z,1772495646.943 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260302T225043/Courier0034.lzma
2026-03-02T23:54:07.348Z,1772495647.348 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0034.lzma.bak
2026-03-02T23:54:07.349Z,1772495647.349 [DataOverHttps](INFO): SBD MOMSN=26693092
2026-03-02T23:54:27.438Z,1772495667.438 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20260302T225043/Express0035.lzma
2026-03-02T23:54:28.440Z,1772495668.440 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0035.lzma.bak
2026-03-02T23:54:28.440Z,1772495668.440 [DataOverHttps](INFO): SBD MOMSN=26693095
2026-03-02T23:54:30.700Z,1772495670.700 [Default:CheckIn:Read_Iridium] Stopped
2026-03-02T23:54:30.700Z,1772495670.700 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-02T23:54:30.700Z,1772495670.700 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-02T23:59:06.715Z,1772495946.715 [NAL9602](INFO): SBD MO Status=0, MOMSN=12618, MT Status=0, MTMSN=0
2026-03-02T23:59:06.715Z,1772495946.715 [NAL9602](INFO): No messages in MT queue
2026-03-02T23:59:31.252Z,1772495971.252 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-02T23:59:31.253Z,1772495971.253 [Default:CheckIn:C.Wait] Stopped
2026-03-02T23:59:31.253Z,1772495971.253 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-02T23:59:31.253Z,1772495971.253 [Default:CheckIn:D] Running Loop=1
2026-03-02T23:59:31.517Z,1772495971.517 [Default:CheckIn:D] Stopped
2026-03-02T23:59:31.517Z,1772495971.517 [Default:CheckIn:E] Running Loop=1
2026-03-02T23:59:31.762Z,1772495971.762 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.847616 min
2026-03-02T23:59:31.762Z,1772495971.762 [Default:CheckIn:E] Stopped
2026-03-02T23:59:31.762Z,1772495971.762 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-02T23:59:31.762Z,1772495971.762 [Default:CheckIn] Stopped
2026-03-02T23:59:31.784Z,1772495971.784 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-02T23:59:31.785Z,1772495971.785 [Default:CheckIn](INFO): Running loop #12
2026-03-02T23:59:31.785Z,1772495971.785 [Default:CheckIn] Running Loop=12
2026-03-02T23:59:31.785Z,1772495971.785 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-02T23:59:31.785Z,1772495971.785 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-02T23:59:33.773Z,1772495973.773 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235933.00,A,3648.16272,N,12147.28109,W,0.253,297.27,020326,,,A*73
2026-03-02T23:59:33.778Z,1772495973.778 [NAL9602](INFO): GPS fix at 20260302T235933: (36.802712, -121.788018)
2026-03-02T23:59:33.858Z,1772495973.858 [Default:CheckIn:Read_GPS] Stopped
2026-03-02T23:59:33.858Z,1772495973.858 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-02T23:59:42.982Z,1772495982.982 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0037.lzma
2026-03-02T23:59:43.984Z,1772495983.984 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0037.lzma.bak
2026-03-02T23:59:43.984Z,1772495983.984 [DataOverHttps](INFO): SBD MOMSN=26693099
2026-03-03T00:00:04.079Z,1772496004.079 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20260302T225043/Express0038.lzma
2026-03-03T00:00:05.081Z,1772496005.081 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0038.lzma.bak
2026-03-03T00:00:05.082Z,1772496005.082 [DataOverHttps](INFO): SBD MOMSN=26693102
2026-03-03T00:00:06.464Z,1772496006.464 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-03T00:00:07.374Z,1772496007.374 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T00:00:07.382Z,1772496007.382 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T00:00:07.383Z,1772496007.383 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T00:02:27.131Z,1772496147.131 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-03T00:02:28.333Z,1772496148.333 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-03T00:02:42.228Z,1772496162.228 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-03T00:02:43.516Z,1772496163.516 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-03T00:02:57.629Z,1772496177.629 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-03T00:02:58.841Z,1772496178.841 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-03T00:03:13.396Z,1772496193.396 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-03T00:03:14.201Z,1772496194.201 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-03T00:03:29.600Z,1772496209.600 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-03T00:03:29.957Z,1772496209.957 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-03T00:03:44.548Z,1772496224.548 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-03T00:03:44.908Z,1772496224.908 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-03T00:03:59.905Z,1772496239.905 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-03T00:04:00.262Z,1772496240.262 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-03T00:04:15.217Z,1772496255.217 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-03T00:04:15.223Z,1772496255.223 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-03T00:04:30.232Z,1772496270.232 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-03T00:04:30.261Z,1772496270.261 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-03T00:04:45.567Z,1772496285.567 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-03T00:04:45.573Z,1772496285.573 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-03T00:04:45.580Z,1772496285.580 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5.
2026-03-03T00:04:45.583Z,1772496285.583 [BPC1](INFO): Received data from all battery sticks.
2026-03-03T00:05:07.937Z,1772496307.937 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T00:05:07.937Z,1772496307.937 [Default:CheckIn:C.Wait] Stopped
2026-03-03T00:05:07.937Z,1772496307.937 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T00:05:07.937Z,1772496307.937 [Default:CheckIn:D] Running Loop=1
2026-03-03T00:05:08.271Z,1772496308.271 [Default:CheckIn:D] Stopped
2026-03-03T00:05:08.271Z,1772496308.271 [Default:CheckIn:E] Running Loop=1
2026-03-03T00:05:08.653Z,1772496308.653 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.460181 min
2026-03-03T00:05:08.653Z,1772496308.653 [Default:CheckIn:E] Stopped
2026-03-03T00:05:08.653Z,1772496308.653 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T00:05:08.653Z,1772496308.653 [Default:CheckIn] Stopped
2026-03-03T00:05:08.653Z,1772496308.653 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T00:05:08.654Z,1772496308.654 [Default:CheckIn](INFO): Running loop #13
2026-03-03T00:05:08.654Z,1772496308.654 [Default:CheckIn] Running Loop=13
2026-03-03T00:05:08.654Z,1772496308.654 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T00:05:08.654Z,1772496308.654 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T00:05:10.621Z,1772496310.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000509.00,A,3648.16216,N,12147.28076,W,0.233,252.63,030326,,,A*77
2026-03-03T00:05:10.623Z,1772496310.623 [NAL9602](INFO): GPS fix at 20260303T000509: (36.802703, -121.788013)
2026-03-03T00:05:10.697Z,1772496310.697 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T00:05:10.697Z,1772496310.697 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T00:05:19.554Z,1772496319.554 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20260302T225043/Courier0040.lzma
2026-03-03T00:05:20.559Z,1772496320.559 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0040.lzma.bak
2026-03-03T00:05:20.560Z,1772496320.560 [DataOverHttps](INFO): SBD MOMSN=26693113
2026-03-03T00:05:40.113Z,1772496340.113 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20260302T225043/Express0041.lzma
2026-03-03T00:05:41.108Z,1772496341.108 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0041.lzma.bak
2026-03-03T00:05:41.108Z,1772496341.108 [DataOverHttps](INFO): SBD MOMSN=26693116
2026-03-03T00:05:42.888Z,1772496342.888 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T00:05:42.888Z,1772496342.888 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T00:05:42.888Z,1772496342.888 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T00:06:23.251Z,1772496383.251 [NAL9602](INFO): SBD MO Status=2, MOMSN=12619, MT Status=2, MTMSN=0
2026-03-03T00:06:23.251Z,1772496383.251 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:07:22.626Z,1772496442.626 [NAL9602](INFO): SBD MO Status=2, MOMSN=12619, MT Status=2, MTMSN=0
2026-03-03T00:07:22.627Z,1772496442.627 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:07:35.537Z,1772496455.537 [NAL9602](INFO): SBD MO Status=2, MOMSN=12619, MT Status=2, MTMSN=0
2026-03-03T00:07:35.537Z,1772496455.537 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:07:58.179Z,1772496478.179 [NAL9602](INFO): SBD MO Status=2, MOMSN=12619, MT Status=2, MTMSN=0
2026-03-03T00:07:58.179Z,1772496478.179 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:09:19.796Z,1772496559.796 [NAL9602](INFO): SBD MO Status=2, MOMSN=12619, MT Status=2, MTMSN=0
2026-03-03T00:09:19.797Z,1772496559.797 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:09:36.737Z,1772496576.737 [NAL9602](INFO): SBD MO Status=2, MOMSN=12619, MT Status=2, MTMSN=0
2026-03-03T00:09:36.737Z,1772496576.737 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:10:11.885Z,1772496611.885 [NAL9602](INFO): SBD MO Status=0, MOMSN=12619, MT Status=0, MTMSN=0
2026-03-03T00:10:11.885Z,1772496611.885 [NAL9602](INFO): No messages in MT queue
2026-03-03T00:10:42.591Z,1772496642.591 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-03T00:10:43.432Z,1772496643.432 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T00:10:43.433Z,1772496643.433 [Default:CheckIn:C.Wait] Stopped
2026-03-03T00:10:43.433Z,1772496643.433 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T00:10:43.433Z,1772496643.433 [Default:CheckIn:D] Running Loop=1
2026-03-03T00:10:43.971Z,1772496643.971 [Default:CheckIn:D] Stopped
2026-03-03T00:10:43.971Z,1772496643.971 [Default:CheckIn:E] Running Loop=1
2026-03-03T00:10:44.318Z,1772496644.318 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.055176 min
2026-03-03T00:10:44.319Z,1772496644.319 [Default:CheckIn:E] Stopped
2026-03-03T00:10:44.319Z,1772496644.319 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T00:10:44.319Z,1772496644.319 [Default:CheckIn] Stopped
2026-03-03T00:10:44.319Z,1772496644.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T00:10:44.319Z,1772496644.319 [Default:CheckIn](INFO): Running loop #14
2026-03-03T00:10:44.319Z,1772496644.319 [Default:CheckIn] Running Loop=14
2026-03-03T00:10:44.319Z,1772496644.319 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T00:10:44.319Z,1772496644.319 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T00:10:46.229Z,1772496646.229 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001045.00,A,3648.16474,N,12147.28145,W,0.330,143.90,030326,,,A*75
2026-03-03T00:10:46.239Z,1772496646.239 [NAL9602](INFO): GPS fix at 20260303T001045: (36.802746, -121.788024)
2026-03-03T00:10:46.296Z,1772496646.296 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T00:10:46.296Z,1772496646.296 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T00:10:55.582Z,1772496655.582 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0043.lzma
2026-03-03T00:10:56.584Z,1772496656.584 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0043.lzma.bak
2026-03-03T00:10:56.584Z,1772496656.584 [DataOverHttps](INFO): SBD MOMSN=26693120
2026-03-03T00:11:17.879Z,1772496677.879 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20260302T225043/Express0044.lzma
2026-03-03T00:11:18.876Z,1772496678.876 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0044.lzma.bak
2026-03-03T00:11:18.876Z,1772496678.876 [DataOverHttps](INFO): SBD MOMSN=26693123
2026-03-03T00:11:20.658Z,1772496680.658 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T00:11:20.658Z,1772496680.658 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T00:11:20.658Z,1772496680.658 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T00:15:48.445Z,1772496948.445 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-03T00:16:19.127Z,1772496979.127 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-03T00:16:21.255Z,1772496981.255 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T00:16:21.255Z,1772496981.255 [Default:CheckIn:C.Wait] Stopped
2026-03-03T00:16:21.255Z,1772496981.255 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T00:16:21.255Z,1772496981.255 [Default:CheckIn:D] Running Loop=1
2026-03-03T00:16:21.611Z,1772496981.611 [Default:CheckIn:D] Stopped
2026-03-03T00:16:21.611Z,1772496981.611 [Default:CheckIn:E] Running Loop=1
2026-03-03T00:16:21.965Z,1772496981.965 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.682520 min
2026-03-03T00:16:21.965Z,1772496981.965 [Default:CheckIn:E] Stopped
2026-03-03T00:16:21.965Z,1772496981.965 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T00:16:21.965Z,1772496981.965 [Default:CheckIn] Stopped
2026-03-03T00:16:21.965Z,1772496981.965 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T00:16:21.965Z,1772496981.965 [Default:CheckIn](INFO): Running loop #15
2026-03-03T00:16:21.965Z,1772496981.965 [Default:CheckIn] Running Loop=15
2026-03-03T00:16:21.965Z,1772496981.965 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T00:16:21.965Z,1772496981.965 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T00:16:23.981Z,1772496983.981 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001623.00,A,3648.16393,N,12147.27966,W,0.253,120.61,030326,,,A*74
2026-03-03T00:16:23.992Z,1772496983.992 [NAL9602](INFO): GPS fix at 20260303T001623: (36.802732, -121.787994)
2026-03-03T00:16:24.028Z,1772496984.028 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T00:16:24.029Z,1772496984.029 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T00:16:33.630Z,1772496993.630 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260302T225043/Courier0046.lzma
2026-03-03T00:16:34.628Z,1772496994.628 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0046.lzma.bak
2026-03-03T00:16:34.628Z,1772496994.628 [DataOverHttps](INFO): SBD MOMSN=26693134
2026-03-03T00:16:54.691Z,1772497014.691 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-03T00:16:54.779Z,1772497014.779 [NAL9602](ERROR): received:
+CSQ:0
OK619, 0, 0, 0, 0
OK
2026-03-03T00:16:57.226Z,1772497017.226 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20260302T225043/Express0047.lzma
2026-03-03T00:16:58.232Z,1772497018.232 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0047.lzma.bak
2026-03-03T00:16:58.232Z,1772497018.232 [DataOverHttps](INFO): SBD MOMSN=26693137
2026-03-03T00:17:00.485Z,1772497020.485 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T00:17:00.486Z,1772497020.486 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T00:17:00.486Z,1772497020.486 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T00:17:14.493Z,1772497034.493 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:17:14.493Z,1772497034.493 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:18:22.349Z,1772497102.349 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:18:22.349Z,1772497102.349 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:18:41.341Z,1772497121.341 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:18:41.341Z,1772497121.341 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:18:54.286Z,1772497134.286 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:18:54.286Z,1772497134.286 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:19:14.123Z,1772497154.123 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0.00, +0.00, 0.00
2026-03-03T00:19:40.729Z,1772497180.729 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:19:40.729Z,1772497180.729 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:20:06.592Z,1772497206.592 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:20:06.592Z,1772497206.592 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:20:33.289Z,1772497233.289 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:20:33.289Z,1772497233.289 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:21:24.827Z,1772497284.827 [Waterlinked](INFO): Powering down
2026-03-03T00:21:26.319Z,1772497286.319 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-03T00:21:57.027Z,1772497317.027 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-03T00:22:01.081Z,1772497321.081 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T00:22:01.081Z,1772497321.081 [Default:CheckIn:C.Wait] Stopped
2026-03-03T00:22:01.081Z,1772497321.081 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T00:22:01.081Z,1772497321.081 [Default:CheckIn:D] Running Loop=1
2026-03-03T00:22:01.488Z,1772497321.488 [Default:CheckIn:D] Stopped
2026-03-03T00:22:01.488Z,1772497321.488 [Default:CheckIn:E] Running Loop=1
2026-03-03T00:22:01.885Z,1772497321.885 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.347135 min
2026-03-03T00:22:01.885Z,1772497321.885 [Default:CheckIn:E] Stopped
2026-03-03T00:22:01.885Z,1772497321.885 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T00:22:01.885Z,1772497321.885 [Default:CheckIn] Stopped
2026-03-03T00:22:01.885Z,1772497321.885 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T00:22:01.886Z,1772497321.886 [Default:CheckIn](INFO): Running loop #16
2026-03-03T00:22:01.886Z,1772497321.886 [Default:CheckIn] Running Loop=16
2026-03-03T00:22:01.886Z,1772497321.886 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T00:22:01.886Z,1772497321.886 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T00:22:03.894Z,1772497323.894 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002203.00,A,3648.16423,N,12147.27846,W,0.175,265.08,030326,,,A*74
2026-03-03T00:22:03.896Z,1772497323.896 [NAL9602](INFO): GPS fix at 20260303T002203: (36.802737, -121.787974)
2026-03-03T00:22:03.907Z,1772497323.907 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T00:22:03.907Z,1772497323.907 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T00:22:11.534Z,1772497331.534 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0049.lzma
2026-03-03T00:22:12.538Z,1772497332.538 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0049.lzma.bak
2026-03-03T00:22:12.538Z,1772497332.538 [DataOverHttps](INFO): SBD MOMSN=26693141
2026-03-03T00:22:28.350Z,1772497348.350 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20260302T225043/Express0050.lzma
2026-03-03T00:22:29.352Z,1772497349.352 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0050.lzma.bak
2026-03-03T00:22:29.352Z,1772497349.352 [DataOverHttps](INFO): SBD MOMSN=26693144
2026-03-03T00:22:30.986Z,1772497350.986 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T00:22:30.986Z,1772497350.986 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T00:22:30.986Z,1772497350.986 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T00:24:58.824Z,1772497498.824 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:24:58.824Z,1772497498.824 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:25:35.593Z,1772497535.593 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:25:35.593Z,1772497535.593 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:26:10.736Z,1772497570.736 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:26:10.736Z,1772497570.736 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:27:00.857Z,1772497620.857 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:27:00.857Z,1772497620.857 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:27:06.503Z,1772497626.503 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-03T00:27:31.636Z,1772497651.636 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T00:27:31.637Z,1772497651.637 [Default:CheckIn:C.Wait] Stopped
2026-03-03T00:27:31.637Z,1772497651.637 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T00:27:31.637Z,1772497651.637 [Default:CheckIn:D] Running Loop=1
2026-03-03T00:27:31.982Z,1772497651.982 [Default:CheckIn:D] Stopped
2026-03-03T00:27:31.982Z,1772497651.982 [Default:CheckIn:E] Running Loop=1
2026-03-03T00:27:32.388Z,1772497652.388 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.855363 min
2026-03-03T00:27:32.388Z,1772497652.388 [Default:CheckIn:E] Stopped
2026-03-03T00:27:32.388Z,1772497652.388 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T00:27:32.388Z,1772497652.388 [Default:CheckIn] Stopped
2026-03-03T00:27:32.388Z,1772497652.388 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T00:27:32.389Z,1772497652.389 [Default:CheckIn](INFO): Running loop #17
2026-03-03T00:27:32.389Z,1772497652.389 [Default:CheckIn] Running Loop=17
2026-03-03T00:27:32.389Z,1772497652.389 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T00:27:32.389Z,1772497652.389 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T00:27:34.389Z,1772497654.389 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002733.00,A,3648.16501,N,12147.28280,W,0.175,265.08,030326,,,A*7C
2026-03-03T00:27:34.391Z,1772497654.391 [NAL9602](INFO): GPS fix at 20260303T002733: (36.802750, -121.788047)
2026-03-03T00:27:34.403Z,1772497654.403 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T00:27:34.403Z,1772497654.403 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T00:27:41.362Z,1772497661.362 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260302T225043/Courier0052.lzma
2026-03-03T00:27:42.364Z,1772497662.364 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0052.lzma.bak
2026-03-03T00:27:42.364Z,1772497662.364 [DataOverHttps](INFO): SBD MOMSN=26693155
2026-03-03T00:27:58.054Z,1772497678.054 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20260302T225043/Express0053.lzma
2026-03-03T00:27:59.072Z,1772497679.072 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0053.lzma.bak
2026-03-03T00:27:59.072Z,1772497679.072 [DataOverHttps](INFO): SBD MOMSN=26693158
2026-03-03T00:28:00.658Z,1772497680.658 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T00:28:00.658Z,1772497680.658 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T00:28:00.658Z,1772497680.658 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T00:28:06.299Z,1772497686.299 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-03T00:33:01.244Z,1772497981.244 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T00:33:01.244Z,1772497981.244 [Default:CheckIn:C.Wait] Stopped
2026-03-03T00:33:01.244Z,1772497981.244 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T00:33:01.244Z,1772497981.244 [Default:CheckIn:D] Running Loop=1
2026-03-03T00:33:01.656Z,1772497981.656 [Default:CheckIn:D] Stopped
2026-03-03T00:33:01.656Z,1772497981.656 [Default:CheckIn:E] Running Loop=1
2026-03-03T00:33:02.045Z,1772497982.045 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.349927 min
2026-03-03T00:33:02.045Z,1772497982.045 [Default:CheckIn:E] Stopped
2026-03-03T00:33:02.045Z,1772497982.045 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T00:33:02.045Z,1772497982.045 [Default:CheckIn] Stopped
2026-03-03T00:33:02.045Z,1772497982.045 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T00:33:02.046Z,1772497982.046 [Default:CheckIn](INFO): Running loop #18
2026-03-03T00:33:02.046Z,1772497982.046 [Default:CheckIn] Running Loop=18
2026-03-03T00:33:02.046Z,1772497982.046 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T00:33:02.046Z,1772497982.046 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T00:33:04.056Z,1772497984.056 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003303.00,A,3648.16459,N,12147.28327,W,0.156,144.64,030326,,,A*71
2026-03-03T00:33:04.058Z,1772497984.058 [NAL9602](INFO): GPS fix at 20260303T003303: (36.802743, -121.788055)
2026-03-03T00:33:04.069Z,1772497984.069 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T00:33:04.069Z,1772497984.069 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T00:33:11.690Z,1772497991.690 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0055.lzma
2026-03-03T00:33:12.693Z,1772497992.693 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0055.lzma.bak
2026-03-03T00:33:12.693Z,1772497992.693 [DataOverHttps](INFO): SBD MOMSN=26693234
2026-03-03T00:33:28.466Z,1772498008.466 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20260302T225043/Express0056.lzma
2026-03-03T00:33:29.468Z,1772498009.468 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0056.lzma.bak
2026-03-03T00:33:29.468Z,1772498009.468 [DataOverHttps](INFO): SBD MOMSN=26693238
2026-03-03T00:33:31.146Z,1772498011.146 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T00:33:31.146Z,1772498011.146 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T00:33:31.146Z,1772498011.146 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T00:34:05.064Z,1772498045.064 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:34:05.065Z,1772498045.065 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:34:56.373Z,1772498096.373 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:34:56.373Z,1772498096.373 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:35:29.909Z,1772498129.909 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:35:29.909Z,1772498129.909 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:35:42.020Z,1772498142.020 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:35:42.020Z,1772498142.020 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:37:15.344Z,1772498235.344 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:37:15.344Z,1772498235.344 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:38:06.248Z,1772498286.248 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-03T00:38:31.713Z,1772498311.713 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T00:38:31.714Z,1772498311.714 [Default:CheckIn:C.Wait] Stopped
2026-03-03T00:38:31.714Z,1772498311.714 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T00:38:31.714Z,1772498311.714 [Default:CheckIn:D] Running Loop=1
2026-03-03T00:38:32.115Z,1772498312.115 [Default:CheckIn:D] Stopped
2026-03-03T00:38:32.115Z,1772498312.115 [Default:CheckIn:E] Running Loop=1
2026-03-03T00:38:32.528Z,1772498312.528 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.857585 min
2026-03-03T00:38:32.528Z,1772498312.528 [Default:CheckIn:E] Stopped
2026-03-03T00:38:32.528Z,1772498312.528 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T00:38:32.528Z,1772498312.528 [Default:CheckIn] Stopped
2026-03-03T00:38:32.528Z,1772498312.528 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T00:38:32.528Z,1772498312.528 [Default:CheckIn](INFO): Running loop #19
2026-03-03T00:38:32.528Z,1772498312.528 [Default:CheckIn] Running Loop=19
2026-03-03T00:38:32.529Z,1772498312.529 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T00:38:32.529Z,1772498312.529 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T00:38:34.533Z,1772498314.533 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003834.00,A,3648.16466,N,12147.28374,W,0.583,144.64,030326,,,A*78
2026-03-03T00:38:34.535Z,1772498314.535 [NAL9602](INFO): GPS fix at 20260303T003834: (36.802744, -121.788062)
2026-03-03T00:38:34.562Z,1772498314.562 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T00:38:34.562Z,1772498314.562 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T00:38:43.726Z,1772498323.726 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0058.lzma
2026-03-03T00:38:44.728Z,1772498324.728 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0058.lzma.bak
2026-03-03T00:38:44.728Z,1772498324.728 [DataOverHttps](INFO): SBD MOMSN=26693248
2026-03-03T00:39:00.391Z,1772498340.391 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20260302T225043/Express0059.lzma
2026-03-03T00:39:01.392Z,1772498341.392 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0059.lzma.bak
2026-03-03T00:39:01.392Z,1772498341.392 [DataOverHttps](INFO): SBD MOMSN=26693251
2026-03-03T00:39:03.234Z,1772498343.234 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T00:39:03.234Z,1772498343.234 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T00:39:03.235Z,1772498343.235 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T00:39:06.447Z,1772498346.447 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-03T00:44:03.816Z,1772498643.816 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T00:44:03.816Z,1772498643.816 [Default:CheckIn:C.Wait] Stopped
2026-03-03T00:44:03.816Z,1772498643.816 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T00:44:03.816Z,1772498643.816 [Default:CheckIn:D] Running Loop=1
2026-03-03T00:44:04.221Z,1772498644.221 [Default:CheckIn:D] Stopped
2026-03-03T00:44:04.222Z,1772498644.222 [Default:CheckIn:E] Running Loop=1
2026-03-03T00:44:04.626Z,1772498644.626 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.392692 min
2026-03-03T00:44:04.627Z,1772498644.627 [Default:CheckIn:E] Stopped
2026-03-03T00:44:04.627Z,1772498644.627 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T00:44:04.627Z,1772498644.627 [Default:CheckIn] Stopped
2026-03-03T00:44:04.627Z,1772498644.627 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T00:44:04.627Z,1772498644.627 [Default:CheckIn](INFO): Running loop #20
2026-03-03T00:44:04.627Z,1772498644.627 [Default:CheckIn] Running Loop=20
2026-03-03T00:44:04.627Z,1772498644.627 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T00:44:04.627Z,1772498644.627 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T00:44:06.637Z,1772498646.637 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004406.00,A,3648.16781,N,12147.27767,W,0.350,144.64,030326,,,A*79
2026-03-03T00:44:06.639Z,1772498646.639 [NAL9602](INFO): GPS fix at 20260303T004406: (36.802797, -121.787961)
2026-03-03T00:44:06.672Z,1772498646.672 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T00:44:06.672Z,1772498646.672 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T00:44:13.586Z,1772498653.586 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0061.lzma
2026-03-03T00:44:14.588Z,1772498654.588 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0061.lzma.bak
2026-03-03T00:44:14.588Z,1772498654.588 [DataOverHttps](INFO): SBD MOMSN=26693271
2026-03-03T00:44:30.394Z,1772498670.394 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20260302T225043/Express0062.lzma
2026-03-03T00:44:31.396Z,1772498671.396 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0062.lzma.bak
2026-03-03T00:44:31.396Z,1772498671.396 [DataOverHttps](INFO): SBD MOMSN=26693274
2026-03-03T00:44:32.973Z,1772498672.973 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T00:44:32.973Z,1772498672.973 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T00:44:32.973Z,1772498672.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T00:45:12.541Z,1772498712.541 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:45:12.541Z,1772498712.541 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:46:17.180Z,1772498777.180 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:46:17.180Z,1772498777.180 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:47:02.849Z,1772498822.849 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:47:02.850Z,1772498822.850 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:47:24.261Z,1772498844.261 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:47:24.261Z,1772498844.261 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T00:49:09.297Z,1772498949.297 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-03T00:49:33.544Z,1772498973.544 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T00:49:33.544Z,1772498973.544 [Default:CheckIn:C.Wait] Stopped
2026-03-03T00:49:33.544Z,1772498973.544 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T00:49:33.544Z,1772498973.544 [Default:CheckIn:D] Running Loop=1
2026-03-03T00:49:33.954Z,1772498973.954 [Default:CheckIn:D] Stopped
2026-03-03T00:49:33.954Z,1772498973.954 [Default:CheckIn:E] Running Loop=1
2026-03-03T00:49:34.361Z,1772498974.361 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.888232 min
2026-03-03T00:49:34.361Z,1772498974.361 [Default:CheckIn:E] Stopped
2026-03-03T00:49:34.361Z,1772498974.361 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T00:49:34.362Z,1772498974.362 [Default:CheckIn] Stopped
2026-03-03T00:49:34.362Z,1772498974.362 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T00:49:34.362Z,1772498974.362 [Default:CheckIn](INFO): Running loop #21
2026-03-03T00:49:34.362Z,1772498974.362 [Default:CheckIn] Running Loop=21
2026-03-03T00:49:34.362Z,1772498974.362 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T00:49:34.362Z,1772498974.362 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T00:49:36.365Z,1772498976.365 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004935.00,A,3648.17237,N,12147.28113,W,0.039,0.00,030326,,,A*78
2026-03-03T00:49:36.377Z,1772498976.377 [NAL9602](INFO): GPS fix at 20260303T004935: (36.802873, -121.788019)
2026-03-03T00:49:36.388Z,1772498976.388 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T00:49:36.388Z,1772498976.388 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T00:49:44.410Z,1772498984.410 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0064.lzma
2026-03-03T00:49:45.412Z,1772498985.412 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0064.lzma.bak
2026-03-03T00:49:45.412Z,1772498985.412 [DataOverHttps](INFO): SBD MOMSN=26693293
2026-03-03T00:50:01.146Z,1772499001.146 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20260302T225043/Express0065.lzma
2026-03-03T00:50:02.148Z,1772499002.148 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0065.lzma.bak
2026-03-03T00:50:02.148Z,1772499002.148 [DataOverHttps](INFO): SBD MOMSN=26693296
2026-03-03T00:50:03.856Z,1772499003.856 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T00:50:03.856Z,1772499003.856 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T00:50:03.856Z,1772499003.856 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T00:50:08.279Z,1772499008.279 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-03T00:55:04.425Z,1772499304.425 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T00:55:04.425Z,1772499304.425 [Default:CheckIn:C.Wait] Stopped
2026-03-03T00:55:04.425Z,1772499304.425 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T00:55:04.425Z,1772499304.425 [Default:CheckIn:D] Running Loop=1
2026-03-03T00:55:04.827Z,1772499304.827 [Default:CheckIn:D] Stopped
2026-03-03T00:55:04.827Z,1772499304.827 [Default:CheckIn:E] Running Loop=1
2026-03-03T00:55:05.245Z,1772499305.245 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.402775 min
2026-03-03T00:55:05.245Z,1772499305.245 [Default:CheckIn:E] Stopped
2026-03-03T00:55:05.245Z,1772499305.245 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T00:55:05.245Z,1772499305.245 [Default:CheckIn] Stopped
2026-03-03T00:55:05.245Z,1772499305.245 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T00:55:05.245Z,1772499305.245 [Default:CheckIn](INFO): Running loop #22
2026-03-03T00:55:05.245Z,1772499305.245 [Default:CheckIn] Running Loop=22
2026-03-03T00:55:05.246Z,1772499305.246 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T00:55:05.246Z,1772499305.246 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T00:55:07.244Z,1772499307.244 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005506.00,A,3648.16771,N,12147.28188,W,0.078,0.00,030326,,,A*74
2026-03-03T00:55:07.247Z,1772499307.247 [NAL9602](INFO): GPS fix at 20260303T005506: (36.802795, -121.788031)
2026-03-03T00:55:07.258Z,1772499307.258 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T00:55:07.258Z,1772499307.258 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T00:55:15.054Z,1772499315.054 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0067.lzma
2026-03-03T00:55:16.056Z,1772499316.056 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0067.lzma.bak
2026-03-03T00:55:16.057Z,1772499316.057 [DataOverHttps](INFO): SBD MOMSN=26693300
2026-03-03T00:55:32.246Z,1772499332.246 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20260302T225043/Express0068.lzma
2026-03-03T00:55:33.248Z,1772499333.248 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0068.lzma.bak
2026-03-03T00:55:33.248Z,1772499333.248 [DataOverHttps](INFO): SBD MOMSN=26693303
2026-03-03T00:55:35.142Z,1772499335.142 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T00:55:35.143Z,1772499335.143 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T00:55:35.143Z,1772499335.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T00:55:37.948Z,1772499337.948 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-03T00:55:38.026Z,1772499338.026 [NAL9602](ERROR): received:
+CSQ:0
OK620, 2, 0, 0, 0
OK
2026-03-03T00:58:05.817Z,1772499485.817 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-03T00:58:05.817Z,1772499485.817 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-03T01:00:09.439Z,1772499609.439 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-03T01:00:35.713Z,1772499635.713 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T01:00:35.713Z,1772499635.713 [Default:CheckIn:C.Wait] Stopped
2026-03-03T01:00:35.713Z,1772499635.713 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T01:00:35.713Z,1772499635.713 [Default:CheckIn:D] Running Loop=1
2026-03-03T01:00:36.114Z,1772499636.114 [Default:CheckIn:D] Stopped
2026-03-03T01:00:36.114Z,1772499636.114 [Default:CheckIn:E] Running Loop=1
2026-03-03T01:00:36.526Z,1772499636.526 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.924235 min
2026-03-03T01:00:36.527Z,1772499636.527 [Default:CheckIn:E] Stopped
2026-03-03T01:00:36.527Z,1772499636.527 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T01:00:36.527Z,1772499636.527 [Default:CheckIn] Stopped
2026-03-03T01:00:36.527Z,1772499636.527 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T01:00:36.527Z,1772499636.527 [Default:CheckIn](INFO): Running loop #23
2026-03-03T01:00:36.527Z,1772499636.527 [Default:CheckIn] Running Loop=23
2026-03-03T01:00:36.528Z,1772499636.528 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T01:00:36.528Z,1772499636.528 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T01:00:38.528Z,1772499638.528 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010037.00,A,3648.16807,N,12147.28577,W,0.194,140.96,030326,,,A*74
2026-03-03T01:00:38.531Z,1772499638.531 [NAL9602](INFO): GPS fix at 20260303T010037: (36.802801, -121.788096)
2026-03-03T01:00:38.541Z,1772499638.541 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T01:00:38.541Z,1772499638.541 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T01:00:47.298Z,1772499647.298 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0070.lzma
2026-03-03T01:00:48.300Z,1772499648.300 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0070.lzma.bak
2026-03-03T01:00:48.300Z,1772499648.300 [DataOverHttps](INFO): SBD MOMSN=26693320
2026-03-03T01:01:04.486Z,1772499664.486 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20260302T225043/Express0071.lzma
2026-03-03T01:01:05.488Z,1772499665.488 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0071.lzma.bak
2026-03-03T01:01:05.488Z,1772499665.488 [DataOverHttps](INFO): SBD MOMSN=26693323
2026-03-03T01:01:07.229Z,1772499667.229 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T01:01:07.229Z,1772499667.229 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T01:01:07.230Z,1772499667.230 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T01:01:11.251Z,1772499671.251 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-03T01:06:07.064Z,1772499967.064 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2026-03-03T01:06:07.857Z,1772499967.857 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-03T01:06:07.857Z,1772499967.857 [Default:CheckIn:C.Wait] Stopped
2026-03-03T01:06:07.857Z,1772499967.857 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T01:06:07.857Z,1772499967.857 [Default:CheckIn:D] Running Loop=1
2026-03-03T01:06:08.245Z,1772499968.245 [Default:CheckIn:D] Stopped
2026-03-03T01:06:08.245Z,1772499968.245 [Default:CheckIn:E] Running Loop=1
2026-03-03T01:06:08.632Z,1772499968.632 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.459749 min
2026-03-03T01:06:08.632Z,1772499968.632 [Default:CheckIn:E] Stopped
2026-03-03T01:06:08.632Z,1772499968.632 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-03T01:06:08.632Z,1772499968.632 [Default:CheckIn] Stopped
2026-03-03T01:06:08.632Z,1772499968.632 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T01:06:08.632Z,1772499968.632 [Default:CheckIn](INFO): Running loop #24
2026-03-03T01:06:08.632Z,1772499968.632 [Default:CheckIn] Running Loop=24
2026-03-03T01:06:08.632Z,1772499968.632 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-03T01:06:08.632Z,1772499968.632 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-03T01:06:10.651Z,1772499970.651 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010609.00,A,3648.15296,N,12147.27934,W,1.166,221.35,030326,,,A*7B
2026-03-03T01:06:10.653Z,1772499970.653 [NAL9602](INFO): GPS fix at 20260303T010609: (36.802549, -121.787989)
2026-03-03T01:06:10.664Z,1772499970.664 [Default:CheckIn:Read_GPS] Stopped
2026-03-03T01:06:10.664Z,1772499970.664 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-03T01:06:17.546Z,1772499977.546 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260302T225043/Courier0073.lzma
2026-03-03T01:06:18.548Z,1772499978.548 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Courier0073.lzma.bak
2026-03-03T01:06:18.548Z,1772499978.548 [DataOverHttps](INFO): SBD MOMSN=26693334
2026-03-03T01:06:34.314Z,1772499994.314 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20260302T225043/Express0074.lzma
2026-03-03T01:06:35.316Z,1772499995.316 [DataOverHttps](INFO): Moved sent file to Logs/20260302T225043/Express0074.lzma.bak
2026-03-03T01:06:35.317Z,1772499995.317 [DataOverHttps](INFO): SBD MOMSN=26693337
2026-03-03T01:06:36.989Z,1772499996.989 [Default:CheckIn:Read_Iridium] Stopped
2026-03-03T01:06:36.989Z,1772499996.989 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-03T01:06:36.989Z,1772499996.989 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-03T01:06:41.420Z,1772500001.420 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-03T01:06:41.498Z,1772500001.498 [NAL9602](ERROR): received:
+CSQ:0
OK620, 2, 0, 0, 0
OK
2026-03-03T01:08:14.447Z,1772500094.447 [CommandExec](IMPORTANT): got command quit
2026-03-03T01:08:15.450Z,1772500095.450 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2026-03-03T01:08:15.451Z,1772500095.451 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:15.451Z,1772500095.451 [CommandExec](INFO): Uninitializing the command executive.
2026-03-03T01:08:15.451Z,1772500095.451 [CommandExec](INFO): Uninitializing the command scheduler.
2026-03-03T01:08:15.451Z,1772500095.451 [CommandExec ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:15.458Z,1772500095.458 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2026-03-03T01:08:15.458Z,1772500095.458 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2026-03-03T01:08:15.459Z,1772500095.459 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:15.459Z,1772500095.459 [NavChartDb](INFO): Join timeout helper Thread ID is 1321
2026-03-03T01:08:15.631Z,1772500095.631 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:15.631Z,1772500095.631 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:15.647Z,1772500095.647 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2026-03-03T01:08:15.647Z,1772500095.647 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:15.648Z,1772500095.648 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1322
2026-03-03T01:08:15.743Z,1772500095.743 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:15.743Z,1772500095.743 [WetLabsBB2FL](INFO): Powering down
2026-03-03T01:08:15.744Z,1772500095.744 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:15.747Z,1772500095.747 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2026-03-03T01:08:15.747Z,1772500095.747 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:15.748Z,1772500095.748 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1323
2026-03-03T01:08:16.330Z,1772500096.330 [CTD_Seabird](INFO): Powering down
2026-03-03T01:08:16.349Z,1772500096.349 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:16.351Z,1772500096.351 [CTD_Seabird](INFO): Powering down
2026-03-03T01:08:16.363Z,1772500096.363 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:16.372Z,1772500096.372 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2026-03-03T01:08:16.372Z,1772500096.372 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:16.372Z,1772500096.372 [Radio_Surface](INFO): Join timeout helper Thread ID is 1324
2026-03-03T01:08:16.455Z,1772500096.455 [Radio_Surface](INFO): Powering down
2026-03-03T01:08:16.456Z,1772500096.456 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:16.457Z,1772500096.457 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:16.470Z,1772500096.470 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2026-03-03T01:08:16.470Z,1772500096.470 [Onboard ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:16.471Z,1772500096.471 [Onboard](INFO): Join timeout helper Thread ID is 1325
2026-03-03T01:08:16.991Z,1772500096.991 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2026-03-03T01:08:21.346Z,1772500101.346 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:21.347Z,1772500101.347 [Onboard ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:21.351Z,1772500101.351 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2026-03-03T01:08:21.351Z,1772500101.351 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:21.352Z,1772500101.352 [DataOverHttps](INFO): Join timeout helper Thread ID is 1326
2026-03-03T01:08:22.010Z,1772500102.010 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:22.011Z,1772500102.011 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:22.031Z,1772500102.031 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2026-03-03T01:08:22.031Z,1772500102.031 [DAT ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:22.032Z,1772500102.032 [DAT](INFO): Join timeout helper Thread ID is 1327
2026-03-03T01:08:22.258Z,1772500102.258 [DAT](INFO): Powering down
2026-03-03T01:08:22.331Z,1772500102.331 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:22.331Z,1772500102.331 [DAT](INFO): Powering down
2026-03-03T01:08:22.332Z,1772500102.332 [DAT ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:22.351Z,1772500102.351 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2026-03-03T01:08:22.351Z,1772500102.351 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:22.351Z,1772500102.351 [BackseatComponent](INFO): Join timeout helper Thread ID is 1328
2026-03-03T01:08:22.402Z,1772500102.402 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:22.403Z,1772500102.403 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:22.404Z,1772500102.404 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2026-03-03T01:08:22.404Z,1772500102.404 [logger ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:22.405Z,1772500102.405 [logger](INFO): Join timeout helper Thread ID is 1329
2026-03-03T01:08:22.417Z,1772500102.417 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:22.418Z,1772500102.418 [logger ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:22.435Z,1772500102.435 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2026-03-03T01:08:22.435Z,1772500102.435 [CommandLine ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:22.436Z,1772500102.436 [CommandLine](INFO): Join timeout helper Thread ID is 1330
2026-03-03T01:08:22.507Z,1772500102.507 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:22.507Z,1772500102.507 [CommandLine ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:22.523Z,1772500102.523 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2026-03-03T01:08:22.523Z,1772500102.523 [CommandExec ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:22.524Z,1772500102.524 [CommandExec](INFO): Join timeout helper Thread ID is 1331
2026-03-03T01:08:22.525Z,1772500102.525 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2026-03-03T01:08:22.525Z,1772500102.525 [controlThread ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:22.525Z,1772500102.525 [controlThread](INFO): Join timeout helper Thread ID is 1332
2026-03-03T01:08:22.798Z,1772500102.798 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2026-03-03T01:08:22.799Z,1772500102.799 [controlThread](DEBUG): Uninitializing ControlThread
2026-03-03T01:08:22.799Z,1772500102.799 [AHRS_M2](INFO): Powering down
2026-03-03T01:08:22.878Z,1772500102.878 [NAL9602](INFO): Powering down
2026-03-03T01:08:22.880Z,1772500102.880 [Sonardyne_Nano](INFO): Powering down
2026-03-03T01:08:22.999Z,1772500102.999 [Waterlinked](INFO): Powering down
2026-03-03T01:08:23.087Z,1772500103.087 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2026-03-03T01:08:23.087Z,1772500103.087 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2026-03-03T01:08:23.088Z,1772500103.088 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2026-03-03T01:08:23.088Z,1772500103.088 [MissionManager](INFO): Uninitializing Mission Default
2026-03-03T01:08:23.088Z,1772500103.088 [Default] Stopped
2026-03-03T01:08:23.089Z,1772500103.089 [Default](DEBUG): Aggregate::uninitialize Default
2026-03-03T01:08:23.089Z,1772500103.089 [Default:B.GoToSurface] Stopped
2026-03-03T01:08:23.089Z,1772500103.089 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-03T01:08:23.089Z,1772500103.089 [Default:CheckIn] Stopped
2026-03-03T01:08:23.089Z,1772500103.089 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-03T01:08:23.089Z,1772500103.089 [Default:CheckIn:C.Wait] Stopped
2026-03-03T01:08:23.089Z,1772500103.089 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-03T01:08:23.092Z,1772500103.092 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2026-03-03T01:08:23.092Z,1772500103.092 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2026-03-03T01:08:23.093Z,1772500103.093 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2026-03-03T01:08:23.093Z,1772500103.093 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2026-03-03T01:08:23.093Z,1772500103.093 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2026-03-03T01:08:23.093Z,1772500103.093 [BuoyancyServo](INFO): Powering down
2026-03-03T01:08:23.106Z,1772500103.106 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2026-03-03T01:08:23.107Z,1772500103.107 [ElevatorServo](INFO): Powering down
2026-03-03T01:08:23.107Z,1772500103.107 [MassServo](DEBUG): Uninitialize Mass Servo.
2026-03-03T01:08:23.108Z,1772500103.108 [MassServo](INFO): Powering down
2026-03-03T01:08:23.108Z,1772500103.108 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2026-03-03T01:08:23.108Z,1772500103.108 [RudderServo](INFO): Powering down
2026-03-03T01:08:23.109Z,1772500103.109 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2026-03-03T01:08:23.109Z,1772500103.109 [ThrusterHE](INFO): Powering down
2026-03-03T01:08:23.110Z,1772500103.110 [SBIT](DEBUG): Uninitialize SBIT Component.
2026-03-03T01:08:23.111Z,1772500103.111 [IBIT](DEBUG): Uninitialize IBIT Component.
2026-03-03T01:08:23.111Z,1772500103.111 [CBIT](DEBUG): Uninitialize CBIT Component.
2026-03-03T01:08:23.111Z,1772500103.111 [CBIT](DEBUG): Powering off loads.
2026-03-03T01:08:23.122Z,1772500103.122 [CBIT](DEBUG): Disabling WDT.
2026-03-03T01:08:23.134Z,1772500103.134 [GFScanner](DEBUG): Uninitialize GFScanner component.
2026-03-03T01:08:23.134Z,1772500103.134 [GFScanner](DEBUG): Opening all GF detection circuits.
2026-03-03T01:08:23.136Z,1772500103.136 [controlThread ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:23.193Z,1772500103.193 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:23.197Z,1772500103.197 [Onboard ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:23.203Z,1772500103.203 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:23.209Z,1772500103.209 [DAT ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:23.253Z,1772500103.253 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:23.296Z,1772500103.296 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:23.301Z,1772500103.301 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:23.321Z,1772500103.321 [GFScanner](DEBUG): Uninitialize GFScanner component.
2026-03-03T01:08:23.322Z,1772500103.322 [GFScanner](DEBUG): Opening all GF detection circuits.
2026-03-03T01:08:23.337Z,1772500103.337 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2026-03-03T01:08:23.406Z,1772500103.406 [logger ThreadHandler](INFO): Thread cancelled.