2025-12-15T20:17:51.390Z,1765829871.390 [Supervisor](DEBUG): Initializing supervisor.
2025-12-15T20:17:51.395Z,1765829871.395 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-12-15T20:17:51.395Z,1765829871.395 [SyncHandler](INFO): Protected caller Thread ID is 1323
2025-12-15T20:17:51.396Z,1765829871.396 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-12-15T20:17:51.397Z,1765829871.397 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-12-15T20:17:51.397Z,1765829871.397 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1324
2025-12-15T20:17:51.401Z,1765829871.401 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-12-15T20:17:51.420Z,1765829871.420 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-12-15T20:17:51.421Z,1765829871.421 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-12-15T20:17:51.421Z,1765829871.421 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1325
2025-12-15T20:17:51.425Z,1765829871.425 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-12-15T20:17:51.426Z,1765829871.426 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-12-15T20:17:51.427Z,1765829871.427 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1326
2025-12-15T20:17:51.429Z,1765829871.429 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-12-15T20:17:51.430Z,1765829871.430 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-12-15T20:17:51.430Z,1765829871.430 [logger ThreadHandler](INFO): Protected caller Thread ID is 1327
2025-12-15T20:17:51.433Z,1765829871.433 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-12-15T20:17:51.434Z,1765829871.434 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-12-15T20:17:51.436Z,1765829871.436 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-12-15T20:17:51.929Z,1765829871.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-12-15T20:17:51.930Z,1765829871.930 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-12-15T20:17:52.143Z,1765829872.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-12-15T20:17:52.143Z,1765829872.143 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-12-15T20:17:52.242Z,1765829872.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-12-15T20:17:52.242Z,1765829872.242 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-12-15T20:17:52.477Z,1765829872.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-12-15T20:17:52.477Z,1765829872.477 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-12-15T20:17:52.564Z,1765829872.564 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-12-15T20:17:52.700Z,1765829872.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-12-15T20:17:52.701Z,1765829872.701 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-12-15T20:17:53.286Z,1765829873.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-12-15T20:17:53.287Z,1765829873.287 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-12-15T20:17:53.427Z,1765829873.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-12-15T20:17:53.428Z,1765829873.428 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-12-15T20:17:53.966Z,1765829873.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-12-15T20:17:53.966Z,1765829873.966 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-12-15T20:17:54.150Z,1765829874.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-12-15T20:17:54.150Z,1765829874.150 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-12-15T20:17:54.568Z,1765829874.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-12-15T20:17:54.569Z,1765829874.569 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-12-15T20:17:54.859Z,1765829874.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-12-15T20:17:54.859Z,1765829874.859 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-12-15T20:17:54.976Z,1765829874.976 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-12-15T20:17:54.977Z,1765829874.977 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-12-15T20:17:55.837Z,1765829875.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-12-15T20:17:55.837Z,1765829875.837 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-12-15T20:17:56.181Z,1765829876.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-12-15T20:17:56.181Z,1765829876.181 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-12-15T20:17:56.427Z,1765829876.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-12-15T20:17:56.429Z,1765829876.429 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2025-12-15T20:17:56.430Z,1765829876.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2025-12-15T20:17:56.554Z,1765829876.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2025-12-15T20:17:56.645Z,1765829876.645 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2025-12-15T20:17:56.786Z,1765829876.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2025-12-15T20:17:56.880Z,1765829876.880 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2025-12-15T20:17:57.150Z,1765829877.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-12-15T20:17:57.151Z,1765829877.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2025-12-15T20:17:57.261Z,1765829877.261 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2025-12-15T20:17:57.374Z,1765829877.374 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2025-12-15T20:17:57.480Z,1765829877.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2025-12-15T20:17:57.637Z,1765829877.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2025-12-15T20:17:57.773Z,1765829877.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2025-12-15T20:17:57.911Z,1765829877.911 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2025-12-15T20:17:57.911Z,1765829877.911 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-12-15T20:17:57.919Z,1765829877.919 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-12-15T20:17:57.930Z,1765829877.930 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-12-15T20:17:57.930Z,1765829877.930 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-12-15T20:17:58.286Z,1765829878.286 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-12-15T20:17:58.287Z,1765829878.287 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-12-15T20:17:58.397Z,1765829878.397 [VerticalControl](DEBUG): Construct VerticalControl.
2025-12-15T20:17:58.489Z,1765829878.489 [VerticalControl] Loaded
2025-12-15T20:17:58.490Z,1765829878.490 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-12-15T20:17:58.492Z,1765829878.492 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-12-15T20:17:58.552Z,1765829878.552 [HorizontalControl] Loaded
2025-12-15T20:17:58.552Z,1765829878.552 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-12-15T20:17:58.554Z,1765829878.554 [SpeedControl](DEBUG): Construct SpeedControl.
2025-12-15T20:17:58.557Z,1765829878.557 [SpeedControl] Loaded
2025-12-15T20:17:58.558Z,1765829878.558 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-12-15T20:17:58.560Z,1765829878.560 [LoopControl](DEBUG): Construct LoopControl.
2025-12-15T20:17:58.561Z,1765829878.561 [LoopControl] Loaded
2025-12-15T20:17:58.561Z,1765829878.561 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-12-15T20:17:58.561Z,1765829878.561 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-12-15T20:17:58.562Z,1765829878.562 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-12-15T20:17:58.874Z,1765829878.874 [BuoyancyServo] Loaded
2025-12-15T20:17:58.874Z,1765829878.874 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-12-15T20:17:58.896Z,1765829878.896 [ElevatorServo] Loaded
2025-12-15T20:17:58.896Z,1765829878.896 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-12-15T20:17:59.008Z,1765829879.008 [MassServo] Loaded
2025-12-15T20:17:59.008Z,1765829879.008 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-12-15T20:17:59.061Z,1765829879.061 [RudderServo] Loaded
2025-12-15T20:17:59.061Z,1765829879.061 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-12-15T20:17:59.078Z,1765829879.078 [ThrusterHE] Loaded
2025-12-15T20:17:59.078Z,1765829879.078 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-12-15T20:17:59.078Z,1765829879.078 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-12-15T20:17:59.079Z,1765829879.079 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-12-15T20:17:59.128Z,1765829879.128 [DepthRateCalculator] Loaded
2025-12-15T20:17:59.129Z,1765829879.129 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-12-15T20:17:59.134Z,1765829879.134 [PitchRateCalculator] Loaded
2025-12-15T20:17:59.134Z,1765829879.134 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-12-15T20:17:59.143Z,1765829879.143 [SpeedCalculator] Loaded
2025-12-15T20:17:59.144Z,1765829879.144 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-12-15T20:17:59.148Z,1765829879.148 [YawRateCalculator] Loaded
2025-12-15T20:17:59.148Z,1765829879.148 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-12-15T20:17:59.166Z,1765829879.166 [ElevatorOffsetCalculator] Loaded
2025-12-15T20:17:59.166Z,1765829879.166 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-12-15T20:17:59.167Z,1765829879.167 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-12-15T20:17:59.167Z,1765829879.167 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-12-15T20:17:59.281Z,1765829879.281 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-12-15T20:17:59.281Z,1765829879.281 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-12-15T20:17:59.295Z,1765829879.295 [NavChart] Loaded
2025-12-15T20:17:59.295Z,1765829879.295 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-12-15T20:17:59.300Z,1765829879.300 [UniversalFixResidualReporter] Loaded
2025-12-15T20:17:59.301Z,1765829879.301 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-12-15T20:17:59.301Z,1765829879.301 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-12-15T20:17:59.302Z,1765829879.302 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-12-15T20:17:59.769Z,1765829879.769 [AHRS_M2] Loaded
2025-12-15T20:17:59.769Z,1765829879.769 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-12-15T20:17:59.808Z,1765829879.808 [BackseatComponent] Loaded
2025-12-15T20:17:59.808Z,1765829879.808 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-12-15T20:17:59.809Z,1765829879.809 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4092B4E0
2025-12-15T20:17:59.810Z,1765829879.810 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1409
2025-12-15T20:17:59.813Z,1765829879.813 [LcmPublisher] Loaded
2025-12-15T20:17:59.813Z,1765829879.813 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2025-12-15T20:17:59.819Z,1765829879.819 [BioacousticsDataBridge] Loaded
2025-12-15T20:17:59.819Z,1765829879.819 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2025-12-15T20:18:00.645Z,1765829880.645 [BPC1] Loaded
2025-12-15T20:18:00.645Z,1765829880.645 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-12-15T20:18:00.743Z,1765829880.743 [DAT] Loaded
2025-12-15T20:18:00.744Z,1765829880.744 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-12-15T20:18:00.745Z,1765829880.745 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4095B4E0
2025-12-15T20:18:00.745Z,1765829880.745 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1410
2025-12-15T20:18:00.820Z,1765829880.820 [DataOverHttps] Loaded
2025-12-15T20:18:00.820Z,1765829880.820 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-12-15T20:18:00.821Z,1765829880.821 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4098B4E0
2025-12-15T20:18:00.821Z,1765829880.821 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1411
2025-12-15T20:18:00.844Z,1765829880.844 [Depth_Keller] Loaded
2025-12-15T20:18:00.844Z,1765829880.844 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-12-15T20:18:00.849Z,1765829880.849 [DropWeight] Loaded
2025-12-15T20:18:00.850Z,1765829880.850 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-12-15T20:18:00.912Z,1765829880.912 [NAL9602] Loaded
2025-12-15T20:18:00.913Z,1765829880.913 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-12-15T20:18:00.942Z,1765829880.942 [Onboard] Loaded
2025-12-15T20:18:00.942Z,1765829880.942 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-12-15T20:18:00.943Z,1765829880.943 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409BB4E0
2025-12-15T20:18:00.943Z,1765829880.943 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1412
2025-12-15T20:18:00.957Z,1765829880.957 [Power24vConverter] Loaded
2025-12-15T20:18:00.958Z,1765829880.958 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-12-15T20:18:00.974Z,1765829880.974 [Radio_Surface] Loaded
2025-12-15T20:18:00.975Z,1765829880.975 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-12-15T20:18:00.976Z,1765829880.976 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409EB4E0
2025-12-15T20:18:00.976Z,1765829880.976 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1413
2025-12-15T20:18:00.989Z,1765829880.989 [Sonardyne_Nano] Loaded
2025-12-15T20:18:00.990Z,1765829880.990 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-12-15T20:18:00.990Z,1765829880.990 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-12-15T20:18:00.991Z,1765829880.991 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-12-15T20:18:01.049Z,1765829881.049 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-12-15T20:18:01.050Z,1765829881.050 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-12-15T20:18:01.223Z,1765829881.223 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-12-15T20:18:01.224Z,1765829881.224 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-12-15T20:18:01.253Z,1765829881.253 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-12-15T20:18:01.254Z,1765829881.254 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-12-15T20:18:01.423Z,1765829881.423 [CTD_Seabird] Loaded
2025-12-15T20:18:01.423Z,1765829881.423 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-12-15T20:18:01.424Z,1765829881.424 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B344E0
2025-12-15T20:18:01.424Z,1765829881.424 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1414
2025-12-15T20:18:01.446Z,1765829881.446 [PAR_Licor] Loaded
2025-12-15T20:18:01.446Z,1765829881.446 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-12-15T20:18:01.490Z,1765829881.490 [WetLabsBB2FL] Loaded
2025-12-15T20:18:01.490Z,1765829881.490 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-12-15T20:18:01.491Z,1765829881.491 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B644E0
2025-12-15T20:18:01.492Z,1765829881.492 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1415
2025-12-15T20:18:01.492Z,1765829881.492 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-12-15T20:18:01.493Z,1765829881.493 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-12-15T20:18:01.829Z,1765829881.829 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-12-15T20:18:01.830Z,1765829881.830 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-12-15T20:18:01.987Z,1765829881.987 [SBIT](DEBUG): Construct Startup Built In Test.
2025-12-15T20:18:01.996Z,1765829881.996 [SBIT] Loaded
2025-12-15T20:18:01.996Z,1765829881.996 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-12-15T20:18:01.999Z,1765829881.999 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-12-15T20:18:02.012Z,1765829882.012 [IBIT] Loaded
2025-12-15T20:18:02.012Z,1765829882.012 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-12-15T20:18:02.017Z,1765829882.017 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-12-15T20:18:02.096Z,1765829882.096 [CBIT] Loaded
2025-12-15T20:18:02.096Z,1765829882.096 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-12-15T20:18:02.128Z,1765829882.128 [GFScanner] Loaded
2025-12-15T20:18:02.129Z,1765829882.129 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-12-15T20:18:02.129Z,1765829882.129 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-12-15T20:18:02.135Z,1765829882.135 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-12-15T20:18:02.138Z,1765829882.138 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-12-15T20:18:02.149Z,1765829882.149 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-12-15T20:18:02.150Z,1765829882.150 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-12-15T20:18:02.151Z,1765829882.151 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1416
2025-12-15T20:18:02.155Z,1765829882.155 [Supervisor](INFO): Main Thread ID is 832
2025-12-15T20:18:02.155Z,1765829882.155 [Supervisor](DEBUG): Running supervisor.
2025-12-15T20:18:02.156Z,1765829882.156 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1417
2025-12-15T20:18:02.156Z,1765829882.156 [CommandExec](INFO): Initializing the command executive.
2025-12-15T20:18:02.158Z,1765829882.158 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1418
2025-12-15T20:18:02.160Z,1765829882.160 [controlThread ThreadHandler](INFO): Handler Thread ID is 1419
2025-12-15T20:18:02.161Z,1765829882.161 [controlThread](DEBUG): Initializing ControlThread
2025-12-15T20:18:02.161Z,1765829882.161 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-12-15T20:18:02.163Z,1765829882.163 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-12-15T20:18:02.164Z,1765829882.164 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-12-15T20:18:02.164Z,1765829882.164 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-12-15T20:18:02.166Z,1765829882.166 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-12-15T20:18:02.166Z,1765829882.166 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-12-15T20:18:02.166Z,1765829882.166 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-12-15T20:18:02.167Z,1765829882.167 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-12-15T20:18:02.167Z,1765829882.167 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-12-15T20:18:02.168Z,1765829882.168 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-12-15T20:18:02.168Z,1765829882.168 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-12-15T20:18:02.176Z,1765829882.176 [SBIT](INFO): Initialize SBIT Component.
2025-12-15T20:18:02.176Z,1765829882.176 [SBIT](IMPORTANT): git: 2025-12-15_A
2025-12-15T20:18:02.176Z,1765829882.176 [SBIT](INFO): git hash: 5b50703234a74cb9c609bca727303ff504a0b330
2025-12-15T20:18:02.177Z,1765829882.177 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-12-15T20:18:02.178Z,1765829882.178 [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
2025-12-15T20:18:02.179Z,1765829882.179 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2025-12-15T20:18:02.180Z,1765829882.180 [IBIT](INFO): Initialize IBIT Component.
2025-12-15T20:18:02.180Z,1765829882.180 [CBIT](DEBUG): Initialize CBIT Component.
2025-12-15T20:18:02.181Z,1765829882.181 [logger ThreadHandler](INFO): Handler Thread ID is 1420
2025-12-15T20:18:02.191Z,1765829882.191 [CBIT](DEBUG): Initialized mux pins.
2025-12-15T20:18:02.192Z,1765829882.192 [CBIT](DEBUG): Initializing the watchdog timer.
2025-12-15T20:18:02.200Z,1765829882.200 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1421
2025-12-15T20:18:02.212Z,1765829882.212 [DAT ThreadHandler](INFO): Handler Thread ID is 1422
2025-12-15T20:18:02.213Z,1765829882.213 [DAT](INFO): Powering up
2025-12-15T20:18:02.213Z,1765829882.213 [DAT](DEBUG): Initializing DAT.
2025-12-15T20:18:02.216Z,1765829882.216 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-12-15T20:18:02.216Z,1765829882.216 [CBIT](DEBUG): Initializing heartbeat.
2025-12-15T20:18:02.218Z,1765829882.218 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1424
2025-12-15T20:18:02.219Z,1765829882.219 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-12-15T20:18:02.229Z,1765829882.229 [Onboard ThreadHandler](INFO): Handler Thread ID is 1425
2025-12-15T20:18:02.258Z,1765829882.258 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1426
2025-12-15T20:18:02.272Z,1765829882.272 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1427
2025-12-15T20:18:02.272Z,1765829882.272 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-12-15T20:18:02.277Z,1765829882.277 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1429
2025-12-15T20:18:02.280Z,1765829882.280 [WetLabsBB2FL](INFO): Powering up
2025-12-15T20:18:02.281Z,1765829882.281 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1431
2025-12-15T20:18:02.285Z,1765829882.285 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2025-12-15T20:18:02.285Z,1765829882.285 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2025-12-15T20:18:02.285Z,1765829882.285 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000
2025-12-15T20:18:02.285Z,1765829882.285 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000
2025-12-15T20:18:02.286Z,1765829882.286 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000
2025-12-15T20:18:02.286Z,1765829882.286 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000
2025-12-15T20:18:02.286Z,1765829882.286 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000
2025-12-15T20:18:02.286Z,1765829882.286 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000
2025-12-15T20:18:02.286Z,1765829882.286 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000
2025-12-15T20:18:02.286Z,1765829882.286 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000
2025-12-15T20:18:02.287Z,1765829882.287 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000
2025-12-15T20:18:02.287Z,1765829882.287 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000
2025-12-15T20:18:02.287Z,1765829882.287 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000
2025-12-15T20:18:02.287Z,1765829882.287 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000
2025-12-15T20:18:02.287Z,1765829882.287 [CBIT](DEBUG): Deactivating emergency mode.
2025-12-15T20:18:02.288Z,1765829882.288 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000
2025-12-15T20:18:02.288Z,1765829882.288 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000
2025-12-15T20:18:02.288Z,1765829882.288 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000
2025-12-15T20:18:02.289Z,1765829882.289 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000
2025-12-15T20:18:02.289Z,1765829882.289 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000
2025-12-15T20:18:02.289Z,1765829882.289 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000
2025-12-15T20:18:02.327Z,1765829882.327 [CBIT](DEBUG): Backplane powered.
2025-12-15T20:18:02.328Z,1765829882.328 [GFScanner](DEBUG): Initializing GFScanner
2025-12-15T20:18:02.328Z,1765829882.328 [GFScanner](DEBUG): Deactivating GF circuits.
2025-12-15T20:18:02.333Z,1765829882.333 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-12-15T20:18:02.333Z,1765829882.333 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-12-15T20:18:02.334Z,1765829882.334 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-12-15T20:18:02.335Z,1765829882.335 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-12-15T20:18:02.387Z,1765829882.387 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-12-15T20:18:02.414Z,1765829882.414 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-12-15T20:18:02.435Z,1765829882.435 [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
}
}
}
2025-12-15T20:18:02.465Z,1765829882.465 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-12-15T20:18:02.465Z,1765829882.465 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-12-15T20:18:02.466Z,1765829882.466 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-12-15T20:18:02.660Z,1765829882.660 [Radio_Surface](INFO): Powering up
2025-12-15T20:18:02.806Z,1765829882.806 [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
2025-12-15T20:18:02.839Z,1765829882.839 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-12-15T20:18:02.841Z,1765829882.841 [Default:A.Wait](DEBUG): Construct Wait.
2025-12-15T20:18:02.848Z,1765829882.848 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-12-15T20:18:02.917Z,1765829882.917 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-12-15T20:18:02.928Z,1765829882.928 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-12-15T20:18:02.947Z,1765829882.947 [Default:E.Execute](DEBUG): Construct Execute.
2025-12-15T20:18:02.949Z,1765829882.949 [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"
}
}
2025-12-15T20:18:02.960Z,1765829882.960 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,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,
2025-12-15T20:18:02.968Z,1765829882.968 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-12-15T20:18:03.028Z,1765829883.028 [Depth_Keller](INFO): Initializing.
2025-12-15T20:18:03.029Z,1765829883.029 [Power24vConverter](INFO): Powering up.
2025-12-15T20:18:03.030Z,1765829883.030 [Sonardyne_Nano](INFO): Initializing.
2025-12-15T20:18:03.072Z,1765829883.072 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-12-15T20:18:03.098Z,1765829883.098 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-12-15T20:18:03.104Z,1765829883.104 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-12-15T20:18:03.105Z,1765829883.105 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-12-15T20:18:03.112Z,1765829883.112 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-12-15T20:18:03.113Z,1765829883.113 [MassServo](DEBUG): Initializing EZServoServo.
2025-12-15T20:18:03.120Z,1765829883.120 [MassServo](DEBUG): Initializing MassServo.
2025-12-15T20:18:03.121Z,1765829883.121 [RudderServo](DEBUG): Initializing EZServoServo.
2025-12-15T20:18:03.128Z,1765829883.128 [RudderServo](DEBUG): Initializing RudderServo.
2025-12-15T20:18:03.129Z,1765829883.129 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-12-15T20:18:03.136Z,1765829883.136 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-12-15T20:18:04.892Z,1765829884.892 [WetLabsBB2FL](INFO): Powering down
2025-12-15T20:18:04.990Z,1765829884.990 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-12-15T20:18:06.280Z,1765829886.280 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2025-12-15T20:18:08.853Z,1765829888.853 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-12-15T20:18:14.568Z,1765829894.568 [DAT](INFO): DAT read:
2025-12-15T20:18:14.569Z,1765829894.569 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-12-15T20:18:16.333Z,1765829896.333 [DAT](INFO): DAT read: MF Frequency Band
2025-12-15T20:18:16.334Z,1765829896.334 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-12-15T20:18:16.334Z,1765829896.334 [DAT](INFO): DAT read: Dec 15 2025 20:18:10
2025-12-15T20:18:17.344Z,1765829897.344 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-12-15T20:18:17.595Z,1765829897.595 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2025-12-15T20:18:17.596Z,1765829897.596 [DAT](INFO): commRate: 800
2025-12-15T20:18:19.660Z,1765829899.660 [DAT](INFO): entering command mode
2025-12-15T20:18:19.860Z,1765829899.860 [DAT](INFO): DAT read:
2025-12-15T20:18:19.861Z,1765829899.861 [DAT](INFO): DAT read: user:1>
2025-12-15T20:18:19.861Z,1765829899.861 [DAT](INFO): setting verbose to 3
2025-12-15T20:18:20.112Z,1765829900.112 [DAT](INFO): DAT read: user:1>
2025-12-15T20:18:20.113Z,1765829900.113 [DAT](INFO): DAT read: Verbose | 3
2025-12-15T20:18:20.114Z,1765829900.114 [DAT](INFO): set verbose to 3
2025-12-15T20:18:20.114Z,1765829900.114 [DAT](INFO): setting DatVerbose to 27440
2025-12-15T20:18:20.364Z,1765829900.364 [DAT](INFO): DAT read: user:2>
2025-12-15T20:18:20.365Z,1765829900.365 [DAT](INFO): DAT read: DatVerbose | 27440
2025-12-15T20:18:20.366Z,1765829900.366 [DAT](INFO): set DatVerbose to 27440
2025-12-15T20:18:20.366Z,1765829900.366 [DAT](INFO): setting transmit power to 8
2025-12-15T20:18:20.616Z,1765829900.616 [DAT](INFO): DAT read: user:3>
2025-12-15T20:18:20.617Z,1765829900.617 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-12-15T20:18:20.617Z,1765829900.617 [DAT](INFO): set transmit power to 8
2025-12-15T20:18:20.618Z,1765829900.618 [DAT](INFO): setting local address to 12
2025-12-15T20:18:20.869Z,1765829900.869 [DAT](INFO): DAT read: user:4>
2025-12-15T20:18:20.870Z,1765829900.870 [DAT](INFO): DAT read: LocalAddr | 12
2025-12-15T20:18:20.870Z,1765829900.870 [DAT](INFO): set local address to 12
2025-12-15T20:18:20.871Z,1765829900.871 [DAT](INFO): Setting time to: 20:18:20 And date to:12/15/2025
2025-12-15T20:18:21.120Z,1765829901.120 [DAT](INFO): DAT read: user:5>
2025-12-15T20:18:21.121Z,1765829901.121 [DAT](INFO): DAT read: Mon Dec 15, 2025 20:18:20
2025-12-15T20:18:21.122Z,1765829901.122 [DAT](INFO): Local DAT time set to Mon Dec 15, 2025 20:18:20
2025-12-15T20:18:31.254Z,1765829911.254 [NAL9602](INFO): Powering up NAL9602
2025-12-15T20:18:42.162Z,1765829922.162 [NAL9602](INFO): NAL9602 initialized
2025-12-15T20:18:54.698Z,1765829934.698 [SBIT](IMPORTANT): Beginning Startup BIT
2025-12-15T20:18:54.703Z,1765829934.703 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-15T20:18:59.241Z,1765829939.241 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006671
CHAN A1 (24V): -0.007286
CHAN A2 (12V): -0.004954
CHAN A3 (5V): -0.002761
CHAN B0 (3.3V): -0.001700
CHAN B1 (3.15aV): -0.001223
CHAN B2 (3.15bV): -0.001434
CHAN B3 (GND): -0.000606
OPEN: 0.004465
Full Scale: +/- 1 mA
2025-12-15T20:19:48.947Z,1765829988.947 [SBIT](IMPORTANT): SBIT PASSED
2025-12-15T20:19:48.947Z,1765829988.947 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-12-15T20:19:48.948Z,1765829988.948 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2025-12-15T20:19:48.948Z,1765829988.948 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter;
2025-12-15T20:19:48.948Z,1765829988.948 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 1.000000 count_per_second;
2025-12-15T20:19:48.948Z,1765829988.948 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 15.000000 meter;
2025-12-15T20:19:48.948Z,1765829988.948 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=123.391022 cubic_centimeter;
2025-12-15T20:19:48.949Z,1765829988.949 [SBIT](IMPORTANT): VerticalControl.massDefault=8.078470 millimeter;
2025-12-15T20:19:48.949Z,1765829988.949 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool;
2025-12-15T20:19:49.357Z,1765829989.357 [MissionManager](IMPORTANT): Started mission Startup
2025-12-15T20:19:49.357Z,1765829989.357 [Startup] Running Loop=1
2025-12-15T20:19:49.357Z,1765829989.357 [Startup](DEBUG): Aggregate::initialize Startup
2025-12-15T20:19:49.357Z,1765829989.357 [Startup:A.GoToSurface] Running Loop=1
2025-12-15T20:19:49.357Z,1765829989.357 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-15T20:19:49.358Z,1765829989.358 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-12-15T20:19:49.358Z,1765829989.358 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-12-15T20:19:49.358Z,1765829989.358 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-12-15T20:19:49.359Z,1765829989.359 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-12-15T20:19:49.359Z,1765829989.359 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-15T20:19:49.360Z,1765829989.360 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-15T20:19:49.361Z,1765829989.361 [Startup:StartupSatComms] Running Loop=1
2025-12-15T20:19:49.361Z,1765829989.361 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-12-15T20:19:49.361Z,1765829989.361 [Startup:StartupSatComms:A] Running Loop=1
2025-12-15T20:19:49.747Z,1765829989.747 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-12-15T20:20:49.538Z,1765830049.538 [Startup:StartupSatComms:A](INFO): Timed out from 2025-12-15T20:19:49.4Z
2025-12-15T20:20:49.538Z,1765830049.538 [Startup:StartupSatComms:A] Stopped
2025-12-15T20:20:49.538Z,1765830049.538 [Startup:StartupSatComms:B] Running Loop=1
2025-12-15T20:20:49.947Z,1765830049.947 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-12-15T20:21:02.464Z,1765830062.464 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-12-15T20:21:02.464Z,1765830062.464 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-12-15T20:21:02.497Z,1765830062.497 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-12-15T20:21:02.877Z,1765830062.877 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-12-15T20:21:02.877Z,1765830062.877 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-12-15T20:21:40.566Z,1765830100.566 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004536
2025-12-15T20:21:47.943Z,1765830107.943 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20251215T201751/Courier0000.lzma
2025-12-15T20:21:48.946Z,1765830108.946 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0000.lzma.bak
2025-12-15T20:21:48.946Z,1765830108.946 [DataOverHttps](INFO): SBD MOMSN=26498980
2025-12-15T20:21:49.741Z,1765830109.741 [Startup:StartupSatComms:B](INFO): Timed out from 2025-12-15T20:20:49.5Z
2025-12-15T20:21:49.741Z,1765830109.741 [Startup:StartupSatComms:B] Stopped
2025-12-15T20:21:49.741Z,1765830109.741 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-12-15T20:21:49.741Z,1765830109.741 [Startup:StartupSatComms] Stopped
2025-12-15T20:21:49.741Z,1765830109.741 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-12-15T20:21:49.742Z,1765830109.742 [Startup](INFO): Completed Startup
2025-12-15T20:21:49.742Z,1765830109.742 [MissionManager](INFO): Startup is completed.
2025-12-15T20:21:49.742Z,1765830109.742 [MissionManager](INFO): Uninitializing Mission Startup
2025-12-15T20:21:49.742Z,1765830109.742 [Startup] Stopped
2025-12-15T20:21:49.742Z,1765830109.742 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-12-15T20:21:49.742Z,1765830109.742 [Startup:A.GoToSurface] Stopped
2025-12-15T20:21:49.742Z,1765830109.742 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-15T20:21:50.138Z,1765830110.138 [MissionManager](IMPORTANT): Started mission Default
2025-12-15T20:21:50.139Z,1765830110.139 [Default] Running Loop=1
2025-12-15T20:21:50.139Z,1765830110.139 [Default](DEBUG): Aggregate::initialize Default
2025-12-15T20:21:50.139Z,1765830110.139 [Default:B.GoToSurface] Running Loop=1
2025-12-15T20:21:50.139Z,1765830110.139 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-15T20:21:50.139Z,1765830110.139 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-12-15T20:21:50.140Z,1765830110.140 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-12-15T20:21:50.140Z,1765830110.140 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-12-15T20:21:50.140Z,1765830110.140 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-12-15T20:21:50.141Z,1765830110.141 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-15T20:21:50.141Z,1765830110.141 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-15T20:21:50.141Z,1765830110.141 [Default:A.Wait] Running Loop=1
2025-12-15T20:21:50.141Z,1765830110.141 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-12-15T20:22:03.495Z,1765830123.495 [Default:A.Wait](INFO): Done Waiting.
2025-12-15T20:22:03.495Z,1765830123.495 [Default:A.Wait] Stopped
2025-12-15T20:22:03.495Z,1765830123.495 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T20:22:03.886Z,1765830123.886 [Default:CheckIn] Running Loop=1
2025-12-15T20:22:03.886Z,1765830123.886 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T20:22:03.886Z,1765830123.886 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T20:22:04.298Z,1765830124.298 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-12-15T20:22:59.613Z,1765830179.613 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T20:23:01.229Z,1765830181.229 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T20:23:15.377Z,1765830195.377 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T20:23:16.580Z,1765830196.580 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T20:23:31.121Z,1765830211.121 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T20:23:31.944Z,1765830211.944 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T20:23:45.674Z,1765830225.674 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-12-15T20:23:46.473Z,1765830226.473 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T20:23:47.296Z,1765830227.296 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T20:24:01.825Z,1765830241.825 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T20:24:03.440Z,1765830243.440 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T20:24:03.455Z,1765830243.455 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-12-15T20:24:03.455Z,1765830243.455 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-12-15T20:24:03.482Z,1765830243.482 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-12-15T20:24:03.896Z,1765830243.896 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-12-15T20:24:03.897Z,1765830243.897 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-12-15T20:24:16.370Z,1765830256.370 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T20:24:17.981Z,1765830257.981 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T20:24:31.717Z,1765830271.717 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T20:24:32.930Z,1765830272.930 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T20:24:45.857Z,1765830285.857 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T20:24:48.283Z,1765830288.283 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T20:25:01.209Z,1765830301.209 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T20:25:03.637Z,1765830303.637 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T20:25:16.161Z,1765830316.161 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T20:25:18.581Z,1765830318.581 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T20:25:18.588Z,1765830318.588 [BPC1](INFO): Calculating totals. Valid battery stick count: 45. Valid reserve battery stick count: 5.
2025-12-15T20:25:18.591Z,1765830318.591 [BPC1](INFO): Received data from all battery sticks.
2025-12-15T20:25:29.449Z,1765830329.449 [DataOverHttps](IMPORTANT): SBD MTMSN=20251215T202529
2025-12-15T20:25:36.704Z,1765830336.704 [DataOverHttps](INFO): Received command: ibit
2025-12-15T20:25:36.733Z,1765830336.733 [CommandExec](IMPORTANT): got command ibit
2025-12-15T20:25:36.796Z,1765830336.796 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-12-15T20:25:36.796Z,1765830336.796 [IBIT](IMPORTANT): Beginning control surface checks.
2025-12-15T20:25:36.800Z,1765830336.800 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-15T20:25:37.705Z,1765830337.705 [DataOverHttps](IMPORTANT): SBD MTMSN=20251215T202537
2025-12-15T20:25:41.304Z,1765830341.304 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007401
CHAN A1 (24V): -0.007289
CHAN A2 (12V): -0.005026
CHAN A3 (5V): -0.003498
CHAN B0 (3.3V): -0.001069
CHAN B1 (3.15aV): -0.001372
CHAN B2 (3.15bV): -0.000652
CHAN B3 (GND): 0.000061
OPEN: 0.004358
Full Scale: +/- 1 mA
2025-12-15T20:25:45.176Z,1765830345.176 [DataOverHttps](INFO): Received command: failc
2025-12-15T20:25:45.177Z,1765830345.177 [CommandExec](IMPORTANT): got command failComponent
2025-12-15T20:25:45.177Z,1765830345.177 [CommandExec](IMPORTANT): Failed components:
2025-12-15T20:25:45.178Z,1765830345.178 [CommandExec](IMPORTANT): No failed Components.
2025-12-15T20:25:52.527Z,1765830352.527 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202551.00,A,3648.17842,N,12147.27923,W,0.194,359.46,151225,,,A*71
2025-12-15T20:25:52.531Z,1765830352.531 [NAL9602](INFO): GPS fix at 20251215T202551: (36.802974, -121.787987)
2025-12-15T20:25:55.354Z,1765830355.354 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202554.00,A,3648.17829,N,12147.27900,W,0.117,359.46,151225,,,A*73
2025-12-15T20:25:55.366Z,1765830355.366 [NAL9602](INFO): GPS fix at 20251215T202554: (36.802971, -121.787983)
2025-12-15T20:25:58.587Z,1765830358.587 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202557.00,A,3648.17813,N,12147.27887,W,0.097,359.46,151225,,,A*7E
2025-12-15T20:25:58.590Z,1765830358.590 [NAL9602](INFO): GPS fix at 20251215T202557: (36.802969, -121.787981)
2025-12-15T20:26:01.419Z,1765830361.419 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202600.00,A,3648.17795,N,12147.27882,W,0.194,359.46,151225,,,A*79
2025-12-15T20:26:01.421Z,1765830361.421 [NAL9602](INFO): GPS fix at 20251215T202600: (36.802966, -121.787980)
2025-12-15T20:26:04.646Z,1765830364.646 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202603.00,A,3648.17787,N,12147.27870,W,0.136,359.46,151225,,,A*7C
2025-12-15T20:26:04.649Z,1765830364.649 [NAL9602](INFO): GPS fix at 20251215T202603: (36.802965, -121.787978)
2025-12-15T20:26:07.479Z,1765830367.479 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202606.00,A,3648.17770,N,12147.27864,W,0.156,359.46,151225,,,A*72
2025-12-15T20:26:07.481Z,1765830367.481 [NAL9602](INFO): GPS fix at 20251215T202606: (36.802962, -121.787977)
2025-12-15T20:26:10.711Z,1765830370.711 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202609.00,A,3648.17752,N,12147.27860,W,0.194,359.46,151225,,,A*77
2025-12-15T20:26:10.721Z,1765830370.721 [NAL9602](INFO): GPS fix at 20251215T202609: (36.802959, -121.787977)
2025-12-15T20:26:13.551Z,1765830373.551 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202612.00,A,3648.17731,N,12147.27858,W,0.097,359.46,151225,,,A*71
2025-12-15T20:26:13.553Z,1765830373.553 [NAL9602](INFO): GPS fix at 20251215T202612: (36.802955, -121.787976)
2025-12-15T20:26:16.367Z,1765830376.367 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202615.00,A,3648.17702,N,12147.27863,W,0.136,359.46,151225,,,A*74
2025-12-15T20:26:16.373Z,1765830376.373 [NAL9602](INFO): GPS fix at 20251215T202615: (36.802950, -121.787977)
2025-12-15T20:26:19.603Z,1765830379.603 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202618.00,A,3648.17677,N,12147.27864,W,0.117,359.46,151225,,,A*7E
2025-12-15T20:26:19.605Z,1765830379.605 [NAL9602](INFO): GPS fix at 20251215T202618: (36.802946, -121.787977)
2025-12-15T20:26:22.443Z,1765830382.443 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202621.00,A,3648.17648,N,12147.27866,W,0.097,359.46,151225,,,A*73
2025-12-15T20:26:22.450Z,1765830382.450 [NAL9602](INFO): GPS fix at 20251215T202621: (36.802941, -121.787978)
2025-12-15T20:26:25.658Z,1765830385.658 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202624.00,A,3648.17626,N,12147.27864,W,0.117,359.46,151225,,,A*75
2025-12-15T20:26:25.661Z,1765830385.661 [NAL9602](INFO): GPS fix at 20251215T202624: (36.802938, -121.787977)
2025-12-15T20:26:28.488Z,1765830388.488 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202627.00,A,3648.17609,N,12147.27860,W,0.097,359.46,151225,,,A*76
2025-12-15T20:26:28.490Z,1765830388.490 [NAL9602](INFO): GPS fix at 20251215T202627: (36.802935, -121.787977)
2025-12-15T20:26:31.314Z,1765830391.314 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202629.00,A,3648.17608,N,12147.27853,W,0.058,359.46,151225,,,A*7A
2025-12-15T20:26:31.317Z,1765830391.317 [NAL9602](INFO): GPS fix at 20251215T202629: (36.802935, -121.787976)
2025-12-15T20:26:34.551Z,1765830394.551 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202633.00,A,3648.17611,N,12147.27846,W,0.058,359.46,151225,,,A*7D
2025-12-15T20:26:34.554Z,1765830394.554 [NAL9602](INFO): GPS fix at 20251215T202633: (36.802935, -121.787974)
2025-12-15T20:26:37.374Z,1765830397.374 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202636.00,A,3648.17616,N,12147.27843,W,0.097,359.46,151225,,,A*79
2025-12-15T20:26:37.376Z,1765830397.376 [NAL9602](INFO): GPS fix at 20251215T202636: (36.802936, -121.787974)
2025-12-15T20:26:40.606Z,1765830400.606 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202639.00,A,3648.17627,N,12147.27845,W,0.117,359.46,151225,,,A*7B
2025-12-15T20:26:40.609Z,1765830400.609 [NAL9602](INFO): GPS fix at 20251215T202639: (36.802938, -121.787974)
2025-12-15T20:26:43.434Z,1765830403.434 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202642.00,A,3648.17647,N,12147.27848,W,0.097,359.46,151225,,,A*75
2025-12-15T20:26:43.446Z,1765830403.446 [NAL9602](INFO): GPS fix at 20251215T202642: (36.802941, -121.787975)
2025-12-15T20:26:46.677Z,1765830406.677 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202645.00,A,3648.17662,N,12147.27854,W,0.078,359.46,151225,,,A*79
2025-12-15T20:26:46.679Z,1765830406.679 [NAL9602](INFO): GPS fix at 20251215T202645: (36.802944, -121.787976)
2025-12-15T20:26:49.503Z,1765830409.503 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202648.00,A,3648.17663,N,12147.27867,W,0.097,359.46,151225,,,A*74
2025-12-15T20:26:49.505Z,1765830409.505 [NAL9602](INFO): GPS fix at 20251215T202648: (36.802944, -121.787978)
2025-12-15T20:26:52.731Z,1765830412.731 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202651.00,A,3648.17664,N,12147.27876,W,0.097,359.46,151225,,,A*7B
2025-12-15T20:26:52.733Z,1765830412.733 [NAL9602](INFO): GPS fix at 20251215T202651: (36.802944, -121.787979)
2025-12-15T20:26:55.555Z,1765830415.555 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202654.00,A,3648.17674,N,12147.27878,W,0.194,359.46,151225,,,A*73
2025-12-15T20:26:55.557Z,1765830415.557 [NAL9602](INFO): GPS fix at 20251215T202654: (36.802946, -121.787980)
2025-12-15T20:26:57.269Z,1765830417.269 [DataOverHttps](IMPORTANT): SBD MTMSN=20251215T202656
2025-12-15T20:26:58.392Z,1765830418.392 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202657.00,A,3648.17685,N,12147.27876,W,0.117,359.46,151225,,,A*7B
2025-12-15T20:26:58.395Z,1765830418.395 [NAL9602](INFO): GPS fix at 20251215T202657: (36.802948, -121.787979)
2025-12-15T20:27:01.634Z,1765830421.634 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202700.00,A,3648.17692,N,12147.27877,W,0.136,359.46,151225,,,A*7C
2025-12-15T20:27:01.640Z,1765830421.640 [NAL9602](INFO): GPS fix at 20251215T202700: (36.802949, -121.787980)
2025-12-15T20:27:04.443Z,1765830424.443 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202703.00,A,3648.17690,N,12147.27869,W,0.214,359.46,151225,,,A*71
2025-12-15T20:27:04.445Z,1765830424.445 [NAL9602](INFO): GPS fix at 20251215T202703: (36.802948, -121.787978)
2025-12-15T20:27:04.660Z,1765830424.660 [DataOverHttps](INFO): Received command: ibit
2025-12-15T20:27:04.753Z,1765830424.753 [CommandExec](IMPORTANT): got command ibit
2025-12-15T20:27:07.675Z,1765830427.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202706.00,A,3648.17707,N,12147.27859,W,0.058,359.46,151225,,,A*72
2025-12-15T20:27:07.677Z,1765830427.677 [NAL9602](INFO): GPS fix at 20251215T202706: (36.802951, -121.787976)
2025-12-15T20:27:10.511Z,1765830430.511 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202709.00,A,3648.17725,N,12147.27846,W,0.136,359.46,151225,,,A*7A
2025-12-15T20:27:10.521Z,1765830430.521 [NAL9602](INFO): GPS fix at 20251215T202709: (36.802954, -121.787974)
2025-12-15T20:27:13.333Z,1765830433.333 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202712.00,A,3648.17713,N,12147.27833,W,0.175,359.46,151225,,,A*70
2025-12-15T20:27:13.335Z,1765830433.335 [NAL9602](INFO): GPS fix at 20251215T202712: (36.802952, -121.787972)
2025-12-15T20:27:16.563Z,1765830436.563 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202715.00,A,3648.17687,N,12147.27822,W,0.058,359.46,151225,,,A*75
2025-12-15T20:27:16.566Z,1765830436.566 [NAL9602](INFO): GPS fix at 20251215T202715: (36.802948, -121.787970)
2025-12-15T20:27:19.390Z,1765830439.390 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202718.00,A,3648.17653,N,12147.27812,W,0.156,359.46,151225,,,A*7D
2025-12-15T20:27:19.392Z,1765830439.392 [NAL9602](INFO): GPS fix at 20251215T202718: (36.802942, -121.787969)
2025-12-15T20:27:22.622Z,1765830442.622 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202721.00,A,3648.17622,N,12147.27806,W,0.214,359.46,151225,,,A*71
2025-12-15T20:27:22.624Z,1765830442.624 [NAL9602](INFO): GPS fix at 20251215T202721: (36.802937, -121.787968)
2025-12-15T20:27:25.450Z,1765830445.450 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202724.00,A,3648.17571,N,12147.27806,W,0.039,359.46,151225,,,A*7C
2025-12-15T20:27:25.452Z,1765830445.452 [NAL9602](INFO): GPS fix at 20251215T202724: (36.802929, -121.787968)
2025-12-15T20:27:27.888Z,1765830447.888 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-12-15T20:27:28.313Z,1765830448.313 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.492001
2025-12-15T20:27:28.314Z,1765830448.314 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-12-15T20:27:28.314Z,1765830448.314 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-12-15T20:27:28.684Z,1765830448.684 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202727.00,A,3648.17513,N,12147.27806,W,0.039,359.46,151225,,,A*7B
2025-12-15T20:27:28.687Z,1765830448.687 [NAL9602](INFO): GPS fix at 20251215T202727: (36.802919, -121.787968)
2025-12-15T20:27:28.717Z,1765830448.717 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-12-15T20:27:28.717Z,1765830448.717 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-12-15T20:27:28.717Z,1765830448.717 [IBIT](IMPORTANT): Pressure:9.604336 PSI
2025-12-15T20:27:28.717Z,1765830448.717 [IBIT](IMPORTANT): Humidity:20.302771 %
2025-12-15T20:27:29.136Z,1765830449.136 [IBIT](IMPORTANT): Vehicle Pitch:-0.825435 degrees
2025-12-15T20:27:29.136Z,1765830449.136 [IBIT](IMPORTANT): Vehicle Roll:4.371098 degrees
2025-12-15T20:27:29.137Z,1765830449.137 [IBIT](IMPORTANT): Vehicle Heading:315.462463 degrees
2025-12-15T20:27:29.508Z,1765830449.508 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-12-15T20:27:29.508Z,1765830449.508 [IBIT](IMPORTANT): buoyancyNeutral: 123.391014 cc
2025-12-15T20:27:29.509Z,1765830449.509 [IBIT](IMPORTANT): massDefault: 0.807847 cm
2025-12-15T20:27:29.509Z,1765830449.509 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-12-15T20:27:29.509Z,1765830449.509 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-12-15T20:27:29.509Z,1765830449.509 [IBIT](IMPORTANT): IBIT FAILED
2025-12-15T20:27:29.908Z,1765830449.908 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-12-15T20:22:03.9Z
2025-12-15T20:27:29.908Z,1765830449.908 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T20:27:29.908Z,1765830449.908 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T20:27:30.326Z,1765830450.326 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-12-15T20:27:37.076Z,1765830457.076 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20251215T201751/Courier0004.lzma
2025-12-15T20:27:38.078Z,1765830458.078 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0004.lzma.bak
2025-12-15T20:27:38.078Z,1765830458.078 [DataOverHttps](INFO): SBD MOMSN=26499114
2025-12-15T20:27:53.729Z,1765830473.729 [DataOverHttps](INFO): Sending 875 bytes from file Logs/20251215T201751/Express0001.lzma
2025-12-15T20:27:54.730Z,1765830474.730 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0001.lzma.bak
2025-12-15T20:27:54.730Z,1765830474.730 [DataOverHttps](INFO): SBD MOMSN=26499120
2025-12-15T20:28:01.010Z,1765830481.010 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T20:28:10.462Z,1765830490.462 [DataOverHttps](INFO): Sending 1537 bytes from file Logs/20251215T201751/Express0005.lzma
2025-12-15T20:28:11.462Z,1765830491.462 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0005.lzma.bak
2025-12-15T20:28:11.462Z,1765830491.462 [DataOverHttps](INFO): SBD MOMSN=26499147
2025-12-15T20:28:27.623Z,1765830507.623 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20251215T201751/Express0008.lzma
2025-12-15T20:28:28.626Z,1765830508.626 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0008.lzma.bak
2025-12-15T20:28:28.626Z,1765830508.626 [DataOverHttps](INFO): SBD MOMSN=26499215
2025-12-15T20:28:29.706Z,1765830509.706 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T20:28:29.706Z,1765830509.706 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T20:28:29.706Z,1765830509.706 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T20:32:10.435Z,1765830730.435 [DataOverHttps](IMPORTANT): SBD MTMSN=20251215T203209
2025-12-15T20:32:17.798Z,1765830737.798 [DataOverHttps](INFO): Received command: ibit
2025-12-15T20:32:17.799Z,1765830737.799 [CommandExec](IMPORTANT): got command ibit
2025-12-15T20:32:17.993Z,1765830737.993 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-12-15T20:32:17.993Z,1765830737.993 [IBIT](IMPORTANT): Beginning control surface checks.
2025-12-15T20:32:17.997Z,1765830737.997 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-15T20:32:19.566Z,1765830739.566 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203218.00,A,3648.14969,N,12147.22508,W,0.019,25.19,151225,,,D*42
2025-12-15T20:32:19.568Z,1765830739.568 [NAL9602](INFO): GPS fix at 20251215T203218: (36.802495, -121.787085)
2025-12-15T20:32:22.479Z,1765830742.479 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006502
CHAN A1 (24V): -0.007851
CHAN A2 (12V): -0.004869
CHAN A3 (5V): -0.003351
CHAN B0 (3.3V): -0.001066
CHAN B1 (3.15aV): -0.001117
CHAN B2 (3.15bV): -0.001823
CHAN B3 (GND): -0.000019
OPEN: 0.004690
Full Scale: +/- 1 mA
2025-12-15T20:32:50.267Z,1765830770.267 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-12-15T20:32:50.350Z,1765830770.350 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-12-15T20:33:04.425Z,1765830784.425 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 36.802494 Longitude: -121.787086
2025-12-15T20:33:04.843Z,1765830784.843 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.492001
2025-12-15T20:33:04.844Z,1765830784.844 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-12-15T20:33:04.844Z,1765830784.844 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-12-15T20:33:05.230Z,1765830785.230 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-12-15T20:33:05.230Z,1765830785.230 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-12-15T20:33:05.231Z,1765830785.231 [IBIT](IMPORTANT): Pressure:9.636807 PSI
2025-12-15T20:33:05.231Z,1765830785.231 [IBIT](IMPORTANT): Humidity:20.192894 %
2025-12-15T20:33:05.632Z,1765830785.632 [IBIT](IMPORTANT): Vehicle Pitch:-0.386518 degrees
2025-12-15T20:33:05.632Z,1765830785.632 [IBIT](IMPORTANT): Vehicle Roll:3.546796 degrees
2025-12-15T20:33:05.632Z,1765830785.632 [IBIT](IMPORTANT): Vehicle Heading:25.514711 degrees
2025-12-15T20:33:06.048Z,1765830786.048 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-12-15T20:33:06.048Z,1765830786.048 [IBIT](IMPORTANT): buoyancyNeutral: 123.391014 cc
2025-12-15T20:33:06.048Z,1765830786.048 [IBIT](IMPORTANT): massDefault: 0.807847 cm
2025-12-15T20:33:06.049Z,1765830786.049 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-12-15T20:33:06.049Z,1765830786.049 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-12-15T20:33:06.049Z,1765830786.049 [IBIT](IMPORTANT): IBIT PASSED
2025-12-15T20:33:09.257Z,1765830789.257 [NAL9602](INFO): SBD MO Status=0, MOMSN=12222, MT Status=0, MTMSN=0
2025-12-15T20:33:09.257Z,1765830789.257 [NAL9602](INFO): No messages in MT queue
2025-12-15T20:33:30.276Z,1765830810.276 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T20:33:30.276Z,1765830810.276 [Default:CheckIn:C.Wait] Stopped
2025-12-15T20:33:30.276Z,1765830810.276 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T20:33:30.276Z,1765830810.276 [Default:CheckIn:D] Running Loop=1
2025-12-15T20:33:30.681Z,1765830810.681 [Default:CheckIn:D] Stopped
2025-12-15T20:33:30.681Z,1765830810.681 [Default:CheckIn:E] Running Loop=1
2025-12-15T20:33:31.080Z,1765830811.080 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.675706 min
2025-12-15T20:33:31.080Z,1765830811.080 [Default:CheckIn:E] Stopped
2025-12-15T20:33:31.080Z,1765830811.080 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T20:33:31.080Z,1765830811.080 [Default:CheckIn] Stopped
2025-12-15T20:33:31.081Z,1765830811.081 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T20:33:31.081Z,1765830811.081 [Default:CheckIn](INFO): Running loop #2
2025-12-15T20:33:31.081Z,1765830811.081 [Default:CheckIn] Running Loop=2
2025-12-15T20:33:31.081Z,1765830811.081 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T20:33:31.081Z,1765830811.081 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T20:33:33.088Z,1765830813.088 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203332.00,A,3648.14713,N,12147.22177,W,0.117,25.19,151225,,,D*4B
2025-12-15T20:33:33.090Z,1765830813.090 [NAL9602](INFO): GPS fix at 20251215T203332: (36.802452, -121.787030)
2025-12-15T20:33:33.100Z,1765830813.100 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T20:33:33.100Z,1765830813.100 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T20:33:40.209Z,1765830820.209 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20251215T201751/Courier0010.lzma
2025-12-15T20:33:41.212Z,1765830821.212 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0010.lzma.bak
2025-12-15T20:33:41.212Z,1765830821.212 [DataOverHttps](INFO): SBD MOMSN=26499327
2025-12-15T20:33:56.903Z,1765830836.903 [DataOverHttps](INFO): Sending 874 bytes from file Logs/20251215T201751/Express0011.lzma
2025-12-15T20:33:57.904Z,1765830837.904 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0011.lzma.bak
2025-12-15T20:33:57.904Z,1765830837.904 [DataOverHttps](INFO): SBD MOMSN=26499330
2025-12-15T20:33:58.963Z,1765830838.963 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T20:33:58.963Z,1765830838.963 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T20:33:58.963Z,1765830838.963 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T20:34:05.406Z,1765830845.406 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T20:38:59.563Z,1765831139.563 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T20:38:59.563Z,1765831139.563 [Default:CheckIn:C.Wait] Stopped
2025-12-15T20:38:59.563Z,1765831139.563 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T20:38:59.563Z,1765831139.563 [Default:CheckIn:D] Running Loop=1
2025-12-15T20:38:59.960Z,1765831139.960 [Default:CheckIn:D] Stopped
2025-12-15T20:38:59.960Z,1765831139.960 [Default:CheckIn:E] Running Loop=1
2025-12-15T20:39:00.364Z,1765831140.364 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.163686 min
2025-12-15T20:39:00.364Z,1765831140.364 [Default:CheckIn:E] Stopped
2025-12-15T20:39:00.364Z,1765831140.364 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T20:39:00.364Z,1765831140.364 [Default:CheckIn] Stopped
2025-12-15T20:39:00.364Z,1765831140.364 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T20:39:00.365Z,1765831140.365 [Default:CheckIn](INFO): Running loop #3
2025-12-15T20:39:00.365Z,1765831140.365 [Default:CheckIn] Running Loop=3
2025-12-15T20:39:00.365Z,1765831140.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T20:39:00.365Z,1765831140.365 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T20:39:02.379Z,1765831142.379 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203901.00,A,3648.14655,N,12147.22311,W,0.078,25.19,151225,,,D*48
2025-12-15T20:39:02.381Z,1765831142.381 [NAL9602](INFO): GPS fix at 20251215T203901: (36.802442, -121.787052)
2025-12-15T20:39:02.391Z,1765831142.391 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T20:39:02.391Z,1765831142.391 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T20:39:09.601Z,1765831149.601 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251215T201751/Courier0016.lzma
2025-12-15T20:39:10.604Z,1765831150.604 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0016.lzma.bak
2025-12-15T20:39:10.604Z,1765831150.604 [DataOverHttps](INFO): SBD MOMSN=26499389
2025-12-15T20:39:14.492Z,1765831154.492 [NAL9602](INFO): SBD MO Status=0, MOMSN=12223, MT Status=0, MTMSN=0
2025-12-15T20:39:14.493Z,1765831154.493 [NAL9602](INFO): No messages in MT queue
2025-12-15T20:39:26.305Z,1765831166.305 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20251215T201751/Express0017.lzma
2025-12-15T20:39:27.308Z,1765831167.308 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0017.lzma.bak
2025-12-15T20:39:27.308Z,1765831167.308 [DataOverHttps](INFO): SBD MOMSN=26499392
2025-12-15T20:39:28.642Z,1765831168.642 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T20:39:28.642Z,1765831168.642 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T20:39:28.642Z,1765831168.642 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T20:39:45.196Z,1765831185.196 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T20:44:29.237Z,1765831469.237 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T20:44:29.237Z,1765831469.237 [Default:CheckIn:C.Wait] Stopped
2025-12-15T20:44:29.237Z,1765831469.237 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T20:44:29.237Z,1765831469.237 [Default:CheckIn:D] Running Loop=1
2025-12-15T20:44:29.641Z,1765831469.641 [Default:CheckIn:D] Stopped
2025-12-15T20:44:29.641Z,1765831469.641 [Default:CheckIn:E] Running Loop=1
2025-12-15T20:44:30.057Z,1765831470.057 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.658364 min
2025-12-15T20:44:30.057Z,1765831470.057 [Default:CheckIn:E] Stopped
2025-12-15T20:44:30.057Z,1765831470.057 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T20:44:30.057Z,1765831470.057 [Default:CheckIn] Stopped
2025-12-15T20:44:30.057Z,1765831470.057 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T20:44:30.057Z,1765831470.057 [Default:CheckIn](INFO): Running loop #4
2025-12-15T20:44:30.057Z,1765831470.057 [Default:CheckIn] Running Loop=4
2025-12-15T20:44:30.058Z,1765831470.058 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T20:44:30.058Z,1765831470.058 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T20:44:32.056Z,1765831472.056 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204431.00,A,3648.14644,N,12147.22335,W,0.019,25.19,151225,,,D*40
2025-12-15T20:44:32.058Z,1765831472.058 [NAL9602](INFO): GPS fix at 20251215T204431: (36.802441, -121.787056)
2025-12-15T20:44:32.068Z,1765831472.068 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T20:44:32.068Z,1765831472.068 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T20:44:38.873Z,1765831478.873 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251215T201751/Courier0019.lzma
2025-12-15T20:44:39.876Z,1765831479.876 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0019.lzma.bak
2025-12-15T20:44:39.876Z,1765831479.876 [DataOverHttps](INFO): SBD MOMSN=26499447
2025-12-15T20:44:55.902Z,1765831495.902 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20251215T201751/Express0020.lzma
2025-12-15T20:44:56.904Z,1765831496.904 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0020.lzma.bak
2025-12-15T20:44:56.904Z,1765831496.904 [DataOverHttps](INFO): SBD MOMSN=26499450
2025-12-15T20:44:57.934Z,1765831497.934 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T20:44:57.934Z,1765831497.934 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T20:44:57.934Z,1765831497.934 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T20:45:16.116Z,1765831516.116 [NAL9602](INFO): SBD MO Status=2, MOMSN=12224, MT Status=2, MTMSN=0
2025-12-15T20:45:16.116Z,1765831516.116 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-15T20:45:25.407Z,1765831525.407 [NAL9602](INFO): SBD MO Status=0, MOMSN=12224, MT Status=0, MTMSN=0
2025-12-15T20:45:25.407Z,1765831525.407 [NAL9602](INFO): No messages in MT queue
2025-12-15T20:45:56.112Z,1765831556.112 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T20:49:58.531Z,1765831798.531 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T20:49:58.531Z,1765831798.531 [Default:CheckIn:C.Wait] Stopped
2025-12-15T20:49:58.531Z,1765831798.531 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T20:49:58.531Z,1765831798.531 [Default:CheckIn:D] Running Loop=1
2025-12-15T20:49:58.929Z,1765831798.929 [Default:CheckIn:D] Stopped
2025-12-15T20:49:58.929Z,1765831798.929 [Default:CheckIn:E] Running Loop=1
2025-12-15T20:49:59.327Z,1765831799.327 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.146499 min
2025-12-15T20:49:59.327Z,1765831799.327 [Default:CheckIn:E] Stopped
2025-12-15T20:49:59.327Z,1765831799.327 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T20:49:59.327Z,1765831799.327 [Default:CheckIn] Stopped
2025-12-15T20:49:59.327Z,1765831799.327 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T20:49:59.328Z,1765831799.328 [Default:CheckIn](INFO): Running loop #5
2025-12-15T20:49:59.328Z,1765831799.328 [Default:CheckIn] Running Loop=5
2025-12-15T20:49:59.328Z,1765831799.328 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T20:49:59.328Z,1765831799.328 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T20:50:01.343Z,1765831801.343 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205000.00,A,3648.14445,N,12147.22101,W,0.097,25.19,151225,,,D*47
2025-12-15T20:50:01.345Z,1765831801.345 [NAL9602](INFO): GPS fix at 20251215T205000: (36.802408, -121.787017)
2025-12-15T20:50:01.382Z,1765831801.382 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T20:50:01.382Z,1765831801.382 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T20:50:08.677Z,1765831808.677 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251215T201751/Courier0022.lzma
2025-12-15T20:50:09.680Z,1765831809.680 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0022.lzma.bak
2025-12-15T20:50:09.680Z,1765831809.680 [DataOverHttps](INFO): SBD MOMSN=26499468
2025-12-15T20:50:25.481Z,1765831825.481 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20251215T201751/Express0023.lzma
2025-12-15T20:50:25.585Z,1765831825.585 [NAL9602](INFO): SBD MO Status=0, MOMSN=12225, MT Status=0, MTMSN=0
2025-12-15T20:50:25.586Z,1765831825.586 [NAL9602](INFO): No messages in MT queue
2025-12-15T20:50:26.484Z,1765831826.484 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0023.lzma.bak
2025-12-15T20:50:26.484Z,1765831826.484 [DataOverHttps](INFO): SBD MOMSN=26499471
2025-12-15T20:50:27.610Z,1765831827.610 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T20:50:27.610Z,1765831827.610 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T20:50:27.610Z,1765831827.610 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T20:50:56.287Z,1765831856.287 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T20:55:28.188Z,1765832128.188 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T20:55:28.188Z,1765832128.188 [Default:CheckIn:C.Wait] Stopped
2025-12-15T20:55:28.188Z,1765832128.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T20:55:28.188Z,1765832128.188 [Default:CheckIn:D] Running Loop=1
2025-12-15T20:55:28.593Z,1765832128.593 [Default:CheckIn:D] Stopped
2025-12-15T20:55:28.593Z,1765832128.593 [Default:CheckIn:E] Running Loop=1
2025-12-15T20:55:29.007Z,1765832129.007 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.640902 min
2025-12-15T20:55:29.007Z,1765832129.007 [Default:CheckIn:E] Stopped
2025-12-15T20:55:29.008Z,1765832129.008 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T20:55:29.008Z,1765832129.008 [Default:CheckIn] Stopped
2025-12-15T20:55:29.008Z,1765832129.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T20:55:29.008Z,1765832129.008 [Default:CheckIn](INFO): Running loop #6
2025-12-15T20:55:29.008Z,1765832129.008 [Default:CheckIn] Running Loop=6
2025-12-15T20:55:29.008Z,1765832129.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T20:55:29.008Z,1765832129.008 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T20:55:31.008Z,1765832131.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205530.00,A,3648.14405,N,12147.21686,W,0.039,25.19,151225,,,D*4A
2025-12-15T20:55:31.010Z,1765832131.010 [NAL9602](INFO): GPS fix at 20251215T205530: (36.802401, -121.786948)
2025-12-15T20:55:31.020Z,1765832131.020 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T20:55:31.020Z,1765832131.020 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T20:55:38.229Z,1765832138.229 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251215T201751/Courier0025.lzma
2025-12-15T20:55:39.232Z,1765832139.232 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0025.lzma.bak
2025-12-15T20:55:39.232Z,1765832139.232 [DataOverHttps](INFO): SBD MOMSN=26499527
2025-12-15T20:55:41.924Z,1765832141.924 [NAL9602](INFO): SBD MO Status=0, MOMSN=12226, MT Status=0, MTMSN=0
2025-12-15T20:55:41.924Z,1765832141.924 [NAL9602](INFO): No messages in MT queue
2025-12-15T20:55:54.949Z,1765832154.949 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20251215T201751/Express0026.lzma
2025-12-15T20:55:55.952Z,1765832155.952 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0026.lzma.bak
2025-12-15T20:55:55.952Z,1765832155.952 [DataOverHttps](INFO): SBD MOMSN=26499530
2025-12-15T20:55:57.289Z,1765832157.289 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T20:55:57.289Z,1765832157.289 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T20:55:57.289Z,1765832157.289 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T20:56:12.623Z,1765832172.623 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T21:00:57.904Z,1765832457.904 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T21:00:57.904Z,1765832457.904 [Default:CheckIn:C.Wait] Stopped
2025-12-15T21:00:57.904Z,1765832457.904 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T21:00:57.904Z,1765832457.904 [Default:CheckIn:D] Running Loop=1
2025-12-15T21:00:58.311Z,1765832458.311 [Default:CheckIn:D] Stopped
2025-12-15T21:00:58.311Z,1765832458.311 [Default:CheckIn:E] Running Loop=1
2025-12-15T21:00:58.704Z,1765832458.704 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.136202 min
2025-12-15T21:00:58.704Z,1765832458.704 [Default:CheckIn:E] Stopped
2025-12-15T21:00:58.704Z,1765832458.704 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T21:00:58.704Z,1765832458.704 [Default:CheckIn] Stopped
2025-12-15T21:00:58.705Z,1765832458.705 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T21:00:58.705Z,1765832458.705 [Default:CheckIn](INFO): Running loop #7
2025-12-15T21:00:58.705Z,1765832458.705 [Default:CheckIn] Running Loop=7
2025-12-15T21:00:58.705Z,1765832458.705 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T21:00:58.705Z,1765832458.705 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T21:01:00.721Z,1765832460.721 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210059.00,A,3648.14798,N,12147.22449,W,0.078,25.19,151225,,,D*44
2025-12-15T21:01:00.723Z,1765832460.723 [NAL9602](INFO): GPS fix at 20251215T210059: (36.802466, -121.787075)
2025-12-15T21:01:00.734Z,1765832460.734 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T21:01:00.734Z,1765832460.734 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T21:01:08.061Z,1765832468.061 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251215T201751/Courier0028.lzma
2025-12-15T21:01:09.064Z,1765832469.064 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0028.lzma.bak
2025-12-15T21:01:09.064Z,1765832469.064 [DataOverHttps](INFO): SBD MOMSN=26499548
2025-12-15T21:01:24.805Z,1765832484.805 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20251215T201751/Express0029.lzma
2025-12-15T21:01:25.816Z,1765832485.816 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0029.lzma.bak
2025-12-15T21:01:25.816Z,1765832485.816 [DataOverHttps](INFO): SBD MOMSN=26499551
2025-12-15T21:01:26.984Z,1765832486.984 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T21:01:26.984Z,1765832486.984 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T21:01:26.984Z,1765832486.984 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T21:01:32.232Z,1765832492.232 [NAL9602](INFO): SBD MO Status=2, MOMSN=12227, MT Status=2, MTMSN=0
2025-12-15T21:01:32.232Z,1765832492.232 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-15T21:02:30.004Z,1765832550.004 [NAL9602](INFO): SBD MO Status=2, MOMSN=12227, MT Status=2, MTMSN=0
2025-12-15T21:02:30.004Z,1765832550.004 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-15T21:03:00.703Z,1765832580.703 [NAL9602](INFO): SBD MO Status=2, MOMSN=12227, MT Status=2, MTMSN=0
2025-12-15T21:03:00.704Z,1765832580.704 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-15T21:03:16.864Z,1765832596.864 [NAL9602](INFO): SBD MO Status=2, MOMSN=12227, MT Status=2, MTMSN=0
2025-12-15T21:03:16.865Z,1765832596.865 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-15T21:03:51.612Z,1765832631.612 [NAL9602](INFO): SBD MO Status=0, MOMSN=12227, MT Status=0, MTMSN=0
2025-12-15T21:03:51.612Z,1765832631.612 [NAL9602](INFO): No messages in MT queue
2025-12-15T21:04:22.315Z,1765832662.315 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T21:06:27.569Z,1765832787.569 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T21:06:27.569Z,1765832787.569 [Default:CheckIn:C.Wait] Stopped
2025-12-15T21:06:27.569Z,1765832787.569 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T21:06:27.570Z,1765832787.570 [Default:CheckIn:D] Running Loop=1
2025-12-15T21:06:27.968Z,1765832787.968 [Default:CheckIn:D] Stopped
2025-12-15T21:06:27.968Z,1765832787.968 [Default:CheckIn:E] Running Loop=1
2025-12-15T21:06:28.372Z,1765832788.372 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.630489 min
2025-12-15T21:06:28.372Z,1765832788.372 [Default:CheckIn:E] Stopped
2025-12-15T21:06:28.372Z,1765832788.372 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T21:06:28.372Z,1765832788.372 [Default:CheckIn] Stopped
2025-12-15T21:06:28.372Z,1765832788.372 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T21:06:28.373Z,1765832788.373 [Default:CheckIn](INFO): Running loop #8
2025-12-15T21:06:28.373Z,1765832788.373 [Default:CheckIn] Running Loop=8
2025-12-15T21:06:28.373Z,1765832788.373 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T21:06:28.373Z,1765832788.373 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T21:06:30.381Z,1765832790.381 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210629.00,A,3648.14822,N,12147.22458,W,0.039,25.19,151225,,,D*4E
2025-12-15T21:06:30.384Z,1765832790.384 [NAL9602](INFO): GPS fix at 20251215T210629: (36.802470, -121.787076)
2025-12-15T21:06:30.394Z,1765832790.394 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T21:06:30.394Z,1765832790.394 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T21:06:37.629Z,1765832797.629 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251215T201751/Courier0031.lzma
2025-12-15T21:06:38.632Z,1765832798.632 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0031.lzma.bak
2025-12-15T21:06:38.632Z,1765832798.632 [DataOverHttps](INFO): SBD MOMSN=26499601
2025-12-15T21:06:43.716Z,1765832803.716 [NAL9602](INFO): SBD MO Status=0, MOMSN=12228, MT Status=0, MTMSN=0
2025-12-15T21:06:43.717Z,1765832803.717 [NAL9602](INFO): No messages in MT queue
2025-12-15T21:06:54.433Z,1765832814.433 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20251215T201751/Express0032.lzma
2025-12-15T21:06:55.436Z,1765832815.436 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0032.lzma.bak
2025-12-15T21:06:55.436Z,1765832815.436 [DataOverHttps](INFO): SBD MOMSN=26499604
2025-12-15T21:06:56.648Z,1765832816.648 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T21:06:56.648Z,1765832816.648 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T21:06:56.648Z,1765832816.648 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T21:07:14.423Z,1765832834.423 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T21:11:57.226Z,1765833117.226 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T21:11:57.226Z,1765833117.226 [Default:CheckIn:C.Wait] Stopped
2025-12-15T21:11:57.226Z,1765833117.226 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T21:11:57.226Z,1765833117.226 [Default:CheckIn:D] Running Loop=1
2025-12-15T21:11:57.635Z,1765833117.635 [Default:CheckIn:D] Stopped
2025-12-15T21:11:57.635Z,1765833117.635 [Default:CheckIn:E] Running Loop=1
2025-12-15T21:11:58.036Z,1765833118.036 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.124943 min
2025-12-15T21:11:58.036Z,1765833118.036 [Default:CheckIn:E] Stopped
2025-12-15T21:11:58.036Z,1765833118.036 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T21:11:58.036Z,1765833118.036 [Default:CheckIn] Stopped
2025-12-15T21:11:58.037Z,1765833118.037 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T21:11:58.037Z,1765833118.037 [Default:CheckIn](INFO): Running loop #9
2025-12-15T21:11:58.037Z,1765833118.037 [Default:CheckIn] Running Loop=9
2025-12-15T21:11:58.037Z,1765833118.037 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T21:11:58.037Z,1765833118.037 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T21:12:00.049Z,1765833120.049 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211159.00,A,3648.14707,N,12147.22446,W,0.117,322.21,151225,,,D*7A
2025-12-15T21:12:00.051Z,1765833120.051 [NAL9602](INFO): GPS fix at 20251215T211159: (36.802451, -121.787074)
2025-12-15T21:12:00.061Z,1765833120.061 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T21:12:00.061Z,1765833120.061 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T21:12:07.067Z,1765833127.067 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251215T201751/Courier0034.lzma
2025-12-15T21:12:08.020Z,1765833128.020 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0034.lzma.bak
2025-12-15T21:12:08.020Z,1765833128.020 [DataOverHttps](INFO): SBD MOMSN=26499624
2025-12-15T21:12:21.861Z,1765833141.861 [NAL9602](INFO): SBD MO Status=2, MOMSN=12229, MT Status=2, MTMSN=0
2025-12-15T21:12:21.861Z,1765833141.861 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-15T21:12:24.101Z,1765833144.101 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20251215T201751/Express0035.lzma
2025-12-15T21:12:25.104Z,1765833145.104 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0035.lzma.bak
2025-12-15T21:12:25.104Z,1765833145.104 [DataOverHttps](INFO): SBD MOMSN=26499627
2025-12-15T21:12:26.333Z,1765833146.333 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T21:12:26.333Z,1765833146.333 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T21:12:26.334Z,1765833146.334 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T21:13:03.884Z,1765833183.884 [NAL9602](INFO): SBD MO Status=0, MOMSN=12229, MT Status=0, MTMSN=0
2025-12-15T21:13:03.885Z,1765833183.885 [NAL9602](INFO): No messages in MT queue
2025-12-15T21:13:34.578Z,1765833214.578 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T21:17:26.905Z,1765833446.905 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T21:17:26.905Z,1765833446.905 [Default:CheckIn:C.Wait] Stopped
2025-12-15T21:17:26.905Z,1765833446.905 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T21:17:26.905Z,1765833446.905 [Default:CheckIn:D] Running Loop=1
2025-12-15T21:17:27.292Z,1765833447.292 [Default:CheckIn:D] Stopped
2025-12-15T21:17:27.292Z,1765833447.292 [Default:CheckIn:E] Running Loop=1
2025-12-15T21:17:27.697Z,1765833447.697 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.619218 min
2025-12-15T21:17:27.697Z,1765833447.697 [Default:CheckIn:E] Stopped
2025-12-15T21:17:27.697Z,1765833447.697 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T21:17:27.697Z,1765833447.697 [Default:CheckIn] Stopped
2025-12-15T21:17:27.697Z,1765833447.697 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T21:17:27.697Z,1765833447.697 [Default:CheckIn](INFO): Running loop #10
2025-12-15T21:17:27.697Z,1765833447.697 [Default:CheckIn] Running Loop=10
2025-12-15T21:17:27.698Z,1765833447.698 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T21:17:27.698Z,1765833447.698 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T21:17:29.710Z,1765833449.710 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211729.99,A,3648.15010,N,12147.21944,W,0.019,87.85,151225,,,D*4A
2025-12-15T21:17:29.712Z,1765833449.712 [NAL9602](INFO): GPS fix at 20251215T211729: (36.802502, -121.786991)
2025-12-15T21:17:29.722Z,1765833449.722 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T21:17:29.722Z,1765833449.722 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T21:17:36.729Z,1765833456.729 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251215T201751/Courier0037.lzma
2025-12-15T21:17:37.732Z,1765833457.732 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0037.lzma.bak
2025-12-15T21:17:37.732Z,1765833457.732 [DataOverHttps](INFO): SBD MOMSN=26499678
2025-12-15T21:17:53.577Z,1765833473.577 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20251215T201751/Express0038.lzma
2025-12-15T21:17:54.580Z,1765833474.580 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0038.lzma.bak
2025-12-15T21:17:54.580Z,1765833474.580 [DataOverHttps](INFO): SBD MOMSN=26499681
2025-12-15T21:17:55.976Z,1765833475.976 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T21:17:55.976Z,1765833475.976 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T21:17:55.977Z,1765833475.977 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T21:18:06.880Z,1765833486.880 [NAL9602](INFO): SBD MO Status=2, MOMSN=12230, MT Status=2, MTMSN=0
2025-12-15T21:18:06.880Z,1765833486.880 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-15T21:19:06.677Z,1765833546.677 [NAL9602](INFO): SBD MO Status=0, MOMSN=12230, MT Status=0, MTMSN=0
2025-12-15T21:19:06.677Z,1765833546.677 [NAL9602](INFO): No messages in MT queue
2025-12-15T21:19:37.371Z,1765833577.371 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T21:22:56.556Z,1765833776.556 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T21:22:56.556Z,1765833776.556 [Default:CheckIn:C.Wait] Stopped
2025-12-15T21:22:56.556Z,1765833776.556 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T21:22:56.556Z,1765833776.556 [Default:CheckIn:D] Running Loop=1
2025-12-15T21:22:56.955Z,1765833776.955 [Default:CheckIn:D] Stopped
2025-12-15T21:22:56.955Z,1765833776.955 [Default:CheckIn:E] Running Loop=1
2025-12-15T21:22:57.369Z,1765833777.369 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.113607 min
2025-12-15T21:22:57.369Z,1765833777.369 [Default:CheckIn:E] Stopped
2025-12-15T21:22:57.369Z,1765833777.369 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T21:22:57.369Z,1765833777.369 [Default:CheckIn] Stopped
2025-12-15T21:22:57.369Z,1765833777.369 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T21:22:57.370Z,1765833777.370 [Default:CheckIn](INFO): Running loop #11
2025-12-15T21:22:57.370Z,1765833777.370 [Default:CheckIn] Running Loop=11
2025-12-15T21:22:57.370Z,1765833777.370 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T21:22:57.370Z,1765833777.370 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T21:22:59.373Z,1765833779.373 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212258.00,A,3648.14843,N,12147.21888,W,0.058,87.85,151225,,,D*41
2025-12-15T21:22:59.383Z,1765833779.383 [NAL9602](INFO): GPS fix at 20251215T212258: (36.802474, -121.786981)
2025-12-15T21:22:59.393Z,1765833779.393 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T21:22:59.394Z,1765833779.394 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T21:23:06.181Z,1765833786.181 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251215T201751/Courier0040.lzma
2025-12-15T21:23:07.184Z,1765833787.184 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0040.lzma.bak
2025-12-15T21:23:07.184Z,1765833787.184 [DataOverHttps](INFO): SBD MOMSN=26499703
2025-12-15T21:23:23.093Z,1765833803.093 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20251215T201751/Express0041.lzma
2025-12-15T21:23:24.096Z,1765833804.096 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0041.lzma.bak
2025-12-15T21:23:24.096Z,1765833804.096 [DataOverHttps](INFO): SBD MOMSN=26499714
2025-12-15T21:23:25.245Z,1765833805.245 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T21:23:25.245Z,1765833805.245 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T21:23:25.245Z,1765833805.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T21:23:27.249Z,1765833807.249 [NAL9602](INFO): SBD MO Status=0, MOMSN=12231, MT Status=0, MTMSN=0
2025-12-15T21:23:27.249Z,1765833807.249 [NAL9602](INFO): No messages in MT queue
2025-12-15T21:23:57.950Z,1765833837.950 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T21:28:25.879Z,1765834105.879 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T21:28:25.879Z,1765834105.879 [Default:CheckIn:C.Wait] Stopped
2025-12-15T21:28:25.879Z,1765834105.879 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T21:28:25.879Z,1765834105.879 [Default:CheckIn:D] Running Loop=1
2025-12-15T21:28:26.242Z,1765834106.242 [Default:CheckIn:D] Stopped
2025-12-15T21:28:26.242Z,1765834106.242 [Default:CheckIn:E] Running Loop=1
2025-12-15T21:28:26.647Z,1765834106.647 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.601721 min
2025-12-15T21:28:26.647Z,1765834106.647 [Default:CheckIn:E] Stopped
2025-12-15T21:28:26.647Z,1765834106.647 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T21:28:26.647Z,1765834106.647 [Default:CheckIn] Stopped
2025-12-15T21:28:26.647Z,1765834106.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T21:28:26.647Z,1765834106.647 [Default:CheckIn](INFO): Running loop #12
2025-12-15T21:28:26.647Z,1765834106.647 [Default:CheckIn] Running Loop=12
2025-12-15T21:28:26.648Z,1765834106.648 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T21:28:26.648Z,1765834106.648 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T21:28:28.646Z,1765834108.646 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212827.00,A,3648.30389,N,12147.12379,W,5.443,11.10,151225,,,D*45
2025-12-15T21:28:28.648Z,1765834108.648 [NAL9602](INFO): GPS fix at 20251215T212827: (36.805065, -121.785397)
2025-12-15T21:28:28.675Z,1765834108.675 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T21:28:28.675Z,1765834108.675 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T21:28:36.061Z,1765834116.061 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251215T201751/Courier0043.lzma
2025-12-15T21:28:37.064Z,1765834117.064 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0043.lzma.bak
2025-12-15T21:28:37.064Z,1765834117.064 [DataOverHttps](INFO): SBD MOMSN=26499774
2025-12-15T21:28:52.946Z,1765834132.946 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20251215T201751/Express0044.lzma
2025-12-15T21:28:53.948Z,1765834133.948 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0044.lzma.bak
2025-12-15T21:28:53.948Z,1765834133.948 [DataOverHttps](INFO): SBD MOMSN=26499777
2025-12-15T21:28:55.315Z,1765834135.315 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T21:28:55.315Z,1765834135.315 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T21:28:55.315Z,1765834135.315 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T21:29:10.268Z,1765834150.268 [NAL9602](INFO): SBD MO Status=2, MOMSN=12232, MT Status=2, MTMSN=0
2025-12-15T21:29:10.269Z,1765834150.269 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-15T21:30:08.429Z,1765834208.429 [NAL9602](INFO): SBD MO Status=2, MOMSN=12232, MT Status=2, MTMSN=0
2025-12-15T21:30:08.429Z,1765834208.429 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-15T21:30:14.485Z,1765834214.485 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T21:30:16.508Z,1765834216.508 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T21:30:21.355Z,1765834221.355 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T21:30:31.856Z,1765834231.856 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T21:30:36.300Z,1765834236.300 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T21:30:46.805Z,1765834246.805 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T21:30:51.248Z,1765834251.248 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T21:30:52.469Z,1765834252.469 [NAL9602](INFO): SBD MO Status=0, MOMSN=12232, MT Status=0, MTMSN=0
2025-12-15T21:30:52.469Z,1765834252.469 [NAL9602](INFO): No messages in MT queue
2025-12-15T21:31:01.349Z,1765834261.349 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T21:31:06.600Z,1765834266.600 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T21:31:16.296Z,1765834276.296 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T21:31:21.952Z,1765834281.952 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T21:31:23.167Z,1765834283.167 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T21:31:31.649Z,1765834291.649 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T21:31:37.304Z,1765834297.304 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T21:31:47.003Z,1765834307.003 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T21:31:52.255Z,1765834312.255 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T21:32:01.544Z,1765834321.544 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T21:32:07.200Z,1765834327.200 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T21:32:16.493Z,1765834336.493 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T21:32:22.552Z,1765834342.552 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-12-15T21:32:31.043Z,1765834351.043 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-12-15T21:32:37.913Z,1765834357.913 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5.
2025-12-15T21:32:37.916Z,1765834357.916 [BPC1](INFO): Received data from all battery sticks.
2025-12-15T21:33:55.898Z,1765834435.898 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T21:33:55.898Z,1765834435.898 [Default:CheckIn:C.Wait] Stopped
2025-12-15T21:33:55.898Z,1765834435.898 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T21:33:55.898Z,1765834435.898 [Default:CheckIn:D] Running Loop=1
2025-12-15T21:33:56.291Z,1765834436.291 [Default:CheckIn:D] Stopped
2025-12-15T21:33:56.291Z,1765834436.291 [Default:CheckIn:E] Running Loop=1
2025-12-15T21:33:56.700Z,1765834436.700 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.102539 min
2025-12-15T21:33:56.700Z,1765834436.700 [Default:CheckIn:E] Stopped
2025-12-15T21:33:56.700Z,1765834436.700 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T21:33:56.700Z,1765834436.700 [Default:CheckIn] Stopped
2025-12-15T21:33:56.700Z,1765834436.700 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T21:33:56.701Z,1765834436.701 [Default:CheckIn](INFO): Running loop #13
2025-12-15T21:33:56.701Z,1765834436.701 [Default:CheckIn] Running Loop=13
2025-12-15T21:33:56.701Z,1765834436.701 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T21:33:56.701Z,1765834436.701 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T21:33:58.717Z,1765834438.717 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213358.00,A,3647.97988,N,12148.26528,W,29.663,223.17,151225,,,D*4E
2025-12-15T21:33:58.719Z,1765834438.719 [NAL9602](INFO): GPS fix at 20251215T213358: (36.799665, -121.804421)
2025-12-15T21:33:58.729Z,1765834438.729 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T21:33:58.729Z,1765834438.729 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T21:34:06.114Z,1765834446.114 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20251215T201751/Courier0046.lzma
2025-12-15T21:34:07.116Z,1765834447.116 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0046.lzma.bak
2025-12-15T21:34:07.116Z,1765834447.116 [DataOverHttps](INFO): SBD MOMSN=26499819
2025-12-15T21:34:18.537Z,1765834458.537 [NAL9602](INFO): SBD MO Status=2, MOMSN=12233, MT Status=2, MTMSN=0
2025-12-15T21:34:18.537Z,1765834458.537 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-15T21:34:22.917Z,1765834462.917 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20251215T201751/Express0047.lzma
2025-12-15T21:34:23.920Z,1765834463.920 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0047.lzma.bak
2025-12-15T21:34:23.920Z,1765834463.920 [DataOverHttps](INFO): SBD MOMSN=26499822
2025-12-15T21:34:24.976Z,1765834464.976 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T21:34:24.976Z,1765834464.976 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T21:34:24.977Z,1765834464.977 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T21:35:25.980Z,1765834525.980 [NAL9602](INFO): SBD MO Status=0, MOMSN=12233, MT Status=0, MTMSN=0
2025-12-15T21:35:25.980Z,1765834525.980 [NAL9602](INFO): No messages in MT queue
2025-12-15T21:35:56.679Z,1765834556.679 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T21:39:25.574Z,1765834765.574 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-15T21:39:25.574Z,1765834765.574 [Default:CheckIn:C.Wait] Stopped
2025-12-15T21:39:25.574Z,1765834765.574 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-15T21:39:25.574Z,1765834765.574 [Default:CheckIn:D] Running Loop=1
2025-12-15T21:39:25.971Z,1765834765.971 [Default:CheckIn:D] Stopped
2025-12-15T21:39:25.971Z,1765834765.971 [Default:CheckIn:E] Running Loop=1
2025-12-15T21:39:26.393Z,1765834766.393 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.597209 min
2025-12-15T21:39:26.393Z,1765834766.393 [Default:CheckIn:E] Stopped
2025-12-15T21:39:26.393Z,1765834766.393 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-15T21:39:26.393Z,1765834766.393 [Default:CheckIn] Stopped
2025-12-15T21:39:26.393Z,1765834766.393 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-15T21:39:26.394Z,1765834766.394 [Default:CheckIn](INFO): Running loop #14
2025-12-15T21:39:26.394Z,1765834766.394 [Default:CheckIn] Running Loop=14
2025-12-15T21:39:26.394Z,1765834766.394 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-15T21:39:26.394Z,1765834766.394 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-15T21:39:28.373Z,1765834768.373 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213927.00,A,3647.74097,N,12150.89970,W,1.341,100.72,151225,,,D*75
2025-12-15T21:39:28.376Z,1765834768.376 [NAL9602](INFO): GPS fix at 20251215T213927: (36.795683, -121.848328)
2025-12-15T21:39:28.385Z,1765834768.385 [Default:CheckIn:Read_GPS] Stopped
2025-12-15T21:39:28.386Z,1765834768.386 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-15T21:39:35.985Z,1765834775.985 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251215T201751/Courier0049.lzma
2025-12-15T21:39:36.988Z,1765834776.988 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Courier0049.lzma.bak
2025-12-15T21:39:36.988Z,1765834776.988 [DataOverHttps](INFO): SBD MOMSN=26499904
2025-12-15T21:39:42.109Z,1765834782.109 [NAL9602](INFO): SBD MO Status=0, MOMSN=12234, MT Status=0, MTMSN=0
2025-12-15T21:39:42.109Z,1765834782.109 [NAL9602](INFO): No messages in MT queue
2025-12-15T21:40:07.850Z,1765834807.850 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20251215T201751/Express0050.lzma
2025-12-15T21:40:12.815Z,1765834812.815 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T21:40:17.886Z,1765834817.886 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-12-15T21:40:47.343Z,1765834847.343 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.915775
2025-12-15T21:40:54.694Z,1765834854.694 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002594
2025-12-15T21:41:02.105Z,1765834862.105 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20251215T201751/Express0050.lzma
2025-12-15T21:41:03.108Z,1765834863.108 [DataOverHttps](INFO): Moved sent file to Logs/20251215T201751/Express0050.lzma.bak
2025-12-15T21:41:03.108Z,1765834863.108 [DataOverHttps](INFO): SBD MOMSN=26499908
2025-12-15T21:41:04.169Z,1765834864.169 [Default:CheckIn:Read_Iridium] Stopped
2025-12-15T21:41:04.169Z,1765834864.169 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-15T21:41:04.169Z,1765834864.169 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-15T21:41:15.875Z,1765834875.875 [NAL9602](INFO): SBD MO Status=1, MOMSN=12235, MT Status=0, MTMSN=0
2025-12-15T21:41:15.926Z,1765834875.926 [NAL9602](INFO): Sent 169 bytes from file Logs/20251215T201751/Express0050.lzma
2025-12-15T21:41:15.926Z,1765834875.926 [NAL9602](INFO): Packets left to send: 0
2025-12-15T21:41:46.580Z,1765834906.580 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-15T21:42:23.079Z,1765834943.079 [DataOverHttps](IMPORTANT): SBD MTMSN=20251215T214222
2025-12-15T21:42:30.497Z,1765834950.497 [DataOverHttps](INFO): Received command: restart logs