2025-09-09T21:30:21.284Z,1757453421.284 [Supervisor](DEBUG): Initializing supervisor.
2025-09-09T21:30:21.288Z,1757453421.288 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-09-09T21:30:21.288Z,1757453421.288 [SyncHandler](INFO): Protected caller Thread ID is 839
2025-09-09T21:30:21.289Z,1757453421.289 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-09-09T21:30:21.290Z,1757453421.290 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-09-09T21:30:21.290Z,1757453421.290 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 840
2025-09-09T21:30:21.294Z,1757453421.294 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-09-09T21:30:21.315Z,1757453421.315 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-09-09T21:30:21.315Z,1757453421.315 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-09-09T21:30:21.316Z,1757453421.316 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 841
2025-09-09T21:30:21.320Z,1757453421.320 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-09-09T21:30:21.321Z,1757453421.321 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-09-09T21:30:21.322Z,1757453421.322 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 842
2025-09-09T21:30:21.324Z,1757453421.324 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-09-09T21:30:21.325Z,1757453421.325 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-09-09T21:30:21.325Z,1757453421.325 [logger ThreadHandler](INFO): Protected caller Thread ID is 843
2025-09-09T21:30:21.329Z,1757453421.329 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-09-09T21:30:21.329Z,1757453421.329 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-09-09T21:30:21.334Z,1757453421.334 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-09-09T21:30:21.440Z,1757453421.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-09-09T21:30:21.442Z,1757453421.442 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-09-09T21:30:22.188Z,1757453422.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-09-09T21:30:22.190Z,1757453422.190 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-09-09T21:30:22.446Z,1757453422.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-09-09T21:30:22.448Z,1757453422.448 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-09-09T21:30:22.537Z,1757453422.537 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-09-09T21:30:22.678Z,1757453422.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-09-09T21:30:22.680Z,1757453422.680 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-09-09T21:30:22.782Z,1757453422.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-09-09T21:30:22.782Z,1757453422.782 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-09-09T21:30:22.901Z,1757453422.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-09-09T21:30:22.903Z,1757453422.903 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-09-09T21:30:23.328Z,1757453423.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-09-09T21:30:23.329Z,1757453423.329 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-09-09T21:30:23.872Z,1757453423.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-09-09T21:30:23.874Z,1757453423.874 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-09-09T21:30:24.393Z,1757453424.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-09-09T21:30:24.394Z,1757453424.394 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-09-09T21:30:24.723Z,1757453424.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-09-09T21:30:24.724Z,1757453424.724 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-09-09T21:30:25.471Z,1757453425.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-09-09T21:30:25.473Z,1757453425.473 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-09-09T21:30:25.773Z,1757453425.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-09-09T21:30:25.775Z,1757453425.775 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-09-09T21:30:26.024Z,1757453426.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-09-09T21:30:26.026Z,1757453426.026 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-09-09T21:30:26.424Z,1757453426.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-09-09T21:30:26.426Z,1757453426.426 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-09-09T21:30:26.785Z,1757453426.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-09-09T21:30:26.787Z,1757453426.787 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2025-09-09T21:30:26.790Z,1757453426.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2025-09-09T21:30:26.884Z,1757453426.884 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2025-09-09T21:30:27.065Z,1757453427.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg
2025-09-09T21:30:27.181Z,1757453427.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2025-09-09T21:30:27.287Z,1757453427.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2025-09-09T21:30:27.402Z,1757453427.402 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2025-09-09T21:30:27.497Z,1757453427.497 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2025-09-09T21:30:27.601Z,1757453427.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2025-09-09T21:30:27.722Z,1757453427.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2025-09-09T21:30:28.004Z,1757453428.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-09-09T21:30:28.005Z,1757453428.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2025-09-09T21:30:28.098Z,1757453428.098 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2025-09-09T21:30:28.200Z,1757453428.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2025-09-09T21:30:28.349Z,1757453428.349 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2025-09-09T21:30:28.454Z,1757453428.454 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-09-09T21:30:28.475Z,1757453428.475 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-09-09T21:30:28.653Z,1757453428.653 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-09-09T21:30:28.653Z,1757453428.653 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-09-09T21:30:28.727Z,1757453428.727 [VerticalControl](DEBUG): Construct VerticalControl.
2025-09-09T21:30:28.791Z,1757453428.791 [VerticalControl] Loaded
2025-09-09T21:30:28.792Z,1757453428.792 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-09-09T21:30:28.794Z,1757453428.794 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-09-09T21:30:28.845Z,1757453428.845 [HorizontalControl] Loaded
2025-09-09T21:30:28.845Z,1757453428.845 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-09-09T21:30:28.848Z,1757453428.848 [SpeedControl](DEBUG): Construct SpeedControl.
2025-09-09T21:30:28.851Z,1757453428.851 [SpeedControl] Loaded
2025-09-09T21:30:28.852Z,1757453428.852 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-09-09T21:30:28.854Z,1757453428.854 [LoopControl](DEBUG): Construct LoopControl.
2025-09-09T21:30:28.855Z,1757453428.855 [LoopControl] Loaded
2025-09-09T21:30:28.855Z,1757453428.855 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-09-09T21:30:28.856Z,1757453428.856 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-09-09T21:30:28.856Z,1757453428.856 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-09-09T21:30:28.928Z,1757453428.928 [DepthRateCalculator] Loaded
2025-09-09T21:30:28.928Z,1757453428.928 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-09-09T21:30:28.933Z,1757453428.933 [PitchRateCalculator] Loaded
2025-09-09T21:30:28.933Z,1757453428.933 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-09-09T21:30:28.944Z,1757453428.944 [SpeedCalculator] Loaded
2025-09-09T21:30:28.944Z,1757453428.944 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-09-09T21:30:28.949Z,1757453428.949 [YawRateCalculator] Loaded
2025-09-09T21:30:28.949Z,1757453428.949 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-09-09T21:30:28.968Z,1757453428.968 [ElevatorOffsetCalculator] Loaded
2025-09-09T21:30:28.968Z,1757453428.968 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-09-09T21:30:28.969Z,1757453428.969 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-09-09T21:30:28.971Z,1757453428.971 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-09-09T21:30:29.020Z,1757453429.020 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-09-09T21:30:29.021Z,1757453429.021 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-09-09T21:30:29.109Z,1757453429.109 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-09-09T21:30:29.110Z,1757453429.110 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-09-09T21:30:29.825Z,1757453429.825 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-09-09T21:30:30.117Z,1757453430.117 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-09-09T21:30:30.461Z,1757453430.461 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-09-09T21:30:30.463Z,1757453430.463 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-09-09T21:30:31.320Z,1757453431.320 [AHRS_M2] Loaded
2025-09-09T21:30:31.320Z,1757453431.320 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-09-09T21:30:31.574Z,1757453431.574 [BackseatComponent] Loaded
2025-09-09T21:30:31.574Z,1757453431.574 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-09-09T21:30:31.575Z,1757453431.575 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409EA4E0
2025-09-09T21:30:31.576Z,1757453431.576 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 931
2025-09-09T21:30:31.578Z,1757453431.578 [LcmUniversalReporter] Loaded
2025-09-09T21:30:31.579Z,1757453431.579 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-09-09T21:30:32.430Z,1757453432.430 [BPC1] Loaded
2025-09-09T21:30:32.430Z,1757453432.430 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-09-09T21:30:32.531Z,1757453432.531 [DAT] Loaded
2025-09-09T21:30:32.532Z,1757453432.532 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-09-09T21:30:32.533Z,1757453432.533 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A1A4E0
2025-09-09T21:30:32.533Z,1757453432.533 [DAT ThreadHandler](INFO): Protected caller Thread ID is 932
2025-09-09T21:30:32.674Z,1757453432.674 [DataOverHttps] Loaded
2025-09-09T21:30:32.674Z,1757453432.674 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-09-09T21:30:32.675Z,1757453432.675 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A4A4E0
2025-09-09T21:30:32.676Z,1757453432.676 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 933
2025-09-09T21:30:32.700Z,1757453432.700 [Depth_Keller] Loaded
2025-09-09T21:30:32.700Z,1757453432.700 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-09-09T21:30:32.706Z,1757453432.706 [DropWeight] Loaded
2025-09-09T21:30:32.706Z,1757453432.706 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-09-09T21:30:32.772Z,1757453432.772 [NAL9602] Loaded
2025-09-09T21:30:32.772Z,1757453432.772 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-09-09T21:30:32.804Z,1757453432.804 [Onboard] Loaded
2025-09-09T21:30:32.804Z,1757453432.804 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-09-09T21:30:32.805Z,1757453432.805 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A7A4E0
2025-09-09T21:30:32.805Z,1757453432.805 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 934
2025-09-09T21:30:32.820Z,1757453432.820 [Power24vConverter] Loaded
2025-09-09T21:30:32.820Z,1757453432.820 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-09-09T21:30:32.837Z,1757453432.837 [Radio_Surface] Loaded
2025-09-09T21:30:32.837Z,1757453432.837 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-09-09T21:30:32.838Z,1757453432.838 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AAA4E0
2025-09-09T21:30:32.838Z,1757453432.838 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 935
2025-09-09T21:30:32.887Z,1757453432.887 [RDI_Pathfinder] Loaded
2025-09-09T21:30:32.888Z,1757453432.888 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-09-09T21:30:32.903Z,1757453432.903 [Sonardyne_Nano] Loaded
2025-09-09T21:30:32.903Z,1757453432.903 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-09-09T21:30:32.904Z,1757453432.904 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-09-09T21:30:32.905Z,1757453432.905 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-09-09T21:30:33.047Z,1757453433.047 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-09-09T21:30:33.048Z,1757453433.048 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-09-09T21:30:33.062Z,1757453433.062 [NavChart] Loaded
2025-09-09T21:30:33.062Z,1757453433.062 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-09-09T21:30:33.068Z,1757453433.068 [UniversalFixResidualReporter] Loaded
2025-09-09T21:30:33.068Z,1757453433.068 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-09-09T21:30:33.069Z,1757453433.069 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-09-09T21:30:33.070Z,1757453433.070 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-09-09T21:30:33.273Z,1757453433.273 [SBIT](DEBUG): Construct Startup Built In Test.
2025-09-09T21:30:33.283Z,1757453433.283 [SBIT] Loaded
2025-09-09T21:30:33.283Z,1757453433.283 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-09-09T21:30:33.286Z,1757453433.286 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-09-09T21:30:33.299Z,1757453433.299 [IBIT] Loaded
2025-09-09T21:30:33.300Z,1757453433.300 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-09-09T21:30:33.305Z,1757453433.305 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-09-09T21:30:33.390Z,1757453433.390 [CBIT] Loaded
2025-09-09T21:30:33.390Z,1757453433.390 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-09-09T21:30:33.424Z,1757453433.424 [GFScanner] Loaded
2025-09-09T21:30:33.425Z,1757453433.425 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-09-09T21:30:33.425Z,1757453433.425 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-09-09T21:30:33.426Z,1757453433.426 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-09-09T21:30:33.597Z,1757453433.597 [BuoyancyServo] Loaded
2025-09-09T21:30:33.598Z,1757453433.598 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-09-09T21:30:33.622Z,1757453433.622 [ElevatorServo] Loaded
2025-09-09T21:30:33.622Z,1757453433.622 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-09-09T21:30:33.653Z,1757453433.653 [DockingStepper] Loaded
2025-09-09T21:30:33.653Z,1757453433.653 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2025-09-09T21:30:33.676Z,1757453433.676 [MassServo] Loaded
2025-09-09T21:30:33.676Z,1757453433.676 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-09-09T21:30:33.697Z,1757453433.697 [RudderServo] Loaded
2025-09-09T21:30:33.697Z,1757453433.697 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-09-09T21:30:33.717Z,1757453433.717 [ThrusterServo] Loaded
2025-09-09T21:30:33.718Z,1757453433.718 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2025-09-09T21:30:33.718Z,1757453433.718 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-09-09T21:30:33.719Z,1757453433.719 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-09-09T21:30:33.735Z,1757453433.735 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-09-09T21:30:33.735Z,1757453433.735 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-09-09T21:30:34.009Z,1757453434.009 [CTD_Seabird] Loaded
2025-09-09T21:30:34.009Z,1757453434.009 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-09-09T21:30:34.010Z,1757453434.010 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C0D4E0
2025-09-09T21:30:34.010Z,1757453434.010 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 937
2025-09-09T21:30:34.049Z,1757453434.049 [ESPComponent] Loaded
2025-09-09T21:30:34.049Z,1757453434.049 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-09-09T21:30:34.072Z,1757453434.072 [PAR_Licor] Loaded
2025-09-09T21:30:34.072Z,1757453434.072 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-09-09T21:30:34.119Z,1757453434.119 [WetLabsBB2FL] Loaded
2025-09-09T21:30:34.120Z,1757453434.120 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-09-09T21:30:34.121Z,1757453434.121 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2025-09-09T21:30:34.121Z,1757453434.121 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 938
2025-09-09T21:30:34.142Z,1757453434.142 [WetLabsUBAT] Loaded
2025-09-09T21:30:34.143Z,1757453434.143 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2025-09-09T21:30:34.144Z,1757453434.144 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-09-09T21:30:34.144Z,1757453434.144 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 939
2025-09-09T21:30:34.145Z,1757453434.145 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-09-09T21:30:34.151Z,1757453434.151 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-09-09T21:30:34.154Z,1757453434.154 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-09-09T21:30:34.166Z,1757453434.166 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-09-09T21:30:34.167Z,1757453434.167 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C9D4E0
2025-09-09T21:30:34.168Z,1757453434.168 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 940
2025-09-09T21:30:34.172Z,1757453434.172 [Supervisor](INFO): Main Thread ID is 830
2025-09-09T21:30:34.172Z,1757453434.172 [Supervisor](DEBUG): Running supervisor.
2025-09-09T21:30:34.173Z,1757453434.173 [CommandExec ThreadHandler](INFO): Handler Thread ID is 941
2025-09-09T21:30:34.173Z,1757453434.173 [CommandExec](INFO): Initializing the command executive.
2025-09-09T21:30:34.175Z,1757453434.175 [CommandLine ThreadHandler](INFO): Handler Thread ID is 942
2025-09-09T21:30:34.177Z,1757453434.177 [controlThread ThreadHandler](INFO): Handler Thread ID is 943
2025-09-09T21:30:34.178Z,1757453434.178 [controlThread](DEBUG): Initializing ControlThread
2025-09-09T21:30:34.178Z,1757453434.178 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-09-09T21:30:34.180Z,1757453434.180 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-09-09T21:30:34.181Z,1757453434.181 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-09-09T21:30:34.181Z,1757453434.181 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-09-09T21:30:34.182Z,1757453434.182 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-09-09T21:30:34.182Z,1757453434.182 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-09-09T21:30:34.182Z,1757453434.182 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-09-09T21:30:34.183Z,1757453434.183 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-09-09T21:30:34.183Z,1757453434.183 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-09-09T21:30:34.187Z,1757453434.187 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-09-09T21:30:34.188Z,1757453434.188 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-09-09T21:30:34.188Z,1757453434.188 [SBIT](INFO): Initialize SBIT Component.
2025-09-09T21:30:34.189Z,1757453434.189 [SBIT](IMPORTANT): git: 2025-08-26
2025-09-09T21:30:34.189Z,1757453434.189 [SBIT](INFO): git hash: bb7b608ae095ad33f3a1ba1dc4d6b0b36a605f14
2025-09-09T21:30:34.189Z,1757453434.189 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-09-09T21:30:34.190Z,1757453434.190 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-09-09T21:30:34.191Z,1757453434.191 [SBIT](INFO): Beginning SBIT in 98.000000 seconds.
2025-09-09T21:30:34.192Z,1757453434.192 [IBIT](INFO): Initialize IBIT Component.
2025-09-09T21:30:34.193Z,1757453434.193 [CBIT](DEBUG): Initialize CBIT Component.
2025-09-09T21:30:34.194Z,1757453434.194 [logger ThreadHandler](INFO): Handler Thread ID is 944
2025-09-09T21:30:34.204Z,1757453434.204 [CBIT](DEBUG): Initialized mux pins.
2025-09-09T21:30:34.204Z,1757453434.204 [CBIT](DEBUG): Initializing the watchdog timer.
2025-09-09T21:30:34.212Z,1757453434.212 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 945
2025-09-09T21:30:34.224Z,1757453434.224 [DAT ThreadHandler](INFO): Handler Thread ID is 946
2025-09-09T21:30:34.225Z,1757453434.225 [DAT](INFO): Powering up
2025-09-09T21:30:34.225Z,1757453434.225 [DAT](DEBUG): Initializing DAT.
2025-09-09T21:30:34.228Z,1757453434.228 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-09-09T21:30:34.228Z,1757453434.228 [CBIT](DEBUG): Initializing heartbeat.
2025-09-09T21:30:34.230Z,1757453434.230 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 948
2025-09-09T21:30:34.232Z,1757453434.232 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-09-09T21:30:34.245Z,1757453434.245 [Onboard ThreadHandler](INFO): Handler Thread ID is 949
2025-09-09T21:30:34.265Z,1757453434.265 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 950
2025-09-09T21:30:34.277Z,1757453434.277 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 951
2025-09-09T21:30:34.278Z,1757453434.278 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-09-09T21:30:34.281Z,1757453434.281 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 953
2025-09-09T21:30:34.284Z,1757453434.284 [WetLabsBB2FL](INFO): Powering up
2025-09-09T21:30:34.285Z,1757453434.285 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 955
2025-09-09T21:30:34.288Z,1757453434.288 [WetLabsUBAT](INFO): Powering up
2025-09-09T21:30:34.289Z,1757453434.289 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 957
2025-09-09T21:30:34.298Z,1757453434.298 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-09-09T21:30:34.298Z,1757453434.298 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-09-09T21:30:34.299Z,1757453434.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-09-09T21:30:34.299Z,1757453434.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-09-09T21:30:34.299Z,1757453434.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-09-09T21:30:34.299Z,1757453434.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-09-09T21:30:34.299Z,1757453434.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-09-09T21:30:34.300Z,1757453434.300 [CBIT](DEBUG): Deactivating emergency mode.
2025-09-09T21:30:34.299Z,1757453434.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-09-09T21:30:34.340Z,1757453434.340 [CBIT](DEBUG): Backplane powered.
2025-09-09T21:30:34.340Z,1757453434.340 [GFScanner](DEBUG): Initializing GFScanner
2025-09-09T21:30:34.340Z,1757453434.340 [GFScanner](DEBUG): Deactivating GF circuits.
2025-09-09T21:30:34.348Z,1757453434.348 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-09-09T21:30:34.349Z,1757453434.349 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2025-09-09T21:30:34.349Z,1757453434.349 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2025-09-09T21:30:34.364Z,1757453434.364 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2025-09-09T21:30:34.385Z,1757453434.385 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
2025-09-09T21:30:34.385Z,1757453434.385 [MissionManager](ERROR): Top node has no child nodes: Mission
2025-09-09T21:30:34.385Z,1757453434.385 [MissionManager](ERROR): Could not load startup mission.
2025-09-09T21:30:34.386Z,1757453434.386 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl
2025-09-09T21:30:34.386Z,1757453434.386 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl
2025-09-09T21:30:34.389Z,1757453434.389 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl
2025-09-09T21:30:34.668Z,1757453434.668 [Radio_Surface](INFO): Powering up
2025-09-09T21:30:34.985Z,1757453434.985 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into:
0
6
10
1
3
30
Timed out while trying to detach from dock at current depth of
.
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-09-09T21:30:35.015Z,1757453435.015 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2025-09-09T21:30:35.019Z,1757453435.019 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h
2025-09-09T21:30:35.051Z,1757453435.051 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count
2025-09-09T21:30:35.055Z,1757453435.055 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s
2025-09-09T21:30:35.067Z,1757453435.067 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2025-09-09T21:30:35.071Z,1757453435.071 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min
2025-09-09T21:30:35.089Z,1757453435.089 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2025-09-09T21:30:35.309Z,1757453435.309 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2025-09-09T21:30:35.309Z,1757453435.309 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-09-09T21:30:35.359Z,1757453435.359 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-09T21:30:35.410Z,1757453435.410 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-09-09T21:30:35.412Z,1757453435.412 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-09-09T21:30:35.434Z,1757453435.434 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2025-09-09T21:30:35.455Z,1757453435.455 [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 DefaultWithUndock {
arguments {
ElapsedSinceDefaultStarted = 0 minute
DetachTimeout = 6 hour
"""
Maximum time duration for the vehicle to detach form the dock.
"""
TransponderCode = Dock:Dock.transponderCode
"""
Dock transponder address.
"""
TrackingUpdatePeriod = 10 second
"""
Time duration between acoustic range queries while the mission is
detaching from the dock.
"""
NumberOfPings = 1 count
"""
Number of pings requested each time.
"""
DriftTimeout = 3 minute
"""
How long to allow vehicle to drift away from dock before retrying reverse
"""
}
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 = 30 second
}
aggregate LeaveDock {
run in sequence
timeout duration=DetachTimeout {
syslog important "Timed out while trying to detach from dock at current depth of "
+ Universal:depth~meter + "."
}
# Acoustic tracking directives
behavior Estimation:TrackAcousticContact {
run while (
Universal:depth > Control:VerticalControl.surfaceThreshold
)
set contactLabelSetting = TransponderCode
set numberOfSamplesSetting = NumberOfPings
set updatePeriodSetting = TrackingUpdatePeriod
}
# Leave the dock
behavior Dock:Undock {
run in sequence
set driftTimeout = DriftTimeout
}
}
aggregate SurfaceDefault {
run in sequence
# This mission assumes open water so from here on it's the same old default that's always used
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=P2H {
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-09-09T21:30:35.460Z,1757453435.460 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-09-09T21:30:35.472Z,1757453435.472 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-09T21:30:35.608Z,1757453435.608 [Depth_Keller](INFO): Initializing.
2025-09-09T21:30:35.610Z,1757453435.610 [Power24vConverter](INFO): Powering up.
2025-09-09T21:30:35.610Z,1757453435.610 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-09-09T21:30:35.613Z,1757453435.613 [Sonardyne_Nano](INFO): Initializing.
2025-09-09T21:30:35.656Z,1757453435.656 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-09-09T21:30:35.682Z,1757453435.682 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-09-09T21:30:35.688Z,1757453435.688 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-09-09T21:30:35.689Z,1757453435.689 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-09-09T21:30:35.696Z,1757453435.696 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-09-09T21:30:35.697Z,1757453435.697 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-09-09T21:30:35.704Z,1757453435.704 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-09-09T21:30:35.705Z,1757453435.705 [MassServo](DEBUG): Initializing EZServoServo.
2025-09-09T21:30:35.712Z,1757453435.712 [MassServo](DEBUG): Initializing MassServo.
2025-09-09T21:30:35.713Z,1757453435.713 [RudderServo](DEBUG): Initializing EZServoServo.
2025-09-09T21:30:35.720Z,1757453435.720 [RudderServo](DEBUG): Initializing RudderServo.
2025-09-09T21:30:35.721Z,1757453435.721 [ThrusterServo](DEBUG): Initializing EZServoServo.
2025-09-09T21:30:35.728Z,1757453435.728 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2025-09-09T21:30:35.909Z,1757453435.909 [Depth_Keller](ERROR): Pressure or depth reading out of range: 824.648376 decibar, 0.000000 m
2025-09-09T21:30:35.921Z,1757453435.921 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-09-09T21:30:36.896Z,1757453436.896 [WetLabsBB2FL](INFO): Powering down
2025-09-09T21:30:37.894Z,1757453437.894 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-09-09T21:30:39.149Z,1757453439.149 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103
2025-09-09T21:30:39.921Z,1757453439.921 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-09-09T21:30:39.922Z,1757453439.922 [Radio_Surface](INFO): No local IP found
2025-09-09T21:30:43.612Z,1757453443.612 [WetLabsUBAT](INFO): Powering down
2025-09-09T21:30:46.242Z,1757453446.242 [CommandExec](IMPORTANT): got command failComponent
2025-09-09T21:30:46.242Z,1757453446.242 [CommandExec](IMPORTANT): Failed components:
2025-09-09T21:30:46.242Z,1757453446.242 [CommandExec](IMPORTANT): No failed Components.
2025-09-09T21:30:47.580Z,1757453447.580 [DAT](INFO): DAT read:
2025-09-09T21:30:47.582Z,1757453447.582 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-09-09T21:30:53.125Z,1757453453.125 [DAT](INFO): DAT read: MF Frequency Band
2025-09-09T21:30:53.126Z,1757453453.126 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2
2025-09-09T21:30:53.127Z,1757453453.127 [DAT](INFO): DAT read: Aug 28 2025 00:38:32
2025-09-09T21:30:54.133Z,1757453454.133 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-09-09T21:30:54.134Z,1757453454.134 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP
2025-09-09T21:30:54.135Z,1757453454.135 [DAT](INFO): commRate: 600
2025-09-09T21:30:56.200Z,1757453456.200 [DAT](INFO): entering command mode
2025-09-09T21:30:56.401Z,1757453456.401 [DAT](INFO): DAT read:
2025-09-09T21:30:56.401Z,1757453456.401 [DAT](INFO): DAT read: user:1>
2025-09-09T21:30:56.401Z,1757453456.401 [DAT](INFO): setting verbose to 3
2025-09-09T21:30:56.653Z,1757453456.653 [DAT](INFO): DAT read: user:1>
2025-09-09T21:30:56.654Z,1757453456.654 [DAT](INFO): DAT read: Verbose | 3
2025-09-09T21:30:56.654Z,1757453456.654 [DAT](INFO): set verbose to 3
2025-09-09T21:30:56.654Z,1757453456.654 [DAT](INFO): setting DatVerbose to 27440
2025-09-09T21:30:56.905Z,1757453456.905 [DAT](INFO): DAT read: user:2>
2025-09-09T21:30:56.906Z,1757453456.906 [DAT](INFO): DAT read: DatVerbose | 27440
2025-09-09T21:30:56.906Z,1757453456.906 [DAT](INFO): set DatVerbose to 27440
2025-09-09T21:30:56.906Z,1757453456.906 [DAT](INFO): setting transmit power to 8
2025-09-09T21:30:57.157Z,1757453457.157 [DAT](INFO): DAT read: user:3>
2025-09-09T21:30:57.158Z,1757453457.158 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-09-09T21:30:57.158Z,1757453457.158 [DAT](INFO): set transmit power to 8
2025-09-09T21:30:57.158Z,1757453457.158 [DAT](INFO): setting local address to 10
2025-09-09T21:30:57.396Z,1757453457.396 [CommandExec](IMPORTANT): got command failComponent
2025-09-09T21:30:57.396Z,1757453457.396 [CommandExec](IMPORTANT): Failed components:
2025-09-09T21:30:57.397Z,1757453457.397 [CommandExec](IMPORTANT): No failed Components.
2025-09-09T21:30:57.409Z,1757453457.409 [DAT](INFO): DAT read: user:4>
2025-09-09T21:30:57.410Z,1757453457.410 [DAT](INFO): DAT read: LocalAddr | 10
2025-09-09T21:30:57.410Z,1757453457.410 [DAT](INFO): set local address to 10
2025-09-09T21:30:57.411Z,1757453457.411 [DAT](INFO): Setting time to: 21:30:57 And date to:9/9/2025
2025-09-09T21:30:57.661Z,1757453457.661 [DAT](INFO): DAT read: user:5>
2025-09-09T21:30:57.662Z,1757453457.662 [DAT](INFO): DAT read: Tue Sep 9, 2025 21:30:57
2025-09-09T21:30:57.662Z,1757453457.662 [DAT](INFO): Local DAT time set to Tue Sep 9, 2025 21:30:57
2025-09-09T21:31:03.129Z,1757453463.129 [NAL9602](INFO): Powering up NAL9602
2025-09-09T21:31:14.037Z,1757453474.037 [NAL9602](INFO): NAL9602 initialized
2025-09-09T21:31:42.436Z,1757453502.436 [CommandExec](IMPORTANT): got command failComponent
2025-09-09T21:31:42.436Z,1757453502.436 [CommandExec](IMPORTANT): Failed components:
2025-09-09T21:31:42.437Z,1757453502.437 [CommandExec](IMPORTANT): No failed Components.
2025-09-09T21:32:08.813Z,1757453528.813 [CommandExec](IMPORTANT): got command docking arm
2025-09-09T21:32:08.813Z,1757453528.813 [CommandExec](IMPORTANT): Switching docking module to ARM.
2025-09-09T21:32:10.238Z,1757453530.238 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-09-09T21:32:10.353Z,1757453530.353 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-09-09T21:32:12.643Z,1757453532.643 [SBIT](IMPORTANT): Beginning Startup BIT
2025-09-09T21:32:12.647Z,1757453532.647 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-09-09T21:32:13.891Z,1757453533.891 [DockingStepper](INFO): Changing to mode: 2
2025-09-09T21:32:17.137Z,1757453537.137 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-09T21:32:17.310Z,1757453537.310 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.009915
CHAN A1 (24V): -0.022899
CHAN A2 (12V): -0.004999
CHAN A3 (5V): -0.001842
CHAN B0 (3.3V): 0.000646
CHAN B1 (3.15aV): -0.001044
CHAN B2 (3.15bV): 0.000007
CHAN B3 (GND): 0.000002
OPEN: -0.004013
Full Scale: +/- 1 mA
2025-09-09T21:32:18.798Z,1757453538.798 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-09T21:32:22.033Z,1757453542.033 [CommandExec](IMPORTANT): got command docking standby
2025-09-09T21:32:22.033Z,1757453542.033 [CommandExec](IMPORTANT): Switching docking module to STANDBY.
2025-09-09T21:32:22.427Z,1757453542.427 [DockingStepper](INFO): Changing to mode: 1
2025-09-09T21:32:44.675Z,1757453564.675 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-09-09T21:32:44.676Z,1757453564.676 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 61.000000 %
2025-09-09T21:33:06.278Z,1757453586.278 [SBIT](IMPORTANT): SBIT PASSED
2025-09-09T21:33:06.278Z,1757453586.278 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-09-09T21:33:06.278Z,1757453586.278 [SBIT](IMPORTANT): CBIT.gf24Offset=173 microampere;
2025-09-09T21:33:06.279Z,1757453586.279 [SBIT](IMPORTANT): Docked.verbose=0 bool;
2025-09-09T21:33:06.279Z,1757453586.279 [SBIT](IMPORTANT): DockingStepper.closedValue=16450 count;
2025-09-09T21:33:06.279Z,1757453586.279 [SBIT](IMPORTANT): DockingStepper.slideValue=10700 count;
2025-09-09T21:33:06.279Z,1757453586.279 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2025-09-09T21:33:06.279Z,1757453586.279 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2025-09-09T21:33:06.279Z,1757453586.279 [SBIT](IMPORTANT): Express mostRecent WetLabsUBAT.average_bioluminescence;
2025-09-09T21:33:06.279Z,1757453586.279 [SBIT](IMPORTANT): LineCapture.rolloutSpeed=0 meter_per_second;
2025-09-09T21:33:06.279Z,1757453586.279 [SBIT](IMPORTANT): NAL9602.iridiumMTQueueTimeout=1 minute;
2025-09-09T21:33:06.280Z,1757453586.280 [SBIT](IMPORTANT): Normal linearApproximation _.dinoflagellates 10000000000.000000 none;
2025-09-09T21:33:06.280Z,1757453586.280 [SBIT](IMPORTANT): RudderServo.offsetAngle=1 degree;
2025-09-09T21:33:06.280Z,1757453586.280 [SBIT](IMPORTANT): SpeedControl.rollOptimum=1.025000 m/s;
2025-09-09T21:33:06.280Z,1757453586.280 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=408.601599 cubic_centimeter;
2025-09-09T21:33:06.280Z,1757453586.280 [SBIT](IMPORTANT): VerticalControl.massDefault=19.541511 millimeter;
2025-09-09T21:33:06.632Z,1757453586.632 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-09-09T21:33:06.632Z,1757453586.632 [DefaultWithUndock] Running Loop=1
2025-09-09T21:33:06.632Z,1757453586.632 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-09-09T21:33:06.633Z,1757453586.633 [DefaultWithUndock:A.Wait] Running Loop=1
2025-09-09T21:33:06.633Z,1757453586.633 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-09-09T21:33:26.995Z,1757453606.995 [CommandExec](IMPORTANT): got command burn on
2025-09-09T21:33:26.996Z,1757453606.996 [CommandExec](IMPORTANT): Activating dropweight wire
2025-09-09T21:33:27.823Z,1757453607.823 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:33:34.327Z,1757453614.327 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-09-09T21:33:34.327Z,1757453614.327 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-09-09T21:33:34.400Z,1757453614.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-09-09T21:33:34.751Z,1757453614.751 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-09-09T21:33:34.751Z,1757453614.751 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-09-09T21:33:36.693Z,1757453616.693 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2025-09-09T21:33:36.693Z,1757453616.693 [DefaultWithUndock:A.Wait] Stopped
2025-09-09T21:33:36.693Z,1757453616.693 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-09-09T21:33:36.693Z,1757453616.693 [DefaultWithUndock:LeaveDock] Running Loop=1
2025-09-09T21:33:36.693Z,1757453616.693 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2025-09-09T21:33:36.693Z,1757453616.693 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-09-09T21:33:36.694Z,1757453616.694 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2025-09-09T21:33:36.694Z,1757453616.694 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2025-09-09T21:33:36.694Z,1757453616.694 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-09-09T21:33:36.694Z,1757453616.694 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-09-09T21:33:37.057Z,1757453617.057 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-09-09T21:33:37.057Z,1757453617.057 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-09-09T21:33:37.067Z,1757453617.067 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth -0.250504 m.
2025-09-09T21:33:37.068Z,1757453617.068 [DefaultWithUndock:LeaveDock:A.] Stopped
2025-09-09T21:33:37.068Z,1757453617.068 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2025-09-09T21:33:37.461Z,1757453617.461 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2025-09-09T21:33:38.782Z,1757453618.782 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004517
2025-09-09T21:33:39.775Z,1757453619.775 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:33:51.499Z,1757453631.499 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:33:53.528Z,1757453633.528 [CommandExec](IMPORTANT): got command burn off
2025-09-09T21:33:53.528Z,1757453633.528 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-09-09T21:33:57.669Z,1757453637.669 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2025-09-09T21:33:58.109Z,1757453638.109 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2025-09-09T21:33:58.517Z,1757453638.517 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2025-09-09T21:33:58.517Z,1757453638.517 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2025-09-09T21:33:58.518Z,1757453638.518 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2025-09-09T21:33:58.518Z,1757453638.518 [DefaultWithUndock:LeaveDock] Stopped
2025-09-09T21:33:58.518Z,1757453638.518 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2025-09-09T21:33:58.518Z,1757453638.518 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2025-09-09T21:33:58.518Z,1757453638.518 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2025-09-09T21:33:58.518Z,1757453638.518 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2025-09-09T21:33:58.518Z,1757453638.518 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-09T21:33:58.519Z,1757453638.519 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-09-09T21:33:58.519Z,1757453638.519 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-09-09T21:33:58.520Z,1757453638.520 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-09-09T21:33:58.520Z,1757453638.520 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-09-09T21:33:58.520Z,1757453638.520 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-09T21:33:58.520Z,1757453638.520 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-09T21:33:58.888Z,1757453638.888 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2025-09-09T21:33:58.888Z,1757453638.888 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T21:33:58.888Z,1757453638.888 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-09T21:33:59.277Z,1757453639.277 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-09-09T21:34:00.392Z,1757453640.392 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:34:09.071Z,1757453649.071 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:34:13.111Z,1757453653.111 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-09T21:34:17.859Z,1757453657.859 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:34:26.687Z,1757453666.687 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:34:35.307Z,1757453675.307 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:34:44.043Z,1757453684.043 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:34:52.667Z,1757453692.667 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:35:01.235Z,1757453701.235 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:35:09.994Z,1757453709.994 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:35:18.639Z,1757453718.639 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:35:27.235Z,1757453727.235 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:35:35.979Z,1757453735.979 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:36:07.731Z,1757453767.731 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:36:29.995Z,1757453789.995 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:36:35.292Z,1757453795.292 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-09-09T21:36:35.292Z,1757453795.292 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-09-09T21:36:35.356Z,1757453795.356 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-09-09T21:36:35.751Z,1757453795.751 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-09-09T21:36:35.752Z,1757453795.752 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-09-09T21:36:48.667Z,1757453808.667 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:37:07.483Z,1757453827.483 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:37:26.171Z,1757453846.171 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:37:44.871Z,1757453864.871 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:38:03.631Z,1757453883.631 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:38:25.507Z,1757453905.507 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:38:44.495Z,1757453924.495 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:38:59.185Z,1757453939.185 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](INFO): Timed out from 2025-09-09T21:33:58.9Z
2025-09-09T21:38:59.185Z,1757453939.185 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-09T21:38:59.186Z,1757453939.186 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-09T21:38:59.597Z,1757453939.597 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-09-09T21:39:10.002Z,1757453950.002 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250909T213021/Courier0000.lzma
2025-09-09T21:39:21.058Z,1757453961.058 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Courier0000.lzma.bak
2025-09-09T21:39:21.058Z,1757453961.058 [DataOverHttps](INFO): SBD MOMSN=25955730
2025-09-09T21:39:36.384Z,1757453976.384 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-09-09T21:39:36.384Z,1757453976.384 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-09-09T21:39:36.440Z,1757453976.440 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-09-09T21:39:36.815Z,1757453976.815 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-09-09T21:39:36.815Z,1757453976.815 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-09-09T21:39:47.550Z,1757453987.550 [DataOverHttps](INFO): Sending 1440 bytes from file Logs/20250909T213021/Express0001.lzma
2025-09-09T21:39:58.588Z,1757453998.588 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Express0001.lzma.bak
2025-09-09T21:39:58.588Z,1757453998.588 [DataOverHttps](INFO): SBD MOMSN=25955733
2025-09-09T21:39:59.788Z,1757453999.788 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-09T21:39:59.788Z,1757453999.788 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-09T21:39:59.788Z,1757453999.788 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-09T21:40:19.995Z,1757454019.995 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:40:38.651Z,1757454038.651 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:40:57.431Z,1757454057.431 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:41:14.940Z,1757454074.940 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-09-09T21:41:14.940Z,1757454074.940 [NAL9602] Data Fault, FailCount= 1
2025-09-09T21:41:14.940Z,1757454074.940 [NAL9602](ERROR): Data Fault
2025-09-09T21:41:15.020Z,1757454075.020 [CBIT](ERROR): Data Fault in component: NAL9602
2025-09-09T21:41:15.344Z,1757454075.344 [NAL9602](INFO): Powering down
2025-09-09T21:41:16.111Z,1757454076.111 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:41:16.271Z,1757454076.271 [CBIT](INFO): Clearing failed state for component NAL9602
2025-09-09T21:41:16.271Z,1757454076.271 [NAL9602] No Fault, FailCount= 1
2025-09-09T21:41:34.975Z,1757454094.975 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:41:45.660Z,1757454105.660 [NAL9602](INFO): Powering up NAL9602
2025-09-09T21:41:53.631Z,1757454113.631 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:41:56.641Z,1757454116.641 [NAL9602](INFO): NAL9602 initialized
2025-09-09T21:42:12.396Z,1757454132.396 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:42:30.947Z,1757454150.947 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:42:37.373Z,1757454157.373 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-09-09T21:42:37.373Z,1757454157.373 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-09-09T21:42:37.436Z,1757454157.436 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-09-09T21:42:37.847Z,1757454157.847 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-09-09T21:42:37.848Z,1757454157.848 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-09-09T21:42:49.719Z,1757454169.719 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:43:08.375Z,1757454188.375 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:43:27.035Z,1757454207.035 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:43:45.883Z,1757454225.883 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:44:04.563Z,1757454244.563 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:44:26.459Z,1757454266.459 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:44:45.123Z,1757454285.123 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:45:00.472Z,1757454300.472 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-09T21:45:00.472Z,1757454300.472 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-09T21:45:00.472Z,1757454300.472 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-09T21:45:00.472Z,1757454300.472 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2025-09-09T21:45:00.892Z,1757454300.892 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2025-09-09T21:45:00.892Z,1757454300.892 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2025-09-09T21:45:01.261Z,1757454301.261 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 11.904333 min
2025-09-09T21:45:01.261Z,1757454301.261 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2025-09-09T21:45:01.261Z,1757454301.261 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T21:45:01.261Z,1757454301.261 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-09T21:45:01.261Z,1757454301.261 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T21:45:01.261Z,1757454301.261 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2
2025-09-09T21:45:01.262Z,1757454301.262 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2
2025-09-09T21:45:01.262Z,1757454301.262 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T21:45:01.262Z,1757454301.262 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-09T21:45:04.011Z,1757454304.011 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:45:22.647Z,1757454322.647 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:45:38.459Z,1757454338.459 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-09-09T21:45:38.459Z,1757454338.459 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-09-09T21:45:38.516Z,1757454338.516 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-09-09T21:45:38.927Z,1757454338.927 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-09-09T21:45:38.927Z,1757454338.927 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-09-09T21:45:41.299Z,1757454341.299 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:45:59.967Z,1757454359.967 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:46:20.135Z,1757454380.135 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:46:38.959Z,1757454398.959 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:46:57.603Z,1757454417.603 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:47:16.463Z,1757454436.463 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:47:35.083Z,1757454455.083 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:47:53.751Z,1757454473.751 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:48:12.591Z,1757454492.591 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:48:31.250Z,1757454511.250 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:48:39.491Z,1757454519.491 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-09-09T21:48:39.491Z,1757454519.491 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-09-09T21:48:39.549Z,1757454519.549 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-09-09T21:48:39.932Z,1757454519.932 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-09-09T21:48:39.932Z,1757454519.932 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-09-09T21:48:50.099Z,1757454530.099 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:49:08.891Z,1757454548.891 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:49:27.543Z,1757454567.543 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:49:46.359Z,1757454586.359 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:50:01.461Z,1757454601.461 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](INFO): Timed out from 2025-09-09T21:45:01.3Z
2025-09-09T21:50:01.461Z,1757454601.461 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-09T21:50:01.461Z,1757454601.461 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-09T21:50:14.866Z,1757454614.866 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250909T213021/Courier0004.lzma
2025-09-09T21:50:25.908Z,1757454625.908 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Courier0004.lzma.bak
2025-09-09T21:50:25.908Z,1757454625.908 [DataOverHttps](INFO): SBD MOMSN=25955802
2025-09-09T21:50:52.330Z,1757454652.330 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20250909T213021/Express0005.lzma
2025-09-09T21:51:03.376Z,1757454663.376 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Express0005.lzma.bak
2025-09-09T21:51:03.377Z,1757454663.377 [DataOverHttps](INFO): SBD MOMSN=25955804
2025-09-09T21:51:04.572Z,1757454664.572 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-09T21:51:04.572Z,1757454664.572 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-09T21:51:04.572Z,1757454664.572 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-09T21:51:23.079Z,1757454683.079 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:51:40.508Z,1757454700.508 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-09-09T21:51:40.508Z,1757454700.508 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-09-09T21:51:40.588Z,1757454700.588 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-09-09T21:51:41.019Z,1757454701.019 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-09-09T21:51:41.019Z,1757454701.019 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-09-09T21:51:41.887Z,1757454701.887 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:51:58.264Z,1757454718.264 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-09-09T21:51:58.264Z,1757454718.264 [NAL9602] Data Fault, FailCount= 2
2025-09-09T21:51:58.264Z,1757454718.264 [NAL9602](ERROR): Data Fault
2025-09-09T21:51:58.339Z,1757454718.339 [CBIT](ERROR): Data Fault in component: NAL9602
2025-09-09T21:51:58.660Z,1757454718.660 [NAL9602](INFO): Powering down
2025-09-09T21:51:59.539Z,1757454719.539 [CBIT](INFO): Clearing failed state for component NAL9602
2025-09-09T21:51:59.539Z,1757454719.539 [NAL9602] No Fault, FailCount= 2
2025-09-09T21:52:00.595Z,1757454720.595 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:52:21.730Z,1757454741.730 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:52:29.004Z,1757454749.004 [NAL9602](INFO): Powering up NAL9602
2025-09-09T21:52:39.929Z,1757454759.929 [NAL9602](INFO): NAL9602 initialized
2025-09-09T21:52:40.503Z,1757454760.503 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:52:59.171Z,1757454779.171 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:53:17.779Z,1757454797.779 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:53:36.567Z,1757454816.567 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:53:55.303Z,1757454835.303 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:54:14.023Z,1757454854.023 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:54:32.859Z,1757454872.859 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:54:41.551Z,1757454881.551 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-09-09T21:54:41.551Z,1757454881.551 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-09-09T21:54:41.617Z,1757454881.617 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-09-09T21:54:42.027Z,1757454882.027 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-09-09T21:54:42.027Z,1757454882.027 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-09-09T21:54:51.499Z,1757454891.499 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:55:10.267Z,1757454910.267 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:55:27.982Z,1757454927.982 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215526.00,A,3648.14918,N,12147.25438,W,1.983,0.00,090925,,,A*7A
2025-09-09T21:55:27.985Z,1757454927.985 [NAL9602](INFO): GPS fix at 20250909T215526: (36.802486, -121.787573)
2025-09-09T21:55:28.013Z,1757454928.013 [DataOverHttps](IMPORTANT): SBD MTMSN=20250909T215527
2025-09-09T21:55:37.418Z,1757454937.418 [DataOverHttps](INFO): Received command: failc
2025-09-09T21:55:37.451Z,1757454937.451 [CommandExec](IMPORTANT): got command failComponent
2025-09-09T21:55:37.451Z,1757454937.451 [CommandExec](IMPORTANT): Failed components:
2025-09-09T21:55:37.451Z,1757454937.451 [CommandExec](IMPORTANT): No failed Components.
2025-09-09T21:55:48.460Z,1757454948.460 [DataOverHttps](IMPORTANT): SBD MTMSN=20250909T215547
2025-09-09T21:55:56.386Z,1757454956.386 [DataOverHttps](INFO): Received command: ibit
2025-09-09T21:55:56.455Z,1757454956.455 [CommandExec](IMPORTANT): got command ibit
2025-09-09T21:55:56.762Z,1757454956.762 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-09-09T21:55:56.762Z,1757454956.762 [IBIT](IMPORTANT): Beginning control surface checks.
2025-09-09T21:55:56.772Z,1757454956.772 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-09-09T21:55:58.281Z,1757454958.281 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215556.00,A,3648.14100,N,12147.24732,W,2.002,113.90,090925,,,A*7D
2025-09-09T21:55:58.284Z,1757454958.284 [NAL9602](INFO): GPS fix at 20250909T215556: (36.802350, -121.787455)
2025-09-09T21:56:00.948Z,1757454960.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215559.00,A,3648.14048,N,12147.24614,W,2.235,113.90,090925,,,A*7C
2025-09-09T21:56:00.960Z,1757454960.960 [NAL9602](INFO): GPS fix at 20250909T215559: (36.802341, -121.787436)
2025-09-09T21:56:01.529Z,1757454961.529 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.009499
CHAN A1 (24V): 0.002871
CHAN A2 (12V): 0.005275
CHAN A3 (5V): 0.000183
CHAN B0 (3.3V): 0.000273
CHAN B1 (3.15aV): -0.000308
CHAN B2 (3.15bV): -0.000822
CHAN B3 (GND): -0.003366
OPEN: 0.005877
Full Scale: +/- 1 mA
2025-09-09T21:56:03.864Z,1757454963.864 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215602.00,A,3648.13946,N,12147.24425,W,2.002,118.77,090925,,,A*75
2025-09-09T21:56:03.867Z,1757454963.867 [NAL9602](INFO): GPS fix at 20250909T215602: (36.802324, -121.787404)
2025-09-09T21:56:07.113Z,1757454967.113 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215605.00,A,3648.13822,N,12147.24081,W,3.616,113.01,090925,,,A*73
2025-09-09T21:56:07.116Z,1757454967.116 [NAL9602](INFO): GPS fix at 20250909T215605: (36.802304, -121.787347)
2025-09-09T21:56:08.431Z,1757454968.431 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:56:09.936Z,1757454969.936 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215609.00,A,3648.13813,N,12147.23756,W,2.546,105.92,090925,,,A*7D
2025-09-09T21:56:09.938Z,1757454969.938 [NAL9602](INFO): GPS fix at 20250909T215609: (36.802302, -121.787293)
2025-09-09T21:56:13.193Z,1757454973.193 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215612.00,A,3648.13851,N,12147.23520,W,1.594,82.32,090925,,,A*4A
2025-09-09T21:56:13.212Z,1757454973.212 [NAL9602](INFO): GPS fix at 20250909T215612: (36.802309, -121.787253)
2025-09-09T21:56:16.218Z,1757454976.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215615.00,A,3648.13987,N,12147.23392,W,2.683,38.54,090925,,,A*4F
2025-09-09T21:56:16.221Z,1757454976.221 [NAL9602](INFO): GPS fix at 20250909T215615: (36.802331, -121.787232)
2025-09-09T21:56:18.878Z,1757454978.878 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215618.00,A,3648.14213,N,12147.23263,W,2.916,21.14,090925,,,A*43
2025-09-09T21:56:18.880Z,1757454978.880 [NAL9602](INFO): GPS fix at 20250909T215618: (36.802369, -121.787211)
2025-09-09T21:56:22.162Z,1757454982.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215621.00,A,3648.14473,N,12147.23146,W,3.149,19.96,090925,,,A*4F
2025-09-09T21:56:22.164Z,1757454982.164 [NAL9602](INFO): GPS fix at 20250909T215621: (36.802412, -121.787191)
2025-09-09T21:56:24.991Z,1757454984.991 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215624.00,A,3648.14708,N,12147.23012,W,2.896,19.33,090925,,,A*40
2025-09-09T21:56:24.993Z,1757454984.993 [NAL9602](INFO): GPS fix at 20250909T215624: (36.802451, -121.787169)
2025-09-09T21:56:27.825Z,1757454987.825 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215627.00,A,3648.14912,N,12147.22895,W,2.974,19.08,090925,,,A*45
2025-09-09T21:56:27.828Z,1757454987.828 [NAL9602](INFO): GPS fix at 20250909T215627: (36.802485, -121.787149)
2025-09-09T21:56:31.050Z,1757454991.050 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215630.00,A,3648.15115,N,12147.22824,W,2.449,19.56,090925,,,A*4F
2025-09-09T21:56:31.052Z,1757454991.052 [NAL9602](INFO): GPS fix at 20250909T215630: (36.802519, -121.787137)
2025-09-09T21:56:31.691Z,1757454991.691 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:56:33.898Z,1757454993.898 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215633.00,A,3648.15244,N,12147.22635,W,2.197,21.82,090925,,,A*41
2025-09-09T21:56:33.900Z,1757454993.900 [NAL9602](INFO): GPS fix at 20250909T215633: (36.802541, -121.787106)
2025-09-09T21:56:37.113Z,1757454997.113 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215636.00,A,3648.15379,N,12147.22503,W,1.555,30.78,090925,,,A*41
2025-09-09T21:56:37.115Z,1757454997.115 [NAL9602](INFO): GPS fix at 20250909T215636: (36.802563, -121.787084)
2025-09-09T21:56:39.938Z,1757454999.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215639.00,A,3648.15393,N,12147.22450,W,0.078,30.78,090925,,,A*46
2025-09-09T21:56:39.940Z,1757454999.940 [NAL9602](INFO): GPS fix at 20250909T215639: (36.802566, -121.787075)
2025-09-09T21:56:43.251Z,1757455003.251 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215642.00,A,3648.15377,N,12147.22457,W,0.097,30.78,090925,,,A*46
2025-09-09T21:56:43.253Z,1757455003.253 [NAL9602](INFO): GPS fix at 20250909T215642: (36.802563, -121.787076)
2025-09-09T21:56:46.002Z,1757455006.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215645.00,A,3648.15364,N,12147.22457,W,0.117,30.78,090925,,,A*4A
2025-09-09T21:56:46.004Z,1757455006.004 [NAL9602](INFO): GPS fix at 20250909T215645: (36.802561, -121.787076)
2025-09-09T21:56:48.835Z,1757455008.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215648.00,A,3648.15367,N,12147.22450,W,0.097,30.78,090925,,,A*4A
2025-09-09T21:56:48.837Z,1757455008.837 [NAL9602](INFO): GPS fix at 20250909T215648: (36.802561, -121.787075)
2025-09-09T21:56:50.831Z,1757455010.831 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:56:52.059Z,1757455012.059 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215651.00,A,3648.15364,N,12147.22449,W,0.058,30.78,090925,,,A*4A
2025-09-09T21:56:52.061Z,1757455012.061 [NAL9602](INFO): GPS fix at 20250909T215651: (36.802561, -121.787075)
2025-09-09T21:56:54.889Z,1757455014.889 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215654.00,A,3648.15362,N,12147.22451,W,0.039,30.78,090925,,,A*47
2025-09-09T21:56:54.892Z,1757455014.892 [NAL9602](INFO): GPS fix at 20250909T215654: (36.802560, -121.787075)
2025-09-09T21:56:58.119Z,1757455018.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215657.00,A,3648.15386,N,12147.22432,W,0.933,42.27,090925,,,A*47
2025-09-09T21:56:58.121Z,1757455018.121 [NAL9602](INFO): GPS fix at 20250909T215657: (36.802564, -121.787072)
2025-09-09T21:57:00.966Z,1757455020.966 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215700.00,A,3648.15408,N,12147.22384,W,0.078,50.82,090925,,,A*45
2025-09-09T21:57:00.968Z,1757455020.968 [NAL9602](INFO): GPS fix at 20250909T215700: (36.802568, -121.787064)
2025-09-09T21:57:04.178Z,1757455024.178 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215703.00,A,3648.15424,N,12147.22375,W,0.603,50.82,090925,,,A*4C
2025-09-09T21:57:04.180Z,1757455024.180 [NAL9602](INFO): GPS fix at 20250909T215703: (36.802571, -121.787063)
2025-09-09T21:57:07.005Z,1757455027.005 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215706.00,A,3648.15451,N,12147.22337,W,0.330,50.82,090925,,,A*48
2025-09-09T21:57:07.015Z,1757455027.015 [NAL9602](INFO): GPS fix at 20250909T215706: (36.802575, -121.787056)
2025-09-09T21:57:10.245Z,1757455030.245 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215709.00,A,3648.15455,N,12147.22322,W,0.175,50.82,090925,,,A*44
2025-09-09T21:57:10.247Z,1757455030.247 [NAL9602](INFO): GPS fix at 20250909T215709: (36.802576, -121.787054)
2025-09-09T21:57:10.339Z,1757455030.339 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:57:13.189Z,1757455033.189 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215712.00,A,3648.15458,N,12147.22314,W,0.058,50.82,090925,,,A*48
2025-09-09T21:57:13.203Z,1757455033.203 [NAL9602](INFO): GPS fix at 20250909T215712: (36.802576, -121.787052)
2025-09-09T21:57:15.905Z,1757455035.905 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215715.00,A,3648.15461,N,12147.22298,W,0.214,50.82,090925,,,A*4A
2025-09-09T21:57:15.908Z,1757455035.908 [NAL9602](INFO): GPS fix at 20250909T215715: (36.802577, -121.787050)
2025-09-09T21:57:19.132Z,1757455039.132 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215718.00,A,3648.15468,N,12147.22290,W,0.078,50.82,090925,,,A*4E
2025-09-09T21:57:19.135Z,1757455039.135 [NAL9602](INFO): GPS fix at 20250909T215718: (36.802578, -121.787048)
2025-09-09T21:57:21.957Z,1757455041.957 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215721.00,A,3648.15451,N,12147.22285,W,0.136,50.82,090925,,,A*41
2025-09-09T21:57:21.959Z,1757455041.959 [NAL9602](INFO): GPS fix at 20250909T215721: (36.802575, -121.787047)
2025-09-09T21:57:25.189Z,1757455045.189 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215724.00,A,3648.15445,N,12147.22284,W,0.078,50.82,090925,,,A*4B
2025-09-09T21:57:25.192Z,1757455045.192 [NAL9602](INFO): GPS fix at 20250909T215724: (36.802574, -121.787047)
2025-09-09T21:57:28.015Z,1757455048.015 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215727.00,A,3648.15420,N,12147.22257,W,0.583,76.24,090925,,,A*4C
2025-09-09T21:57:28.017Z,1757455048.017 [NAL9602](INFO): GPS fix at 20250909T215727: (36.802570, -121.787043)
2025-09-09T21:57:29.499Z,1757455049.499 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:57:31.253Z,1757455051.253 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215730.00,A,3648.15365,N,12147.22230,W,0.019,76.24,090925,,,A*4B
2025-09-09T21:57:31.256Z,1757455051.256 [NAL9602](INFO): GPS fix at 20250909T215730: (36.802561, -121.787038)
2025-09-09T21:57:34.077Z,1757455054.077 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215733.00,A,3648.15362,N,12147.22224,W,0.292,76.24,090925,,,A*4B
2025-09-09T21:57:34.080Z,1757455054.080 [NAL9602](INFO): GPS fix at 20250909T215733: (36.802560, -121.787037)
2025-09-09T21:57:36.907Z,1757455056.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215736.00,A,3648.15344,N,12147.22196,W,0.467,76.24,090925,,,A*4C
2025-09-09T21:57:36.909Z,1757455056.909 [NAL9602](INFO): GPS fix at 20250909T215736: (36.802557, -121.787033)
2025-09-09T21:57:40.153Z,1757455060.153 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215739.00,A,3648.15310,N,12147.22187,W,0.758,76.24,090925,,,A*4D
2025-09-09T21:57:40.156Z,1757455060.156 [NAL9602](INFO): GPS fix at 20250909T215739: (36.802552, -121.787031)
2025-09-09T21:57:42.965Z,1757455062.965 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215742.00,A,3648.15278,N,12147.22177,W,0.603,76.24,090925,,,A*4E
2025-09-09T21:57:42.968Z,1757455062.968 [NAL9602](INFO): GPS fix at 20250909T215742: (36.802546, -121.787030)
2025-09-09T21:57:46.194Z,1757455066.194 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215745.00,A,3648.15246,N,12147.22157,W,0.778,145.10,090925,,,A*7D
2025-09-09T21:57:46.196Z,1757455066.196 [NAL9602](INFO): GPS fix at 20250909T215745: (36.802541, -121.787026)
2025-09-09T21:57:47.488Z,1757455067.488 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-09-09T21:57:47.905Z,1757455067.905 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2025-09-09T21:57:48.285Z,1757455068.285 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-09-09T21:57:48.285Z,1757455068.285 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-09-09T21:57:48.285Z,1757455068.285 [IBIT](IMPORTANT): Pressure:10.513229 PSI
2025-09-09T21:57:48.285Z,1757455068.285 [IBIT](IMPORTANT): Humidity:16.719570 %
2025-09-09T21:57:48.709Z,1757455068.709 [IBIT](IMPORTANT): Vehicle Pitch:0.556655 degrees
2025-09-09T21:57:48.709Z,1757455068.709 [IBIT](IMPORTANT): Vehicle Roll:0.799999 degrees
2025-09-09T21:57:48.709Z,1757455068.709 [IBIT](IMPORTANT): Vehicle Heading:190.790421 degrees
2025-09-09T21:57:48.835Z,1757455068.835 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T21:57:49.035Z,1757455069.035 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215748.00,A,3648.15225,N,12147.22139,W,0.778,161.62,090925,,,A*7E
2025-09-09T21:57:49.037Z,1757455069.037 [NAL9602](INFO): GPS fix at 20250909T215748: (36.802537, -121.787023)
2025-09-09T21:57:49.126Z,1757455069.126 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-09-09T21:57:49.127Z,1757455069.127 [IBIT](IMPORTANT): buoyancyNeutral: 408.601593 cc
2025-09-09T21:57:49.129Z,1757455069.129 [IBIT](IMPORTANT): massDefault: 1.954151 cm
2025-09-09T21:57:49.130Z,1757455069.130 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2025-09-09T21:57:49.131Z,1757455069.131 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2025-09-09T21:57:49.131Z,1757455069.131 [IBIT](IMPORTANT): IBIT FAILED
2025-09-09T21:57:49.468Z,1757455069.468 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-09T21:57:49.468Z,1757455069.468 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-09T21:57:49.468Z,1757455069.468 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-09T21:57:49.468Z,1757455069.468 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2025-09-09T21:57:49.845Z,1757455069.845 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2025-09-09T21:57:49.845Z,1757455069.845 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2025-09-09T21:57:50.259Z,1757455070.259 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 24.720219 min
2025-09-09T21:57:50.260Z,1757455070.260 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2025-09-09T21:57:50.260Z,1757455070.260 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T21:57:50.260Z,1757455070.260 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-09T21:57:50.260Z,1757455070.260 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T21:57:50.260Z,1757455070.260 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3
2025-09-09T21:57:50.260Z,1757455070.260 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3
2025-09-09T21:57:50.260Z,1757455070.260 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T21:57:50.260Z,1757455070.260 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-09T21:57:51.862Z,1757455071.862 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215751.00,A,3648.15176,N,12147.22124,W,0.583,168.56,090925,,,A*77
2025-09-09T21:57:51.865Z,1757455071.865 [NAL9602](INFO): GPS fix at 20250909T215751: (36.802529, -121.787021)
2025-09-09T21:57:51.885Z,1757455071.885 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-09T21:57:51.885Z,1757455071.885 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-09T21:57:55.726Z,1757455075.726 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20250909T213021/Courier0007.lzma
2025-09-09T21:58:06.496Z,1757455086.496 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Courier0007.lzma.bak
2025-09-09T21:58:06.497Z,1757455086.497 [DataOverHttps](INFO): SBD MOMSN=25955828
2025-09-09T21:58:24.328Z,1757455104.328 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-09T21:58:37.321Z,1757455117.321 [DataOverHttps](INFO): Sending 1595 bytes from file Logs/20250909T213021/Express0008.lzma
2025-09-09T21:58:48.348Z,1757455128.348 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Express0008.lzma.bak
2025-09-09T21:58:48.348Z,1757455128.348 [DataOverHttps](INFO): SBD MOMSN=25955831
2025-09-09T21:59:15.296Z,1757455155.296 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250909T213021/Express0011.lzma
2025-09-09T21:59:26.336Z,1757455166.336 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Express0011.lzma.bak
2025-09-09T21:59:26.336Z,1757455166.336 [DataOverHttps](INFO): SBD MOMSN=25955899
2025-09-09T21:59:27.436Z,1757455167.436 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-09T21:59:27.436Z,1757455167.436 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-09T21:59:27.436Z,1757455167.436 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-09T21:59:46.655Z,1757455186.655 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:00:05.779Z,1757455205.779 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:00:28.219Z,1757455228.219 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:00:47.249Z,1757455247.249 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:01:06.035Z,1757455266.035 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:01:25.227Z,1757455285.227 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:01:35.879Z,1757455295.879 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2025-09-09T22:01:35.882Z,1757455295.882 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-09-09T22:01:44.095Z,1757455304.095 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:02:03.035Z,1757455323.035 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:02:25.079Z,1757455345.079 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:02:43.791Z,1757455363.791 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:03:02.867Z,1757455382.867 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:03:06.855Z,1757455386.855 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,25090914042150,35.0, +22.6, 0.0 0
2025-09-09T22:03:21.699Z,1757455401.699 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:03:40.739Z,1757455420.739 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:03:59.611Z,1757455439.611 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:04:20.083Z,1757455460.083 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:04:28.071Z,1757455468.071 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-09T22:04:28.072Z,1757455468.072 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-09T22:04:28.072Z,1757455468.072 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-09T22:04:28.072Z,1757455468.072 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2025-09-09T22:04:28.486Z,1757455468.486 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2025-09-09T22:04:28.486Z,1757455468.486 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2025-09-09T22:04:28.912Z,1757455468.912 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 31.364223 min
2025-09-09T22:04:28.912Z,1757455468.912 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2025-09-09T22:04:28.912Z,1757455468.912 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:04:28.912Z,1757455468.912 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-09T22:04:28.912Z,1757455468.912 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:04:28.912Z,1757455468.912 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #4
2025-09-09T22:04:28.912Z,1757455468.912 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=4
2025-09-09T22:04:28.913Z,1757455468.913 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:04:28.913Z,1757455468.913 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-09T22:04:30.893Z,1757455470.893 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220430.00,A,3648.15334,N,12147.22241,W,0.019,242.90,090925,,,A*76
2025-09-09T22:04:30.896Z,1757455470.896 [NAL9602](INFO): GPS fix at 20250909T220430: (36.802556, -121.787040)
2025-09-09T22:04:30.954Z,1757455470.954 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-09T22:04:30.954Z,1757455470.954 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-09T22:04:45.610Z,1757455485.610 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20250909T213021/Courier0013.lzma
2025-09-09T22:04:56.652Z,1757455496.652 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Courier0013.lzma.bak
2025-09-09T22:04:56.652Z,1757455496.652 [DataOverHttps](INFO): SBD MOMSN=25955923
2025-09-09T22:05:23.534Z,1757455523.534 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250909T213021/Express0014.lzma
2025-09-09T22:05:33.512Z,1757455533.512 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-09T22:05:34.576Z,1757455534.576 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Express0014.lzma.bak
2025-09-09T22:05:34.576Z,1757455534.576 [DataOverHttps](INFO): SBD MOMSN=25955926
2025-09-09T22:05:35.944Z,1757455535.944 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-09T22:05:35.944Z,1757455535.944 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-09T22:05:35.944Z,1757455535.944 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-09T22:05:54.479Z,1757455554.479 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:06:04.211Z,1757455564.211 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-09T22:06:13.523Z,1757455573.523 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:06:32.439Z,1757455592.439 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:06:51.327Z,1757455611.327 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:07:10.103Z,1757455630.103 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:07:28.927Z,1757455648.927 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:07:48.019Z,1757455668.019 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:07:58.981Z,1757455678.981 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BS,-32768,-32768,-3.97
2025-09-09T22:08:07.015Z,1757455687.015 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:08:29.059Z,1757455709.059 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:08:47.779Z,1757455727.779 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:09:06.687Z,1757455746.687 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:09:25.479Z,1757455765.479 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:09:44.488Z,1757455784.488 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:10:03.523Z,1757455803.523 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:10:25.935Z,1757455825.935 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:10:36.662Z,1757455836.662 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-09T22:10:36.662Z,1757455836.662 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-09T22:10:36.663Z,1757455836.663 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-09T22:10:36.663Z,1757455836.663 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2025-09-09T22:10:37.101Z,1757455837.101 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2025-09-09T22:10:37.102Z,1757455837.102 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2025-09-09T22:10:37.473Z,1757455837.473 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 37.507821 min
2025-09-09T22:10:37.473Z,1757455837.473 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2025-09-09T22:10:37.473Z,1757455837.473 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:10:37.473Z,1757455837.473 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-09T22:10:37.473Z,1757455837.473 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:10:37.473Z,1757455837.473 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #5
2025-09-09T22:10:37.473Z,1757455837.473 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=5
2025-09-09T22:10:37.473Z,1757455837.473 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:10:37.473Z,1757455837.473 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-09T22:10:39.481Z,1757455839.481 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221038.00,A,3648.15283,N,12147.22106,W,0.039,242.90,090925,,,A*74
2025-09-09T22:10:39.484Z,1757455839.484 [NAL9602](INFO): GPS fix at 20250909T221038: (36.802547, -121.787018)
2025-09-09T22:10:39.524Z,1757455839.524 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-09T22:10:39.524Z,1757455839.524 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-09T22:10:47.204Z,1757455847.204 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0.00, 20.00,1348.53
2025-09-09T22:10:51.546Z,1757455851.546 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250909T213021/Courier0016.lzma
2025-09-09T22:11:02.588Z,1757455862.588 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Courier0016.lzma.bak
2025-09-09T22:11:02.588Z,1757455862.588 [DataOverHttps](INFO): SBD MOMSN=25955929
2025-09-09T22:11:10.188Z,1757455870.188 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-09T22:11:10.262Z,1757455870.262 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-09-09T22:11:23.923Z,1757455883.923 [NAL9602](INFO): SBD MO Status=0, MOMSN=49676, MT Status=0, MTMSN=0
2025-09-09T22:11:23.923Z,1757455883.923 [NAL9602](INFO): No messages in MT queue
2025-09-09T22:11:29.161Z,1757455889.161 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250909T213021/Express0017.lzma
2025-09-09T22:11:40.196Z,1757455900.196 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Express0017.lzma.bak
2025-09-09T22:11:40.196Z,1757455900.196 [DataOverHttps](INFO): SBD MOMSN=25955932
2025-09-09T22:11:41.310Z,1757455901.310 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-09T22:11:41.310Z,1757455901.310 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-09T22:11:41.310Z,1757455901.310 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-09T22:11:54.635Z,1757455914.635 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-09T22:11:59.943Z,1757455919.943 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:12:21.791Z,1757455941.791 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:12:40.776Z,1757455960.776 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:12:59.595Z,1757455979.595 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:13:18.475Z,1757455998.475 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:13:37.318Z,1757456017.318 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:13:56.375Z,1757456036.375 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:14:15.243Z,1757456055.243 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:14:34.291Z,1757456074.291 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:14:53.155Z,1757456093.155 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:15:11.124Z,1757456111.124 [DataOverHttps](IMPORTANT): SBD MTMSN=20250909T221510
2025-09-09T22:15:18.942Z,1757456118.942 [DataOverHttps](INFO): Received command: ibit
2025-09-09T22:15:19.003Z,1757456119.003 [CommandExec](IMPORTANT): got command ibit
2025-09-09T22:15:19.181Z,1757456119.181 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-09-09T22:15:19.181Z,1757456119.181 [IBIT](IMPORTANT): Beginning control surface checks.
2025-09-09T22:15:19.184Z,1757456119.184 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-09-09T22:15:20.716Z,1757456120.716 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221519.00,A,3648.47986,N,12147.12017,W,6.240,340.08,090925,,,D*74
2025-09-09T22:15:20.718Z,1757456120.718 [NAL9602](INFO): GPS fix at 20250909T221519: (36.807998, -121.785336)
2025-09-09T22:15:23.762Z,1757456123.762 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006266
CHAN A1 (24V): -0.001657
CHAN A2 (12V): 0.003744
CHAN A3 (5V): -0.000483
CHAN B0 (3.3V): -0.000749
CHAN B1 (3.15aV): -0.000584
CHAN B2 (3.15bV): -0.001284
CHAN B3 (GND): -0.002432
OPEN: 0.005888
Full Scale: +/- 1 mA
2025-09-09T22:15:30.988Z,1757456130.988 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:15:32.987Z,1757456132.987 [NAL9602](INFO): SBD MO Status=0, MOMSN=49677, MT Status=0, MTMSN=0
2025-09-09T22:15:32.987Z,1757456132.987 [NAL9602](INFO): No messages in MT queue
2025-09-09T22:15:34.201Z,1757456134.201 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221533.00,A,3648.49993,N,12147.13507,W,5.793,316.41,090925,,,D*75
2025-09-09T22:15:34.204Z,1757456134.204 [NAL9602](INFO): GPS fix at 20250909T221533: (36.808332, -121.785584)
2025-09-09T22:15:37.029Z,1757456137.029 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221536.00,A,3648.50318,N,12147.13946,W,5.832,309.37,090925,,,D*73
2025-09-09T22:15:37.031Z,1757456137.031 [NAL9602](INFO): GPS fix at 20250909T221536: (36.808386, -121.785658)
2025-09-09T22:15:40.262Z,1757456140.262 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221539.00,A,3648.50586,N,12147.14464,W,6.143,301.88,090925,,,D*77
2025-09-09T22:15:40.264Z,1757456140.264 [NAL9602](INFO): GPS fix at 20250909T221539: (36.808431, -121.785744)
2025-09-09T22:15:43.087Z,1757456143.087 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221542.00,A,3648.50828,N,12147.15056,W,6.492,295.38,090925,,,D*78
2025-09-09T22:15:43.089Z,1757456143.089 [NAL9602](INFO): GPS fix at 20250909T221542: (36.808471, -121.785843)
2025-09-09T22:15:45.931Z,1757456145.931 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221545.00,A,3648.51039,N,12147.15695,W,6.687,288.68,090925,,,D*70
2025-09-09T22:15:45.933Z,1757456145.933 [NAL9602](INFO): GPS fix at 20250909T221545: (36.808507, -121.785949)
2025-09-09T22:15:49.164Z,1757456149.164 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221548.00,A,3648.51179,N,12147.16379,W,6.881,281.49,090925,,,D*7E
2025-09-09T22:15:49.166Z,1757456149.166 [NAL9602](INFO): GPS fix at 20250909T221548: (36.808530, -121.786063)
2025-09-09T22:15:50.227Z,1757456150.227 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:15:51.989Z,1757456151.989 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221551.00,A,3648.51235,N,12147.17108,W,7.251,274.38,090925,,,D*72
2025-09-09T22:15:51.992Z,1757456151.992 [NAL9602](INFO): GPS fix at 20250909T221551: (36.808539, -121.786185)
2025-09-09T22:15:55.305Z,1757456155.305 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221554.00,A,3648.51226,N,12147.17867,W,7.406,265.31,090925,,,D*78
2025-09-09T22:15:55.312Z,1757456155.312 [NAL9602](INFO): GPS fix at 20250909T221554: (36.808538, -121.786311)
2025-09-09T22:15:58.114Z,1757456158.114 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221557.00,A,3648.51130,N,12147.18633,W,7.717,260.06,090925,,,D*7D
2025-09-09T22:15:58.116Z,1757456158.116 [NAL9602](INFO): GPS fix at 20250909T221557: (36.808522, -121.786439)
2025-09-09T22:16:00.936Z,1757456160.936 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221600.00,A,3648.50976,N,12147.19433,W,8.164,255.12,090925,,,D*7A
2025-09-09T22:16:00.938Z,1757456160.938 [NAL9602](INFO): GPS fix at 20250909T221600: (36.808496, -121.786572)
2025-09-09T22:16:04.167Z,1757456164.167 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221603.00,A,3648.50772,N,12147.20249,W,8.436,250.84,090925,,,D*7A
2025-09-09T22:16:04.169Z,1757456164.169 [NAL9602](INFO): GPS fix at 20250909T221603: (36.808462, -121.786708)
2025-09-09T22:16:05.134Z,1757456165.134 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.808460 Longitude: -121.786705
2025-09-09T22:16:05.498Z,1757456165.498 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.937000
2025-09-09T22:16:05.498Z,1757456165.498 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-09-09T22:16:05.498Z,1757456165.498 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-09-09T22:16:05.877Z,1757456165.877 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-09-09T22:16:05.877Z,1757456165.877 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-09-09T22:16:05.877Z,1757456165.877 [IBIT](IMPORTANT): Pressure:10.542793 PSI
2025-09-09T22:16:05.878Z,1757456165.878 [IBIT](IMPORTANT): Humidity:16.304480 %
2025-09-09T22:16:06.277Z,1757456166.277 [IBIT](IMPORTANT): Vehicle Pitch:2.914084 degrees
2025-09-09T22:16:06.278Z,1757456166.278 [IBIT](IMPORTANT): Vehicle Roll:1.875520 degrees
2025-09-09T22:16:06.278Z,1757456166.278 [IBIT](IMPORTANT): Vehicle Heading:251.227554 degrees
2025-09-09T22:16:06.665Z,1757456166.665 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-09-09T22:16:06.665Z,1757456166.665 [IBIT](IMPORTANT): buoyancyNeutral: 408.601593 cc
2025-09-09T22:16:06.665Z,1757456166.665 [IBIT](IMPORTANT): massDefault: 1.954151 cm
2025-09-09T22:16:06.666Z,1757456166.666 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2025-09-09T22:16:06.666Z,1757456166.666 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2025-09-09T22:16:06.666Z,1757456166.666 [IBIT](IMPORTANT): IBIT PASSED
2025-09-09T22:16:09.767Z,1757456169.767 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:16:29.055Z,1757456189.055 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:16:36.484Z,1757456196.484 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-09T22:16:41.765Z,1757456201.765 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-09T22:16:41.765Z,1757456201.765 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-09T22:16:41.765Z,1757456201.765 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-09T22:16:41.765Z,1757456201.765 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2025-09-09T22:16:42.152Z,1757456202.152 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2025-09-09T22:16:42.152Z,1757456202.152 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2025-09-09T22:16:42.560Z,1757456202.560 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 43.591992 min
2025-09-09T22:16:42.560Z,1757456202.560 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2025-09-09T22:16:42.560Z,1757456202.560 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:16:42.560Z,1757456202.560 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-09T22:16:42.560Z,1757456202.560 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:16:42.560Z,1757456202.560 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #6
2025-09-09T22:16:42.560Z,1757456202.560 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=6
2025-09-09T22:16:42.560Z,1757456202.560 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:16:42.560Z,1757456202.560 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-09T22:16:44.566Z,1757456204.566 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221643.00,A,3648.45469,N,12147.31688,W,9.875,237.47,090925,,,D*7E
2025-09-09T22:16:44.568Z,1757456204.568 [NAL9602](INFO): GPS fix at 20250909T221643: (36.807578, -121.788615)
2025-09-09T22:16:44.599Z,1757456204.599 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-09T22:16:44.599Z,1757456204.599 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-09T22:16:53.710Z,1757456213.710 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250909T213021/Courier0019.lzma
2025-09-09T22:17:00.736Z,1757456220.736 [NAL9602](INFO): SBD MO Status=0, MOMSN=49678, MT Status=0, MTMSN=0
2025-09-09T22:17:00.736Z,1757456220.736 [NAL9602](INFO): No messages in MT queue
2025-09-09T22:17:04.752Z,1757456224.752 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Courier0019.lzma.bak
2025-09-09T22:17:04.752Z,1757456224.752 [DataOverHttps](INFO): SBD MOMSN=25955969
2025-09-09T22:17:31.409Z,1757456251.409 [DataOverHttps](INFO): Sending 1013 bytes from file Logs/20250909T213021/Express0020.lzma
2025-09-09T22:17:31.438Z,1757456251.438 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-09T22:17:42.448Z,1757456262.448 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Express0020.lzma.bak
2025-09-09T22:17:42.448Z,1757456262.448 [DataOverHttps](INFO): SBD MOMSN=25955973
2025-09-09T22:18:12.837Z,1757456292.837 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250909T213021/Express0023.lzma
2025-09-09T22:18:23.884Z,1757456303.884 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Express0023.lzma.bak
2025-09-09T22:18:23.884Z,1757456303.884 [DataOverHttps](INFO): SBD MOMSN=25956012
2025-09-09T22:18:24.900Z,1757456304.900 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-09T22:18:24.900Z,1757456304.900 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-09T22:18:24.900Z,1757456304.900 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-09T22:18:43.823Z,1757456323.823 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:19:02.779Z,1757456342.779 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:19:21.651Z,1757456361.651 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:19:41.431Z,1757456381.431 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:20:05.463Z,1757456405.463 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:20:27.707Z,1757456427.707 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:20:46.571Z,1757456446.571 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:21:05.327Z,1757456465.327 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:21:24.332Z,1757456484.332 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:21:42.856Z,1757456502.856 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS4225750,35.0, +22.2, 0.0,1527.4, 0
2025-09-09T22:21:43.531Z,1757456503.531 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:22:02.543Z,1757456522.543 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:22:03.049Z,1757456523.049 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,250909142.0, +22.1, 0.0,1527.2, 0
2025-09-09T22:22:24.796Z,1757456544.796 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:22:43.567Z,1757456563.567 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:23:02.660Z,1757456582.660 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:23:22.839Z,1757456602.839 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:23:25.554Z,1757456605.554 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-09T22:23:25.554Z,1757456605.554 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-09T22:23:25.554Z,1757456605.554 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-09T22:23:25.554Z,1757456605.554 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2025-09-09T22:23:25.967Z,1757456605.967 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2025-09-09T22:23:25.967Z,1757456605.967 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2025-09-09T22:23:26.359Z,1757456606.359 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 50.322241 min
2025-09-09T22:23:26.359Z,1757456606.359 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2025-09-09T22:23:26.360Z,1757456606.360 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:23:26.360Z,1757456606.360 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-09T22:23:26.360Z,1757456606.360 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:23:26.360Z,1757456606.360 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #7
2025-09-09T22:23:26.360Z,1757456606.360 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=7
2025-09-09T22:23:26.360Z,1757456606.360 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:23:26.360Z,1757456606.360 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-09T22:23:28.358Z,1757456608.358 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222327.00,A,3647.60866,N,12149.85672,W,23.929,255.35,090925,,,D*44
2025-09-09T22:23:28.360Z,1757456608.360 [NAL9602](INFO): GPS fix at 20250909T222327: (36.793478, -121.830945)
2025-09-09T22:23:28.380Z,1757456608.380 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-09T22:23:28.380Z,1757456608.380 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-09T22:23:30.953Z,1757456610.953 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250909T213021/Courier0025.lzma
2025-09-09T22:23:42.996Z,1757456622.996 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Courier0025.lzma.bak
2025-09-09T22:23:42.996Z,1757456622.996 [DataOverHttps](INFO): SBD MOMSN=25956022
2025-09-09T22:23:44.182Z,1757456624.182 [NAL9602](INFO): SBD MO Status=0, MOMSN=49679, MT Status=0, MTMSN=0
2025-09-09T22:23:44.183Z,1757456624.183 [NAL9602](INFO): No messages in MT queue
2025-09-09T22:24:14.943Z,1757456654.943 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-09T22:24:19.302Z,1757456659.302 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250909T213021/Express0026.lzma
2025-09-09T22:24:31.348Z,1757456671.348 [DataOverHttps](INFO): Moved sent file to Logs/20250909T213021/Express0026.lzma.bak
2025-09-09T22:24:31.348Z,1757456671.348 [DataOverHttps](INFO): SBD MOMSN=25956030
2025-09-09T22:24:32.733Z,1757456672.733 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-09T22:24:32.733Z,1757456672.733 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-09T22:24:32.733Z,1757456672.733 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-09T22:24:51.375Z,1757456691.375 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:25:10.375Z,1757456710.375 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:25:40.495Z,1757456740.495 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-09T22:25:41.500Z,1757456741.500 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:26:00.363Z,1757456760.363 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:26:20.579Z,1757456780.579 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:26:50.727Z,1757456810.727 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-09-09T22:26:51.731Z,1757456811.731 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:27:11.539Z,1757456831.539 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:27:31.349Z,1757456851.349 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:27:53.151Z,1757456873.151 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:28:23.163Z,1757456903.163 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-09-09T22:28:24.167Z,1757456904.167 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:28:52.280Z,1757456932.280 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.131233
2025-09-09T22:28:53.283Z,1757456933.283 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:29:23.403Z,1757456963.403 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-09T22:29:24.407Z,1757456964.407 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:29:33.299Z,1757456973.299 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-09T22:29:33.299Z,1757456973.299 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-09T22:29:33.299Z,1757456973.299 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-09T22:29:33.299Z,1757456973.299 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2025-09-09T22:29:33.702Z,1757456973.702 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2025-09-09T22:29:33.702Z,1757456973.702 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2025-09-09T22:29:34.111Z,1757456974.111 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 56.451156 min
2025-09-09T22:29:34.111Z,1757456974.111 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2025-09-09T22:29:34.111Z,1757456974.111 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:29:34.111Z,1757456974.111 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-09T22:29:34.112Z,1757456974.112 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:29:34.112Z,1757456974.112 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #8
2025-09-09T22:29:34.112Z,1757456974.112 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=8
2025-09-09T22:29:34.112Z,1757456974.112 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T22:29:34.112Z,1757456974.112 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-09T22:29:36.114Z,1757456976.114 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222935.00,A,3646.97967,N,12151.97865,W,0.816,78.50,090925,,,D*44
2025-09-09T22:29:36.116Z,1757456976.116 [NAL9602](INFO): GPS fix at 20250909T222935: (36.782995, -121.866311)
2025-09-09T22:29:36.128Z,1757456976.128 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-09T22:29:36.128Z,1757456976.128 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-09T22:29:54.531Z,1757456994.531 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-09T22:29:55.535Z,1757456995.535 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:29:55.581Z,1757456995.581 [DAT](INFO): DAT read: user:6>22:29:53.5547 LVL= 32752, 32753, 29586, 32755, AGC= 74, IDX= 319, 0.43,-0.973,-2.643,-1.463,-1.699, PHS= 0.814,-0.897, 0.233, RAW= 130.5, -2.0, CAL= 136.2, -11.6, ROT= 13.8, 11.6
2025-09-09T22:29:55.582Z,1757456995.582 [DAT](INFO): DAT read: Rx Time:22:29:53.5547
2025-09-09T22:29:55.583Z,1757456995.583 [DAT](INFO): Rx dataTimestamp_ set to:1757456995.582563
2025-09-09T22:29:55.584Z,1757456995.584 [DAT](INFO): DAT read: $Low SNR acquisition
2025-09-09T22:29:55.584Z,1757456995.584 [DAT](INFO): Received low SNR in chirp
2025-09-09T22:29:57.339Z,1757456997.339 [DAT](INFO): DAT read: Rx Time:22:29:55.2892
2025-09-09T22:29:57.340Z,1757456997.340 [DAT](INFO): Rx dataTimestamp_ set to:1757456997.339607
2025-09-09T22:29:57.843Z,1757456997.843 [DAT](INFO): DAT read:
2025-09-09T22:29:57.844Z,1757456997.844 [DAT](INFO): DAT read: $Packet for address 11
2025-09-09T22:29:57.844Z,1757456997.844 [DAT](INFO): received a packet notification
2025-09-09T22:30:00.065Z,1757457000.065 [NAL9602](INFO): SBD MO Status=1, MOMSN=49680, MT Status=0, MTMSN=0
2025-09-09T22:30:00.127Z,1757457000.127 [NAL9602](INFO): Sent 74 bytes from file Logs/20250909T213021/Courier0028.lzma
2025-09-09T22:30:00.127Z,1757457000.127 [NAL9602](INFO): Packets left to send: 0
2025-09-09T22:30:08.704Z,1757457008.704 [NAL9602](INFO): SBD MO Status=1, MOMSN=49681, MT Status=0, MTMSN=0
2025-09-09T22:30:08.755Z,1757457008.755 [NAL9602](INFO): Sent 168 bytes from file Logs/20250909T213021/Express0029.lzma
2025-09-09T22:30:08.755Z,1757457008.755 [NAL9602](INFO): Packets left to send: 0
2025-09-09T22:30:17.581Z,1757457017.581 [NAL9602](INFO): SBD MO Status=0, MOMSN=49682, MT Status=0, MTMSN=0
2025-09-09T22:30:17.665Z,1757457017.665 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-09T22:30:17.665Z,1757457017.665 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-09T22:30:17.665Z,1757457017.665 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-09T22:30:25.663Z,1757457025.663 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-09T22:30:26.667Z,1757457026.667 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:30:48.291Z,1757457048.291 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-09T22:30:56.804Z,1757457056.804 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-09T22:30:57.807Z,1757457057.807 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:31:27.939Z,1757457087.939 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-09T22:31:28.943Z,1757457088.943 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:31:59.063Z,1757457119.063 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-09T22:32:00.068Z,1757457120.068 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:32:30.191Z,1757457150.191 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-09T22:32:31.195Z,1757457151.195 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:33:01.315Z,1757457181.315 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-09T22:33:01.923Z,1757457181.923 [DAT](INFO): DAT read: Rx Time:22:32:59.9258
2025-09-09T22:33:01.924Z,1757457181.924 [DAT](INFO): Rx dataTimestamp_ set to:1757457181.923558
2025-09-09T22:33:02.319Z,1757457182.319 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:33:02.427Z,1757457182.427 [DAT](INFO): DAT read:
2025-09-09T22:33:02.428Z,1757457182.428 [DAT](INFO): DAT read: $Packet for address 21
2025-09-09T22:33:02.428Z,1757457182.428 [DAT](INFO): received a packet notification
2025-09-09T22:33:32.443Z,1757457212.443 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-09T22:33:33.447Z,1757457213.447 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T22:33:51.488Z,1757457231.488 [DataOverHttps](IMPORTANT): SBD MTMSN=20250909T223350
2025-09-09T22:33:51.491Z,1757457231.491 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003420
2025-09-09T22:33:59.415Z,1757457239.415 [DataOverHttps](INFO): Received command: restart logs