2025-12-17T22:42:16.862Z,1766011336.862 [Supervisor](DEBUG): Initializing supervisor.
2025-12-17T22:42:16.867Z,1766011336.867 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-12-17T22:42:16.867Z,1766011336.867 [SyncHandler](INFO): Protected caller Thread ID is 2868
2025-12-17T22:42:16.868Z,1766011336.868 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-12-17T22:42:16.869Z,1766011336.869 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-12-17T22:42:16.869Z,1766011336.869 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2869
2025-12-17T22:42:16.873Z,1766011336.873 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-12-17T22:42:16.893Z,1766011336.893 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-12-17T22:42:16.894Z,1766011336.894 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-12-17T22:42:16.894Z,1766011336.894 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2870
2025-12-17T22:42:16.899Z,1766011336.899 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-12-17T22:42:16.900Z,1766011336.900 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-12-17T22:42:16.900Z,1766011336.900 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2871
2025-12-17T22:42:16.902Z,1766011336.902 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-12-17T22:42:16.903Z,1766011336.903 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-12-17T22:42:16.903Z,1766011336.903 [logger ThreadHandler](INFO): Protected caller Thread ID is 2872
2025-12-17T22:42:16.907Z,1766011336.907 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-12-17T22:42:16.908Z,1766011336.908 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-12-17T22:42:16.909Z,1766011336.909 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-12-17T22:42:17.300Z,1766011337.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-12-17T22:42:17.300Z,1766011337.300 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-12-17T22:42:18.052Z,1766011338.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-12-17T22:42:18.053Z,1766011338.053 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-12-17T22:42:18.171Z,1766011338.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-12-17T22:42:18.172Z,1766011338.172 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-12-17T22:42:18.708Z,1766011338.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-12-17T22:42:18.709Z,1766011338.709 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-12-17T22:42:19.386Z,1766011339.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-12-17T22:42:19.387Z,1766011339.387 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-12-17T22:42:19.488Z,1766011339.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-12-17T22:42:19.489Z,1766011339.489 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-12-17T22:42:19.905Z,1766011339.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-12-17T22:42:19.905Z,1766011339.905 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-12-17T22:42:20.133Z,1766011340.133 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-12-17T22:42:20.134Z,1766011340.134 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-12-17T22:42:20.279Z,1766011340.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-12-17T22:42:20.280Z,1766011340.280 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-12-17T22:42:20.537Z,1766011340.537 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-12-17T22:42:20.537Z,1766011340.537 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-12-17T22:42:21.094Z,1766011341.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-12-17T22:42:21.095Z,1766011341.095 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-12-17T22:42:21.484Z,1766011341.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-12-17T22:42:21.485Z,1766011341.485 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-12-17T22:42:21.573Z,1766011341.573 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-12-17T22:42:21.763Z,1766011341.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-12-17T22:42:21.763Z,1766011341.763 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-12-17T22:42:22.013Z,1766011342.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-12-17T22:42:22.014Z,1766011342.014 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-12-17T22:42:22.316Z,1766011342.316 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-12-17T22:42:22.318Z,1766011342.318 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2025-12-17T22:42:22.319Z,1766011342.319 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2025-12-17T22:42:22.425Z,1766011342.425 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2025-12-17T22:42:22.582Z,1766011342.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2025-12-17T22:42:22.676Z,1766011342.676 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2025-12-17T22:42:22.769Z,1766011342.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2025-12-17T22:42:22.888Z,1766011342.888 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg
2025-12-17T22:42:22.989Z,1766011342.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Dock.cfg
2025-12-17T22:42:23.108Z,1766011343.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2025-12-17T22:42:23.220Z,1766011343.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2025-12-17T22:42:23.493Z,1766011343.493 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-12-17T22:42:23.493Z,1766011343.493 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2025-12-17T22:42:23.633Z,1766011343.633 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2025-12-17T22:42:23.742Z,1766011343.742 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2025-12-17T22:42:24.395Z,1766011344.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2025-12-17T22:42:24.502Z,1766011344.502 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-12-17T22:42:24.515Z,1766011344.515 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-12-17T22:42:24.672Z,1766011344.672 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-12-17T22:42:24.672Z,1766011344.672 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-12-17T22:42:24.682Z,1766011344.682 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-12-17T22:42:24.683Z,1766011344.683 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-12-17T22:42:24.739Z,1766011344.739 [DepthRateCalculator] Loaded
2025-12-17T22:42:24.739Z,1766011344.739 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-12-17T22:42:24.745Z,1766011344.745 [PitchRateCalculator] Loaded
2025-12-17T22:42:24.745Z,1766011344.745 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-12-17T22:42:24.755Z,1766011344.755 [SpeedCalculator] Loaded
2025-12-17T22:42:24.756Z,1766011344.756 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-12-17T22:42:24.761Z,1766011344.761 [YawRateCalculator] Loaded
2025-12-17T22:42:24.761Z,1766011344.761 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-12-17T22:42:24.782Z,1766011344.782 [ElevatorOffsetCalculator] Loaded
2025-12-17T22:42:24.783Z,1766011344.783 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-12-17T22:42:24.783Z,1766011344.783 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-12-17T22:42:24.784Z,1766011344.784 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-12-17T22:42:24.837Z,1766011344.837 [VerticalControl](DEBUG): Construct VerticalControl.
2025-12-17T22:42:24.892Z,1766011344.892 [VerticalControl] Loaded
2025-12-17T22:42:24.893Z,1766011344.893 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-12-17T22:42:24.895Z,1766011344.895 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-12-17T22:42:24.943Z,1766011344.943 [HorizontalControl] Loaded
2025-12-17T22:42:24.943Z,1766011344.943 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-12-17T22:42:24.945Z,1766011344.945 [SpeedControl](DEBUG): Construct SpeedControl.
2025-12-17T22:42:24.948Z,1766011344.948 [SpeedControl] Loaded
2025-12-17T22:42:24.949Z,1766011344.949 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-12-17T22:42:24.951Z,1766011344.951 [LoopControl](DEBUG): Construct LoopControl.
2025-12-17T22:42:24.952Z,1766011344.952 [LoopControl] Loaded
2025-12-17T22:42:24.952Z,1766011344.952 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-12-17T22:42:24.953Z,1766011344.953 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-12-17T22:42:24.953Z,1766011344.953 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-12-17T22:42:25.065Z,1766011345.065 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-12-17T22:42:25.065Z,1766011345.065 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-12-17T22:42:25.079Z,1766011345.079 [NavChart] Loaded
2025-12-17T22:42:25.079Z,1766011345.079 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-12-17T22:42:25.085Z,1766011345.085 [UniversalFixResidualReporter] Loaded
2025-12-17T22:42:25.085Z,1766011345.085 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-12-17T22:42:25.086Z,1766011345.086 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-12-17T22:42:25.086Z,1766011345.086 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-12-17T22:42:25.264Z,1766011345.264 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-12-17T22:42:25.264Z,1766011345.264 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-12-17T22:42:25.758Z,1766011345.758 [AHRS_M2] Loaded
2025-12-17T22:42:25.758Z,1766011345.758 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-12-17T22:42:25.797Z,1766011345.797 [BackseatComponent] Loaded
2025-12-17T22:42:25.798Z,1766011345.798 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-12-17T22:42:25.799Z,1766011345.799 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409404E0
2025-12-17T22:42:25.799Z,1766011345.799 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2960
2025-12-17T22:42:25.802Z,1766011345.802 [LcmPublisher](INFO): Full slate access is ENABLED. All requested slate values (including non-universals) will be published on LCM.
2025-12-17T22:42:25.802Z,1766011345.802 [LcmPublisher] Loaded
2025-12-17T22:42:25.803Z,1766011345.803 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2025-12-17T22:42:26.637Z,1766011346.637 [BPC1] Loaded
2025-12-17T22:42:26.637Z,1766011346.637 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-12-17T22:42:26.736Z,1766011346.736 [DAT] Loaded
2025-12-17T22:42:26.736Z,1766011346.736 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-12-17T22:42:26.737Z,1766011346.737 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409704E0
2025-12-17T22:42:26.737Z,1766011346.737 [DAT ThreadHandler](INFO): Protected caller Thread ID is 2961
2025-12-17T22:42:26.810Z,1766011346.810 [DataOverHttps] Loaded
2025-12-17T22:42:26.811Z,1766011346.811 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-12-17T22:42:26.812Z,1766011346.812 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0
2025-12-17T22:42:26.812Z,1766011346.812 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2962
2025-12-17T22:42:26.836Z,1766011346.836 [Depth_Keller] Loaded
2025-12-17T22:42:26.836Z,1766011346.836 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-12-17T22:42:26.841Z,1766011346.841 [DropWeight] Loaded
2025-12-17T22:42:26.841Z,1766011346.841 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-12-17T22:42:26.905Z,1766011346.905 [NAL9602] Loaded
2025-12-17T22:42:26.905Z,1766011346.905 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-12-17T22:42:26.934Z,1766011346.934 [Onboard] Loaded
2025-12-17T22:42:26.935Z,1766011346.935 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-12-17T22:42:26.936Z,1766011346.936 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409D04E0
2025-12-17T22:42:26.936Z,1766011346.936 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2963
2025-12-17T22:42:26.950Z,1766011346.950 [Power24vConverter] Loaded
2025-12-17T22:42:26.950Z,1766011346.950 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-12-17T22:42:26.966Z,1766011346.966 [Radio_Surface] Loaded
2025-12-17T22:42:26.966Z,1766011346.966 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-12-17T22:42:26.968Z,1766011346.968 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A004E0
2025-12-17T22:42:26.968Z,1766011346.968 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2964
2025-12-17T22:42:27.014Z,1766011347.014 [RDI_Pathfinder] Loaded
2025-12-17T22:42:27.014Z,1766011347.014 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-12-17T22:42:27.030Z,1766011347.030 [Sonardyne_Nano] Loaded
2025-12-17T22:42:27.030Z,1766011347.030 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-12-17T22:42:27.030Z,1766011347.030 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-12-17T22:42:27.031Z,1766011347.031 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-12-17T22:42:27.165Z,1766011347.165 [BuoyancyServo] Loaded
2025-12-17T22:42:27.166Z,1766011347.166 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-12-17T22:42:27.189Z,1766011347.189 [ElevatorServo] Loaded
2025-12-17T22:42:27.189Z,1766011347.189 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-12-17T22:42:27.211Z,1766011347.211 [MassServo] Loaded
2025-12-17T22:42:27.212Z,1766011347.212 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-12-17T22:42:27.238Z,1766011347.238 [RudderServo] Loaded
2025-12-17T22:42:27.238Z,1766011347.238 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-12-17T22:42:27.255Z,1766011347.255 [ThrusterHE] Loaded
2025-12-17T22:42:27.255Z,1766011347.255 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-12-17T22:42:27.255Z,1766011347.255 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-12-17T22:42:27.256Z,1766011347.256 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-12-17T22:42:27.315Z,1766011347.315 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-12-17T22:42:27.316Z,1766011347.316 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-12-17T22:42:27.345Z,1766011347.345 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-12-17T22:42:27.346Z,1766011347.346 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-12-17T22:42:27.687Z,1766011347.687 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-12-17T22:42:27.687Z,1766011347.687 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-12-17T22:42:27.859Z,1766011347.859 [CTD_Seabird] Loaded
2025-12-17T22:42:27.860Z,1766011347.860 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-12-17T22:42:27.861Z,1766011347.861 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BC24E0
2025-12-17T22:42:27.861Z,1766011347.861 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2965
2025-12-17T22:42:27.897Z,1766011347.897 [ESPComponent] Loaded
2025-12-17T22:42:27.897Z,1766011347.897 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-12-17T22:42:27.919Z,1766011347.919 [PAR_Licor] Loaded
2025-12-17T22:42:27.920Z,1766011347.920 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-12-17T22:42:27.965Z,1766011347.965 [WetLabsBB2FL] Loaded
2025-12-17T22:42:27.965Z,1766011347.965 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-12-17T22:42:27.966Z,1766011347.966 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BF24E0
2025-12-17T22:42:27.966Z,1766011347.966 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2966
2025-12-17T22:42:27.967Z,1766011347.967 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-12-17T22:42:27.968Z,1766011347.968 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-12-17T22:42:28.128Z,1766011348.128 [SBIT](DEBUG): Construct Startup Built In Test.
2025-12-17T22:42:28.137Z,1766011348.137 [SBIT] Loaded
2025-12-17T22:42:28.138Z,1766011348.138 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-12-17T22:42:28.141Z,1766011348.141 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-12-17T22:42:28.153Z,1766011348.153 [IBIT] Loaded
2025-12-17T22:42:28.153Z,1766011348.153 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-12-17T22:42:28.159Z,1766011348.159 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-12-17T22:42:28.239Z,1766011348.239 [CBIT] Loaded
2025-12-17T22:42:28.240Z,1766011348.240 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-12-17T22:42:28.272Z,1766011348.272 [GFScanner] Loaded
2025-12-17T22:42:28.273Z,1766011348.273 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-12-17T22:42:28.273Z,1766011348.273 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-12-17T22:42:28.279Z,1766011348.279 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-12-17T22:42:28.282Z,1766011348.282 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-12-17T22:42:28.294Z,1766011348.294 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-12-17T22:42:28.295Z,1766011348.295 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-12-17T22:42:28.295Z,1766011348.295 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2967
2025-12-17T22:42:28.299Z,1766011348.299 [Supervisor](INFO): Main Thread ID is 826
2025-12-17T22:42:28.299Z,1766011348.299 [Supervisor](DEBUG): Running supervisor.
2025-12-17T22:42:28.300Z,1766011348.300 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2968
2025-12-17T22:42:28.301Z,1766011348.301 [CommandExec](INFO): Initializing the command executive.
2025-12-17T22:42:28.302Z,1766011348.302 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2969
2025-12-17T22:42:28.304Z,1766011348.304 [controlThread ThreadHandler](INFO): Handler Thread ID is 2970
2025-12-17T22:42:28.305Z,1766011348.305 [controlThread](DEBUG): Initializing ControlThread
2025-12-17T22:42:28.306Z,1766011348.306 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-12-17T22:42:28.306Z,1766011348.306 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-12-17T22:42:28.306Z,1766011348.306 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-12-17T22:42:28.307Z,1766011348.307 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-12-17T22:42:28.307Z,1766011348.307 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-12-17T22:42:28.308Z,1766011348.308 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-12-17T22:42:28.309Z,1766011348.309 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-12-17T22:42:28.310Z,1766011348.310 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-12-17T22:42:28.311Z,1766011348.311 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-12-17T22:42:28.311Z,1766011348.311 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-12-17T22:42:28.312Z,1766011348.312 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-12-17T22:42:28.320Z,1766011348.320 [SBIT](INFO): Initialize SBIT Component.
2025-12-17T22:42:28.321Z,1766011348.321 [SBIT](IMPORTANT): git: 2025-12-11-2-g5b5070323
2025-12-17T22:42:28.321Z,1766011348.321 [SBIT](INFO): git hash: 5b50703234a74cb9c609bca727303ff504a0b330
2025-12-17T22:42:28.321Z,1766011348.321 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-12-17T22:42:28.322Z,1766011348.322 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-12-17T22:42:28.323Z,1766011348.323 [SBIT](INFO): Beginning SBIT in 76.000000 seconds.
2025-12-17T22:42:28.324Z,1766011348.324 [IBIT](INFO): Initialize IBIT Component.
2025-12-17T22:42:28.325Z,1766011348.325 [CBIT](DEBUG): Initialize CBIT Component.
2025-12-17T22:42:28.326Z,1766011348.326 [logger ThreadHandler](INFO): Handler Thread ID is 2971
2025-12-17T22:42:28.336Z,1766011348.336 [CBIT](DEBUG): Initialized mux pins.
2025-12-17T22:42:28.336Z,1766011348.336 [CBIT](DEBUG): Initializing the watchdog timer.
2025-12-17T22:42:28.344Z,1766011348.344 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2972
2025-12-17T22:42:28.356Z,1766011348.356 [DAT ThreadHandler](INFO): Handler Thread ID is 2973
2025-12-17T22:42:28.357Z,1766011348.357 [DAT](INFO): Powering up
2025-12-17T22:42:28.357Z,1766011348.357 [DAT](DEBUG): Initializing DAT.
2025-12-17T22:42:28.360Z,1766011348.360 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-12-17T22:42:28.360Z,1766011348.360 [CBIT](DEBUG): Initializing heartbeat.
2025-12-17T22:42:28.362Z,1766011348.362 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2975
2025-12-17T22:42:28.364Z,1766011348.364 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-12-17T22:42:28.373Z,1766011348.373 [Onboard ThreadHandler](INFO): Handler Thread ID is 2976
2025-12-17T22:42:28.403Z,1766011348.403 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2977
2025-12-17T22:42:28.416Z,1766011348.416 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2978
2025-12-17T22:42:28.417Z,1766011348.417 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-12-17T22:42:28.421Z,1766011348.421 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2980
2025-12-17T22:42:28.424Z,1766011348.424 [WetLabsBB2FL](INFO): Powering up
2025-12-17T22:42:28.426Z,1766011348.426 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2982
2025-12-17T22:42:28.429Z,1766011348.429 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-12-17T22:42:28.430Z,1766011348.430 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-12-17T22:42:28.430Z,1766011348.430 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-12-17T22:42:28.430Z,1766011348.430 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-12-17T22:42:28.430Z,1766011348.430 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-12-17T22:42:28.430Z,1766011348.430 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-12-17T22:42:28.431Z,1766011348.431 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-12-17T22:42:28.431Z,1766011348.431 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-12-17T22:42:28.432Z,1766011348.432 [CBIT](DEBUG): Deactivating emergency mode.
2025-12-17T22:42:28.472Z,1766011348.472 [CBIT](DEBUG): Backplane powered.
2025-12-17T22:42:28.472Z,1766011348.472 [GFScanner](DEBUG): Initializing GFScanner
2025-12-17T22:42:28.472Z,1766011348.472 [GFScanner](DEBUG): Deactivating GF circuits.
2025-12-17T22:42:28.477Z,1766011348.477 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-12-17T22:42:28.478Z,1766011348.478 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-12-17T22:42:28.478Z,1766011348.478 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-12-17T22:42:28.480Z,1766011348.480 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-12-17T22:42:28.528Z,1766011348.528 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-12-17T22:42:28.549Z,1766011348.549 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-12-17T22:42:28.602Z,1766011348.602 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2025-12-17T22:42:28.602Z,1766011348.602 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-12-17T22:42:28.603Z,1766011348.603 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-12-17T22:42:28.605Z,1766011348.605 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-12-17T22:42:28.804Z,1766011348.804 [Radio_Surface](INFO): Powering up
2025-12-17T22:42:28.867Z,1766011348.867 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-12-17T22:42:28.946Z,1766011348.946 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-12-17T22:42:28.947Z,1766011348.947 [Default:A.Wait](DEBUG): Construct Wait.
2025-12-17T22:42:28.961Z,1766011348.961 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-12-17T22:42:29.010Z,1766011349.010 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-12-17T22:42:29.013Z,1766011349.013 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-12-17T22:42:29.018Z,1766011349.018 [Default:E.Execute](DEBUG): Construct Execute.
2025-12-17T22:42:29.148Z,1766011349.148 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2025-12-17T22:42:29.153Z,1766011349.153 [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,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter,
2025-12-17T22:42:29.185Z,1766011349.185 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-12-17T22:42:29.237Z,1766011349.237 [Depth_Keller](INFO): Initializing.
2025-12-17T22:42:29.238Z,1766011349.238 [Power24vConverter](INFO): Powering up.
2025-12-17T22:42:29.239Z,1766011349.239 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-12-17T22:42:29.253Z,1766011349.253 [Sonardyne_Nano](INFO): Initializing.
2025-12-17T22:42:29.300Z,1766011349.300 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-12-17T22:42:29.334Z,1766011349.334 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-12-17T22:42:29.341Z,1766011349.341 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-12-17T22:42:29.342Z,1766011349.342 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-12-17T22:42:29.348Z,1766011349.348 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-12-17T22:42:29.349Z,1766011349.349 [MassServo](DEBUG): Initializing EZServoServo.
2025-12-17T22:42:29.356Z,1766011349.356 [MassServo](DEBUG): Initializing MassServo.
2025-12-17T22:42:29.357Z,1766011349.357 [RudderServo](DEBUG): Initializing EZServoServo.
2025-12-17T22:42:29.367Z,1766011349.367 [RudderServo](DEBUG): Initializing RudderServo.
2025-12-17T22:42:29.368Z,1766011349.368 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-12-17T22:42:29.376Z,1766011349.376 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-12-17T22:42:30.532Z,1766011350.532 [WetLabsBB2FL](INFO): Powering down
2025-12-17T22:42:31.182Z,1766011351.182 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-12-17T22:42:32.469Z,1766011352.469 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207
2025-12-17T22:42:34.056Z,1766011354.056 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11
2025-12-17T22:42:34.057Z,1766011354.057 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0
2025-12-17T22:42:34.929Z,1766011354.929 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-12-17T22:42:40.452Z,1766011360.452 [DAT](INFO): DAT read:
2025-12-17T22:42:40.454Z,1766011360.454 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-12-17T22:42:42.217Z,1766011362.217 [DAT](INFO): DAT read: MF Frequency Band
2025-12-17T22:42:42.218Z,1766011362.218 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-12-17T22:42:42.219Z,1766011362.219 [DAT](INFO): DAT read: Dec 17 2025 22:42:36
2025-12-17T22:42:43.225Z,1766011363.225 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-12-17T22:42:43.226Z,1766011363.226 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP
2025-12-17T22:42:43.227Z,1766011363.227 [DAT](INFO): commRate: 600
2025-12-17T22:42:45.292Z,1766011365.292 [DAT](INFO): entering command mode
2025-12-17T22:42:45.493Z,1766011365.493 [DAT](INFO): DAT read:
2025-12-17T22:42:45.493Z,1766011365.493 [DAT](INFO): DAT read: user:1>
2025-12-17T22:42:45.493Z,1766011365.493 [DAT](INFO): setting verbose to 3
2025-12-17T22:42:45.745Z,1766011365.745 [DAT](INFO): DAT read: user:1>
2025-12-17T22:42:45.746Z,1766011365.746 [DAT](INFO): DAT read: Verbose | 3
2025-12-17T22:42:45.746Z,1766011365.746 [DAT](INFO): set verbose to 3
2025-12-17T22:42:45.746Z,1766011365.746 [DAT](INFO): setting DatVerbose to 27440
2025-12-17T22:42:45.997Z,1766011365.997 [DAT](INFO): DAT read: user:2>
2025-12-17T22:42:45.998Z,1766011365.998 [DAT](INFO): DAT read: DatVerbose | 27440
2025-12-17T22:42:45.998Z,1766011365.998 [DAT](INFO): set DatVerbose to 27440
2025-12-17T22:42:45.998Z,1766011365.998 [DAT](INFO): setting transmit power to 8
2025-12-17T22:42:46.249Z,1766011366.249 [DAT](INFO): DAT read: user:3>
2025-12-17T22:42:46.250Z,1766011366.250 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-12-17T22:42:46.250Z,1766011366.250 [DAT](INFO): set transmit power to 8
2025-12-17T22:42:46.250Z,1766011366.250 [DAT](INFO): setting local address to 11
2025-12-17T22:42:46.501Z,1766011366.501 [DAT](INFO): DAT read: user:4>
2025-12-17T22:42:46.502Z,1766011366.502 [DAT](INFO): DAT read: LocalAddr | 11
2025-12-17T22:42:46.502Z,1766011366.502 [DAT](INFO): set local address to 11
2025-12-17T22:42:46.503Z,1766011366.503 [DAT](INFO): Setting time to: 22:42:46 And date to:12/17/2025
2025-12-17T22:42:46.753Z,1766011366.753 [DAT](INFO): DAT read: user:5>
2025-12-17T22:42:46.754Z,1766011366.754 [DAT](INFO): DAT read: Wed Dec 17, 2025 22:42:46
2025-12-17T22:42:46.754Z,1766011366.754 [DAT](INFO): Local DAT time set to Wed Dec 17, 2025 22:42:46
2025-12-17T22:42:57.045Z,1766011377.045 [NAL9602](INFO): Powering up NAL9602
2025-12-17T22:42:59.484Z,1766011379.484 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:43:07.958Z,1766011387.958 [NAL9602](INFO): NAL9602 initialized
2025-12-17T22:43:30.609Z,1766011410.609 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:43:30.987Z,1766011410.987 [NAL9602](INFO): SBD MO Status=0, MOMSN=6000, MT Status=0, MTMSN=0
2025-12-17T22:43:30.988Z,1766011410.988 [NAL9602](INFO): No messages in MT queue
2025-12-17T22:43:32.199Z,1766011412.199 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224328.00,A,3647.30087,N,12153.79441,W,0.447,207.79,171225,,,A*7E
2025-12-17T22:43:32.203Z,1766011412.203 [NAL9602](INFO): GPS fix at 20251217T224328: (36.788348, -121.896574)
2025-12-17T22:43:44.762Z,1766011424.762 [SBIT](IMPORTANT): Beginning Startup BIT
2025-12-17T22:43:44.767Z,1766011424.767 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-17T22:43:49.316Z,1766011429.316 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001179
CHAN A1 (24V): 0.001058
CHAN A2 (12V): -0.000490
CHAN A3 (5V): -0.004878
CHAN B0 (3.3V): 0.000484
CHAN B1 (3.15aV): 0.000734
CHAN B2 (3.15bV): 0.000107
CHAN B3 (GND): -0.001404
OPEN: 0.005482
Full Scale: +/- 1 mA
2025-12-17T22:44:01.740Z,1766011441.740 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:44:04.582Z,1766011444.582 [NAL9602](INFO): Powering down
2025-12-17T22:44:09.430Z,1766011449.430 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-17T22:44:32.865Z,1766011472.865 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:44:38.979Z,1766011478.979 [SBIT](IMPORTANT): SBIT PASSED
2025-12-17T22:44:38.980Z,1766011478.980 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-12-17T22:44:38.981Z,1766011478.981 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=29 count;
2025-12-17T22:44:38.981Z,1766011478.981 [SBIT](IMPORTANT): BackseatComponent.fullSlateAccess=1 bool;
2025-12-17T22:44:38.981Z,1766011478.981 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;
2025-12-17T22:44:38.981Z,1766011478.981 [SBIT](IMPORTANT): DAT.transponderAddress=8 enum;
2025-12-17T22:44:38.981Z,1766011478.981 [SBIT](IMPORTANT): DockingStepper.loadAtStartup=0 bool;
2025-12-17T22:44:38.981Z,1766011478.981 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2025-12-17T22:44:38.981Z,1766011478.981 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter;
2025-12-17T22:44:38.981Z,1766011478.981 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=-0.1 ampere_hour;
2025-12-17T22:44:38.981Z,1766011478.981 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2025-12-17T22:44:38.982Z,1766011478.982 [SBIT](IMPORTANT): NAL9602.fastGPSFix=0 bool;
2025-12-17T22:44:38.982Z,1766011478.982 [SBIT](IMPORTANT): SpeedControl.rollOptimum=0.800000 m/s;
2025-12-17T22:44:38.982Z,1766011478.982 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=463.623538 cubic_centimeter;
2025-12-17T22:44:38.982Z,1766011478.982 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=0.001 reciprocal_second;
2025-12-17T22:44:38.982Z,1766011478.982 [SBIT](IMPORTANT): VerticalControl.massDefault=14.138867 millimeter;
2025-12-17T22:44:39.353Z,1766011479.353 [MissionManager](IMPORTANT): Started mission Startup
2025-12-17T22:44:39.353Z,1766011479.353 [Startup] Running Loop=1
2025-12-17T22:44:39.353Z,1766011479.353 [Startup](DEBUG): Aggregate::initialize Startup
2025-12-17T22:44:39.353Z,1766011479.353 [Startup:A.GoToSurface] Running Loop=1
2025-12-17T22:44:39.353Z,1766011479.353 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-17T22:44:39.354Z,1766011479.354 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-12-17T22:44:39.354Z,1766011479.354 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-12-17T22:44:39.355Z,1766011479.355 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-12-17T22:44:39.355Z,1766011479.355 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-12-17T22:44:39.355Z,1766011479.355 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-17T22:44:39.355Z,1766011479.355 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-17T22:44:39.357Z,1766011479.357 [Startup:StartupSatComms] Running Loop=1
2025-12-17T22:44:39.357Z,1766011479.357 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-12-17T22:44:39.357Z,1766011479.357 [Startup:StartupSatComms:A] Running Loop=1
2025-12-17T22:44:39.755Z,1766011479.755 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-12-17T22:44:40.153Z,1766011480.153 [NAL9602](INFO): Powering up
2025-12-17T22:44:51.061Z,1766011491.061 [NAL9602](INFO): NAL9602 initialized
2025-12-17T22:44:54.332Z,1766011494.332 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-17T22:45:04.000Z,1766011504.000 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:45:04.790Z,1766011504.790 [NAL9602](INFO): SBD MO Status=0, MOMSN=6001, MT Status=0, MTMSN=0
2025-12-17T22:45:04.790Z,1766011504.790 [NAL9602](INFO): No messages in MT queue
2025-12-17T22:45:06.004Z,1766011506.004 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224505.00,A,3647.29493,N,12153.76925,W,0.719,114.29,171225,,,A*72
2025-12-17T22:45:06.006Z,1766011506.006 [NAL9602](INFO): GPS fix at 20251217T224505: (36.788249, -121.896154)
2025-12-17T22:45:06.039Z,1766011506.039 [Startup:StartupSatComms:A] Stopped
2025-12-17T22:45:06.039Z,1766011506.039 [Startup:StartupSatComms:B] Running Loop=1
2025-12-17T22:45:06.445Z,1766011506.445 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-12-17T22:45:12.246Z,1766011512.246 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004375
2025-12-17T22:45:19.759Z,1766011519.759 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251217T193843/Courier0051.lzma
2025-12-17T22:45:20.762Z,1766011520.762 [DataOverHttps](INFO): Moved sent file to Logs/20251217T193843/Courier0051.lzma.bak
2025-12-17T22:45:20.763Z,1766011520.763 [DataOverHttps](INFO): SBD MOMSN=26517585
2025-12-17T22:45:22.202Z,1766011522.202 [NAL9602](INFO): SBD MO Status=1, MOMSN=6002, MT Status=0, MTMSN=0
2025-12-17T22:45:22.264Z,1766011522.264 [NAL9602](INFO): Sent 39 bytes from file Logs/20251217T193843/Courier0051.lzma
2025-12-17T22:45:22.264Z,1766011522.264 [NAL9602](INFO): Packets left to send: 0
2025-12-17T22:45:22.299Z,1766011522.299 [Startup:StartupSatComms:B] Stopped
2025-12-17T22:45:22.300Z,1766011522.300 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-12-17T22:45:22.300Z,1766011522.300 [Startup:StartupSatComms] Stopped
2025-12-17T22:45:22.300Z,1766011522.300 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-12-17T22:45:22.301Z,1766011522.301 [Startup](INFO): Completed Startup
2025-12-17T22:45:22.301Z,1766011522.301 [MissionManager](INFO): Startup is completed.
2025-12-17T22:45:22.301Z,1766011522.301 [MissionManager](INFO): Uninitializing Mission Startup
2025-12-17T22:45:22.301Z,1766011522.301 [Startup] Stopped
2025-12-17T22:45:22.301Z,1766011522.301 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-12-17T22:45:22.301Z,1766011522.301 [Startup:A.GoToSurface] Stopped
2025-12-17T22:45:22.301Z,1766011522.301 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-17T22:45:22.619Z,1766011522.619 [MissionManager](IMPORTANT): Started mission Default
2025-12-17T22:45:22.620Z,1766011522.620 [Default] Running Loop=1
2025-12-17T22:45:22.620Z,1766011522.620 [Default](DEBUG): Aggregate::initialize Default
2025-12-17T22:45:22.620Z,1766011522.620 [Default:B.GoToSurface] Running Loop=1
2025-12-17T22:45:22.620Z,1766011522.620 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-17T22:45:22.620Z,1766011522.620 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-12-17T22:45:22.621Z,1766011522.621 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-12-17T22:45:22.621Z,1766011522.621 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-12-17T22:45:22.621Z,1766011522.621 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-12-17T22:45:22.622Z,1766011522.622 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-17T22:45:22.622Z,1766011522.622 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-17T22:45:22.622Z,1766011522.622 [Default:A.Wait] Running Loop=1
2025-12-17T22:45:22.622Z,1766011522.622 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-12-17T22:45:30.700Z,1766011530.700 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:45:35.973Z,1766011535.973 [Default:A.Wait](INFO): Done Waiting.
2025-12-17T22:45:35.973Z,1766011535.973 [Default:A.Wait] Stopped
2025-12-17T22:45:35.974Z,1766011535.974 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-12-17T22:45:36.357Z,1766011536.357 [Default:CheckIn] Running Loop=1
2025-12-17T22:45:36.358Z,1766011536.358 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-17T22:45:36.358Z,1766011536.358 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-17T22:45:36.758Z,1766011536.758 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-12-17T22:45:37.356Z,1766011537.356 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20251217T224216/Courier0000.lzma
2025-12-17T22:45:38.357Z,1766011538.357 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Courier0000.lzma.bak
2025-12-17T22:45:38.358Z,1766011538.358 [DataOverHttps](INFO): SBD MOMSN=26517588
2025-12-17T22:45:38.383Z,1766011538.383 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224537.00,A,3647.29283,N,12153.76094,W,1.244,119.49,171225,,,A*70
2025-12-17T22:45:38.385Z,1766011538.385 [NAL9602](INFO): GPS fix at 20251217T224537: (36.788214, -121.896016)
2025-12-17T22:45:38.397Z,1766011538.397 [Default:CheckIn:Read_GPS] Stopped
2025-12-17T22:45:38.397Z,1766011538.397 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-17T22:45:38.801Z,1766011538.801 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-12-17T22:45:54.843Z,1766011554.843 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20251217T224216/Courier0004.lzma
2025-12-17T22:45:55.846Z,1766011555.846 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Courier0004.lzma.bak
2025-12-17T22:45:55.846Z,1766011555.846 [DataOverHttps](INFO): SBD MOMSN=26517592
2025-12-17T22:46:10.277Z,1766011570.277 [NAL9602](INFO): Powering down
2025-12-17T22:46:12.576Z,1766011572.576 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20251217T193843/Express0052.lzma
2025-12-17T22:46:13.579Z,1766011573.579 [DataOverHttps](INFO): Moved sent file to Logs/20251217T193843/Express0052.lzma.bak
2025-12-17T22:46:13.579Z,1766011573.579 [DataOverHttps](INFO): SBD MOMSN=26517597
2025-12-17T22:46:29.845Z,1766011589.845 [DataOverHttps](INFO): Sending 1282 bytes from file Logs/20251217T224216/Express0001.lzma
2025-12-17T22:46:30.846Z,1766011590.846 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Express0001.lzma.bak
2025-12-17T22:46:30.847Z,1766011590.847 [DataOverHttps](INFO): SBD MOMSN=26517603
2025-12-17T22:46:47.124Z,1766011607.124 [DataOverHttps](INFO): Sending 125 bytes from file Logs/20251217T224216/Express0005.lzma
2025-12-17T22:46:48.122Z,1766011608.122 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Express0005.lzma.bak
2025-12-17T22:46:48.122Z,1766011608.122 [DataOverHttps](INFO): SBD MOMSN=26517632
2025-12-17T22:46:49.895Z,1766011609.895 [Default:CheckIn:Read_Iridium] Stopped
2025-12-17T22:46:49.895Z,1766011609.895 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-17T22:46:49.895Z,1766011609.895 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-17T22:46:57.744Z,1766011617.744 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:47:06.384Z,1766011626.384 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:47:15.116Z,1766011635.116 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:47:23.865Z,1766011643.865 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:47:31.449Z,1766011651.449 [DataOverHttps](IMPORTANT): SBD MTMSN=20251217T224730
2025-12-17T22:47:39.156Z,1766011659.156 [DataOverHttps](INFO): Received command: schedule resume
2025-12-17T22:47:39.177Z,1766011659.177 [CommandExec](IMPORTANT): got command schedule resume
2025-12-17T22:47:41.160Z,1766011661.160 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:47:48.874Z,1766011668.874 [DataOverHttps](IMPORTANT): SBD MTMSN=20251217T224748
2025-12-17T22:47:56.440Z,1766011676.440 [DataOverHttps](INFO): Received command: load Transport/keepstation.tl;set keepstation.NeedCommsTime 10 min;set keepstation.Latitude 36.79114 degree;set keepstation.Longitude -121.89915 degree;run
2025-12-17T22:47:56.472Z,1766011676.472 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-12-17T22:47:56.472Z,1766011676.472 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-12-17T22:47:56.472Z,1766011676.472 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-12-17T22:47:56.477Z,1766011676.477 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-12-17T22:47:57.885Z,1766011677.885 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
4
45
NaN
NaN
30
15
4
0.75
200
10
10
45
2
0
0
1
2
3
NaN
0
Sinking to
Timed out while trying to reach target sink depth at current depth of
2025-12-17T22:47:57.894Z,1766011677.894 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-12-17T22:47:57.897Z,1766011677.897 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-12-17T22:47:57.899Z,1766011677.899 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-12-17T22:47:57.902Z,1766011677.902 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-12-17T22:47:57.926Z,1766011677.926 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-12-17T22:47:57.930Z,1766011677.930 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-12-17T22:47:57.934Z,1766011677.934 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-12-17T22:47:57.949Z,1766011677.949 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-12-17T22:47:57.956Z,1766011677.956 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-12-17T22:47:57.959Z,1766011677.959 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-12-17T22:47:57.966Z,1766011677.966 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-12-17T22:47:57.971Z,1766011677.971 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-12-17T22:47:57.982Z,1766011677.982 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-12-17T22:47:57.986Z,1766011677.986 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-12-17T22:47:57.997Z,1766011677.997 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-12-17T22:47:58.021Z,1766011678.021 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-12-17T22:47:58.024Z,1766011678.024 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-12-17T22:47:58.047Z,1766011678.047 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-12-17T22:47:58.074Z,1766011678.074 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-12-17T22:47:58.081Z,1766011678.081 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-12-17T22:47:58.081Z,1766011678.081 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-12-17T22:47:58.081Z,1766011678.081 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-12-17T22:47:58.083Z,1766011678.083 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-12-17T22:47:58.444Z,1766011678.444 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:47:58.511Z,1766011678.511 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1500
7
30
0
0
0
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2025-12-17T22:47:58.517Z,1766011678.517 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-12-17T22:47:58.537Z,1766011678.537 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-12-17T22:47:58.541Z,1766011678.541 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-12-17T22:47:58.543Z,1766011678.543 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-12-17T22:47:58.546Z,1766011678.546 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-12-17T22:47:58.550Z,1766011678.550 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1500.000000 s
2025-12-17T22:47:58.553Z,1766011678.553 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-12-17T22:47:58.555Z,1766011678.555 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-12-17T22:47:58.558Z,1766011678.558 [MissionManager](INFO): DefineArg keepstation:NeedComms.DoingCommsMode = 0.000000 count
2025-12-17T22:47:58.561Z,1766011678.561 [MissionManager](INFO): DefineArg keepstation:NeedComms.RestoreMode = 0.000000 count
2025-12-17T22:47:58.564Z,1766011678.564 [MissionManager](INFO): DefineOutput keepstation:NeedComms.IsActive = 0 bool
2025-12-17T22:47:58.567Z,1766011678.567 [MissionManager](INFO): DefineOutput keepstation:NeedComms.RequiredDiveMode = 0.000000 count
2025-12-17T22:47:58.622Z,1766011678.622 [keepstation:NeedComms:C.GoToSurface](DEBUG): Construct GoToSurface.
2025-12-17T22:47:58.730Z,1766011678.730 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-12-17T22:47:58.730Z,1766011678.730 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-12-17T22:47:58.732Z,1766011678.732 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-12-17T22:47:58.898Z,1766011678.898 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2025-12-17T22:47:58.903Z,1766011678.903 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-12-17T22:47:58.918Z,1766011678.918 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-12-17T22:47:58.921Z,1766011678.921 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-12-17T22:47:58.924Z,1766011678.924 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-12-17T22:47:58.925Z,1766011678.925 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-12-17T22:47:58.949Z,1766011678.949 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-12-17T22:47:58.957Z,1766011678.957 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-12-17T22:47:58.980Z,1766011678.980 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-12-17T22:47:58.981Z,1766011678.981 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-12-17T22:47:58.994Z,1766011678.994 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-12-17T22:47:59.111Z,1766011679.111 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-12-17T22:47:59.123Z,1766011679.123 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-12-17T22:47:59.136Z,1766011679.136 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-12-17T22:47:59.139Z,1766011679.139 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-12-17T22:47:59.139Z,1766011679.139 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-12-17T22:47:59.140Z,1766011679.140 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-12-17T22:47:59.357Z,1766011679.357 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-12-17T22:47:59.370Z,1766011679.370 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-12-17T22:47:59.372Z,1766011679.372 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-12-17T22:47:59.375Z,1766011679.375 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-12-17T22:47:59.378Z,1766011679.378 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-12-17T22:47:59.389Z,1766011679.389 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2025-12-17T22:47:59.436Z,1766011679.436 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-12-17T22:47:59.438Z,1766011679.438 [keepstation:H.Pitch](DEBUG): Construct.
2025-12-17T22:47:59.561Z,1766011679.561 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-12-17T22:47:59.568Z,1766011679.568 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-12-17T22:47:59.577Z,1766011679.577 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-12-17T22:47:59.614Z,1766011679.614 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-12-17T22:47:59.626Z,1766011679.626 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-12-17T22:47:59.635Z,1766011679.635 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-12-17T22:47:59.650Z,1766011679.650 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-12-17T22:47:59.655Z,1766011679.655 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-12-17T22:47:59.681Z,1766011679.681 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-12-17T22:47:59.708Z,1766011679.708 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-12-17T22:47:59.739Z,1766011679.739 [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 keepstation {
"""
Vehicle transits to desired waypoint (or stays put if none is commanded)
and stays within the specified radius.
"""
arguments {
MissionTimeout = 4 hour
"""
Maximum duration of mission
"""
NeedCommsTime = 45 minute
"""
How often to surface for communications
"""
Latitude = NaN degree
"""
Latitude of waypoint to hold. If NaN, hold the latitude at start of
mission.
"""
Longitude = NaN degree
"""
Longitude of waypoint to hold. If NaN, hold the longitude at start of
mission.
"""
Depth = 30 meter
"""
Depth held during drift mode
"""
ApproachDepth = 15 meter
"""
Depth for initial approach to station.
"""
DepthDeadband = 4 meter
"""
How much vertical drift from the specified depth is allowed in drift
mode
"""
Speed = 0.75 meter_per_second
"""
Vehicle transit speed.
"""
Radius = 200 meter
"""
Radius of circle around waypoint to hold.
"""
SinkOffSurface = false
"""
If true, use buoyancy only to decend to SinkDepth before driving to first target depth
"""
SinkDepth = 10 meter
"""
Initial depth to decend using only buoyancy before driving
"""
SinkDepthTimeout = 10 minute
"""
Max wait time for the vehicle to reach the target depth
"""
MaxDepth = 45 meter
"""
Maximum allowable depth during the mission.
"""
MinOffshore = 2 kilometer
"""
Minimum distance from the shoreline to maintain
"""
}
output {
DiveMode = 0 count
"""
Mission variable (don't change). The mission sets this variable to
switch between dive modes. Initialized to DoingComms.
"""
DoingComms = 0 count
"""
Mission variable (don't change). The mission will run in this mode
when doing surface comms.
"""
SurfaceSink = 1 count
"""
Mission variable (don't change). The mission will run in this mode
when sinking from the surface using only buoyancy.
"""
TransitToStation = 2 count
"""
Mission variable (don't change). The mission will run in this mode
when performing the transit to station
"""
StationKeep = 3 count
"""
Mission variable (don't change). The mission will run in this mode
when keeping station
"""
OnStation = false
"""
Mission variable (don't change). Determines last mode (Transit or StationKeep)
to resume after needcomms
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Pitch {
run in parallel
set massPosition = Control:VerticalControl.massDefault
}
aggregate SurfaceComms {
run when (
called
or ( elapsed ( Universal:time_fix ) > NeedCommsTime )
)
assign in sequence DiveMode = DoingComms
call priorityHere=false refId="NeedComms"
aggregate HandleNan {
run in sequence
break if (
not isNaN ( Latitude )
and not isNaN ( Longitude )
)
# If both lat and lon are nan, we are implicitly on station and should skip initial transit, so force OnStation to true
aggregate SetOnStation {
run in sequence
break if (
not isNaN ( Latitude )
or not isNaN ( Longitude )
)
assign in sequence OnStation = true
}
# The "assigns" here mean we should only ever run these aggregates once
# An initial NaN lat/lon should be set here during the initial surface NeedComms call
# Use the most recent fix -- if there isn't one, the keepstation behavior will use Universal:lat/lon
aggregate SetLat {
run in sequence
break if ( not isNaN ( Latitude ) )
assign in sequence Latitude = Universal:latitude_fix
}
aggregate SetLon {
run in sequence
break if ( not isNaN ( Longitude ) )
assign in sequence Longitude = Universal:longitude_fix
}
}
aggregate setTransit {
run in sequence
break if (
SinkOffSurface
or OnStation
)
assign in sequence DiveMode = TransitToStation
}
aggregate setStation {
run in sequence
break if (
SinkOffSurface
or not OnStation
)
assign in sequence DiveMode = StationKeep
}
aggregate setSink {
run in sequence
break if ( not SinkOffSurface )
assign in sequence DiveMode = SurfaceSink
}
}
call id="StartingMission" priorityHere=false refId="SurfaceComms"
aggregate DiveCmd {
run when ( called )
aggregate SurfaceSink {
run while ( DiveMode == SurfaceSink )
aggregate sink {
run in sequence
behavior Guidance:Buoyancy id="BuoyancyHold" {
run in parallel
set position = NaN cc
}
behavior Guidance:SetSpeed {
run in parallel
set speed = 0 m/s
}
syslog info "Sinking to " + SinkDepth~meter
behavior Guidance:Pitch {
run in sequence
timeout duration=SinkDepthTimeout {
syslog important "Timed out while trying to reach target sink depth at current depth of "
+ Universal:depth~meter
}
set depth = SinkDepth
}
# Once we've sunk, either resume transit or station keeping depths and speeds
aggregate sinkToTransit {
run in sequence
break if ( OnStation )
assign in sequence DiveMode = TransitToStation
}
aggregate sinkToStation {
run in sequence
break if ( not OnStation )
assign in sequence DiveMode = StationKeep
}
}
}
aggregate TransitToStation {
run while ( DiveMode == TransitToStation )
aggregate dive {
run in sequence
behavior Guidance:Buoyancy {
run in parallel
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch {
run in parallel
set depth = ApproachDepth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
}
}
aggregate StationKeep {
run while ( DiveMode == StationKeep )
assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:KeepStation {
run in parallel
set latitude = Latitude
set longitude = Longitude
set radius = Radius
set speed = Speed
}
}
}
aggregate TransitToStation {
"""
Need a separate aggregate for transit if we want to specify a
different depth for the approach.
"""
run in sequence
aggregate Dive {
run while ( not ( DiveMode == DoingComms ) )
call priorityHere=false refId="DiveCmd"
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
}
assign in sequence DiveMode = StationKeep
assign in sequence OnStation = true
}
call id="OnStation" priorityHere=false refId="NeedComms"
aggregate KeepStation {
run in sequence
aggregate Dive {
run while ( not ( DiveMode == DoingComms ) )
call priorityHere=false refId="DiveCmd"
}
behavior Guidance:Wait {
"""
Due to the way the KeepStation behavior is currently
implemented, we must run it in parallel and use a Wait to keep
from completing the mission before the timeout expires. However,
this new version of the mission uses a top-level timeout so that
the entire mission will not run for longer then MissionTimeout.
"""
run in sequence
set duration = MissionTimeout
}
}
}
2025-12-17T22:47:59.744Z,1766011679.744 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-12-17T22:48:12.916Z,1766011692.916 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:48:14.212Z,1766011694.212 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 10 minute
2025-12-17T22:48:14.213Z,1766011694.213 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.79114 degree
2025-12-17T22:48:14.214Z,1766011694.214 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.89915 degree
2025-12-17T22:48:14.214Z,1766011694.214 [CommandExec](IMPORTANT): got command run
2025-12-17T22:48:14.217Z,1766011694.217 [CommandExec](IMPORTANT): Running
2025-12-17T22:48:14.417Z,1766011694.417 [Default] Stopped
2025-12-17T22:48:14.417Z,1766011694.417 [Default](DEBUG): Aggregate::uninitialize Default
2025-12-17T22:48:14.418Z,1766011694.418 [Default:B.GoToSurface] Stopped
2025-12-17T22:48:14.418Z,1766011694.418 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-17T22:48:14.418Z,1766011694.418 [Default:CheckIn] Stopped
2025-12-17T22:48:14.418Z,1766011694.418 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-17T22:48:14.418Z,1766011694.418 [Default:CheckIn:C.Wait] Stopped
2025-12-17T22:48:14.418Z,1766011694.418 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-17T22:48:14.418Z,1766011694.418 [MissionManager](IMPORTANT): Started mission keepstation
2025-12-17T22:48:14.418Z,1766011694.418 [keepstation] Running Loop=1
2025-12-17T22:48:14.418Z,1766011694.418 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-12-17T22:48:14.419Z,1766011694.419 [keepstation:StandardEnvelopes] Running Loop=1
2025-12-17T22:48:14.419Z,1766011694.419 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-12-17T22:48:14.419Z,1766011694.419 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-12-17T22:48:14.419Z,1766011694.419 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-12-17T22:48:14.419Z,1766011694.419 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-12-17T22:48:14.419Z,1766011694.419 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-12-17T22:48:14.419Z,1766011694.419 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-12-17T22:48:14.419Z,1766011694.419 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-12-17T22:48:14.420Z,1766011694.420 [keepstation:BackseatDriver] Running Loop=1
2025-12-17T22:48:14.420Z,1766011694.420 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-12-17T22:48:14.420Z,1766011694.420 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-12-17T22:48:14.420Z,1766011694.420 [keepstation:PowerOnly] Running Loop=1
2025-12-17T22:48:14.420Z,1766011694.420 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-12-17T22:48:14.420Z,1766011694.420 [keepstation:PowerOnly:A] Running Loop=1
2025-12-17T22:48:14.420Z,1766011694.420 [keepstation:PowerOnly:B] Running Loop=1
2025-12-17T22:48:14.421Z,1766011694.421 [keepstation:PowerOnly:C] Running Loop=1
2025-12-17T22:48:14.421Z,1766011694.421 [keepstation:PowerOnly:D] Running Loop=1
2025-12-17T22:48:14.421Z,1766011694.421 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-12-17T22:48:14.421Z,1766011694.421 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-12-17T22:48:14.421Z,1766011694.421 [keepstation:H.Pitch] Running Loop=1
2025-12-17T22:48:14.421Z,1766011694.421 [keepstation:H.Pitch](DEBUG): Initialize.
2025-12-17T22:48:14.421Z,1766011694.421 [keepstation:B] Running Loop=1
2025-12-17T22:48:14.422Z,1766011694.422 [keepstation:H.Pitch] Running Loop=1
2025-12-17T22:48:14.422Z,1766011694.422 [keepstation:PowerOnly] Running Loop=1
2025-12-17T22:48:14.423Z,1766011694.423 [keepstation:PowerOnly:D] Stopped
2025-12-17T22:48:14.423Z,1766011694.423 [keepstation:PowerOnly:C] Stopped
2025-12-17T22:48:14.423Z,1766011694.423 [keepstation:PowerOnly:B] Stopped
2025-12-17T22:48:14.423Z,1766011694.423 [keepstation:PowerOnly:A] Stopped
2025-12-17T22:48:14.423Z,1766011694.423 [keepstation:BackseatDriver] Running Loop=1
2025-12-17T22:48:14.423Z,1766011694.423 [keepstation:StandardEnvelopes] Running Loop=1
2025-12-17T22:48:14.423Z,1766011694.423 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-12-17T22:48:14.425Z,1766011694.425 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-12-17T22:48:14.426Z,1766011694.426 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-12-17T22:48:14.426Z,1766011694.426 [keepstation:B] Stopped
2025-12-17T22:48:14.426Z,1766011694.426 [keepstation:D] Running Loop=1
2025-12-17T22:48:14.806Z,1766011694.806 [keepstation:D] Stopped
2025-12-17T22:48:14.806Z,1766011694.806 [keepstation:E] Running Loop=1
2025-12-17T22:48:15.377Z,1766011695.377 [keepstation:E] Stopped
2025-12-17T22:48:15.377Z,1766011695.377 [keepstation:StartingMission] Running Loop=1
2025-12-17T22:48:15.377Z,1766011695.377 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-12-17T22:48:15.638Z,1766011695.638 [keepstation:SurfaceComms] Running Loop=1
2025-12-17T22:48:15.638Z,1766011695.638 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-17T22:48:15.639Z,1766011695.639 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-17T22:48:15.639Z,1766011695.639 [keepstation:SurfaceComms:A] Stopped
2025-12-17T22:48:15.639Z,1766011695.639 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-17T22:48:15.660Z,1766011695.660 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-17T22:48:16.042Z,1766011696.042 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-12-17T22:48:16.042Z,1766011696.042 [keepstation:NeedComms] Running Loop=1
2025-12-17T22:48:16.042Z,1766011696.042 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-17T22:48:16.042Z,1766011696.042 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-17T22:48:16.043Z,1766011696.043 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-17T22:48:16.043Z,1766011696.043 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-17T22:48:16.043Z,1766011696.043 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-17T22:48:16.044Z,1766011696.044 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-17T22:48:16.048Z,1766011696.048 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-17T22:48:16.048Z,1766011696.048 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-17T22:48:16.049Z,1766011696.049 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-17T22:48:16.049Z,1766011696.049 [keepstation:NeedComms:A] Running Loop=1
2025-12-17T22:48:16.050Z,1766011696.050 [keepstation:NeedComms:A](INFO): last time_fix was: 1766011537.000000 second since 1970/01/01T00:00:00Z
2025-12-17T22:48:16.050Z,1766011696.050 [keepstation:NeedComms:A] Stopped
2025-12-17T22:48:16.050Z,1766011696.050 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-17T22:48:16.050Z,1766011696.050 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-17T22:48:16.051Z,1766011696.051 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-17T22:48:16.477Z,1766011696.477 [keepstation:NeedComms:setActive:A] Stopped
2025-12-17T22:48:16.477Z,1766011696.477 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-17T22:48:16.844Z,1766011696.844 [keepstation:NeedComms:setActive:B] Stopped
2025-12-17T22:48:16.844Z,1766011696.844 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-17T22:48:16.844Z,1766011696.844 [keepstation:NeedComms:setActive] Stopped
2025-12-17T22:48:16.844Z,1766011696.844 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-17T22:48:17.376Z,1766011697.376 [keepstation:NeedComms:D] Running Loop=1
2025-12-17T22:48:17.673Z,1766011697.673 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-12-17T22:48:18.029Z,1766011698.029 [NAL9602](INFO): Powering up
2025-12-17T22:48:22.277Z,1766011702.277 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:48:28.929Z,1766011708.929 [NAL9602](INFO): NAL9602 initialized
2025-12-17T22:48:31.006Z,1766011711.006 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:48:39.568Z,1766011719.568 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:48:40.650Z,1766011720.650 [NAL9602](INFO): SBD MO Status=1, MOMSN=6004, MT Status=0, MTMSN=0
2025-12-17T22:48:40.650Z,1766011720.650 [NAL9602](INFO): No messages in MT queue
2025-12-17T22:48:41.863Z,1766011721.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224841.00,A,3647.28196,N,12153.71222,W,0.311,105.79,171225,,,A*7C
2025-12-17T22:48:41.865Z,1766011721.865 [NAL9602](INFO): GPS fix at 20251217T224841: (36.788033, -121.895204)
2025-12-17T22:48:41.909Z,1766011721.909 [keepstation:NeedComms:D] Stopped
2025-12-17T22:48:41.909Z,1766011721.909 [keepstation:NeedComms:E] Running Loop=1
2025-12-17T22:48:42.316Z,1766011722.316 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-12-17T22:48:46.062Z,1766011726.062 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20251217T224216/Courier0007.lzma
2025-12-17T22:48:46.554Z,1766011726.554 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Courier0007.lzma.bak
2025-12-17T22:48:46.554Z,1766011726.554 [DataOverHttps](INFO): SBD MOMSN=26517647
2025-12-17T22:49:03.439Z,1766011743.439 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251217T224216/Courier0010.lzma
2025-12-17T22:49:04.442Z,1766011744.442 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Courier0010.lzma.bak
2025-12-17T22:49:04.442Z,1766011744.442 [DataOverHttps](INFO): SBD MOMSN=26517650
2025-12-17T22:49:14.273Z,1766011754.273 [NAL9602](INFO): Powering down
2025-12-17T22:49:20.692Z,1766011760.692 [DataOverHttps](INFO): Sending 393 bytes from file Logs/20251217T224216/Express0008.lzma
2025-12-17T22:49:21.694Z,1766011761.694 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Express0008.lzma.bak
2025-12-17T22:49:21.694Z,1766011761.694 [DataOverHttps](INFO): SBD MOMSN=26517652
2025-12-17T22:49:27.995Z,1766011767.995 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-12-17T22:49:27.997Z,1766011767.997 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-17T22:49:27.997Z,1766011767.997 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-12-17T22:49:27.998Z,1766011767.998 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-12-17T22:49:27.999Z,1766011767.999 [BPC1](ERROR): Battery stick #28 (s/n: 0151) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-17T22:49:27.999Z,1766011767.999 [BPC1](ERROR): Battery stick #44 (s/n: 00E4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-17T22:49:28.000Z,1766011768.000 [BPC1](ERROR): Battery stick #47 (s/n: 00EC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-17T22:49:28.001Z,1766011768.001 [BPC1](ERROR): Battery stick #54 (s/n: 009C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-17T22:49:28.002Z,1766011768.002 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-12-17T22:49:28.002Z,1766011768.002 [BPC1](ERROR): Battery stick #62 (s/n: 0126) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-17T22:49:28.003Z,1766011768.003 [BPC1](INFO): Calculating totals. Valid battery stick count: 26. Valid reserve battery stick count: 1.
2025-12-17T22:49:28.006Z,1766011768.006 [BPC1](INFO): Received data from all battery sticks.
2025-12-17T22:49:38.371Z,1766011778.371 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20251217T224216/Express0011.lzma
2025-12-17T22:49:39.374Z,1766011779.374 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Express0011.lzma.bak
2025-12-17T22:49:39.374Z,1766011779.374 [DataOverHttps](INFO): SBD MOMSN=26517666
2025-12-17T22:49:40.943Z,1766011780.943 [keepstation:NeedComms:E] Stopped
2025-12-17T22:49:40.944Z,1766011780.944 [keepstation:NeedComms:F] Running Loop=1
2025-12-17T22:49:41.366Z,1766011781.366 [keepstation:NeedComms:F](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-12-17T22:49:41.729Z,1766011781.729 [NAL9602](INFO): Powering up
2025-12-17T22:49:49.068Z,1766011789.068 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:49:52.633Z,1766011792.633 [NAL9602](INFO): NAL9602 initialized
2025-12-17T22:49:57.768Z,1766011797.768 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:50:07.602Z,1766011807.602 [NAL9602](INFO): SBD MO Status=0, MOMSN=6005, MT Status=0, MTMSN=0
2025-12-17T22:50:07.602Z,1766011807.602 [NAL9602](INFO): No messages in MT queue
2025-12-17T22:50:08.212Z,1766011808.212 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:50:08.823Z,1766011808.823 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225005.00,A,3647.27662,N,12153.69020,W,0.680,123.31,171225,,,A*7A
2025-12-17T22:50:08.825Z,1766011808.825 [NAL9602](INFO): GPS fix at 20251217T225005: (36.787944, -121.894837)
2025-12-17T22:50:08.863Z,1766011808.863 [keepstation:NeedComms:F] Stopped
2025-12-17T22:50:08.863Z,1766011808.863 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-17T22:50:08.863Z,1766011808.863 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-17T22:50:08.863Z,1766011808.863 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-17T22:50:09.257Z,1766011809.257 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-17T22:50:09.258Z,1766011809.258 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-17T22:50:09.258Z,1766011809.258 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-17T22:50:09.258Z,1766011809.258 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-17T22:50:09.258Z,1766011809.258 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-17T22:50:09.258Z,1766011809.258 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-17T22:50:09.258Z,1766011809.258 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-17T22:50:09.644Z,1766011809.644 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-17T22:50:09.645Z,1766011809.645 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-17T22:50:09.645Z,1766011809.645 [keepstation:NeedComms:setInactive] Stopped
2025-12-17T22:50:09.645Z,1766011809.645 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-17T22:50:09.645Z,1766011809.645 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-17T22:50:09.646Z,1766011809.646 [keepstation:NeedComms] Stopped
2025-12-17T22:50:09.646Z,1766011809.646 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-17T22:50:09.646Z,1766011809.646 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-17T22:50:09.646Z,1766011809.646 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-17T22:50:10.044Z,1766011810.044 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-17T22:50:10.044Z,1766011810.044 [keepstation:SurfaceComms:B] Stopped
2025-12-17T22:50:10.044Z,1766011810.044 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-17T22:50:10.045Z,1766011810.045 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-17T22:50:10.045Z,1766011810.045 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-17T22:50:10.045Z,1766011810.045 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-17T22:50:10.045Z,1766011810.045 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-17T22:50:10.045Z,1766011810.045 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-17T22:50:10.517Z,1766011810.517 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-17T22:50:10.517Z,1766011810.517 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-17T22:50:10.517Z,1766011810.517 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-17T22:50:10.517Z,1766011810.517 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-17T22:50:10.517Z,1766011810.517 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-17T22:50:10.518Z,1766011810.518 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-17T22:50:10.518Z,1766011810.518 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-17T22:50:10.518Z,1766011810.518 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-17T22:50:10.863Z,1766011810.863 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-17T22:50:10.863Z,1766011810.863 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2025-12-17T22:50:10.863Z,1766011810.863 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-17T22:50:10.864Z,1766011810.864 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-17T22:50:10.868Z,1766011810.868 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-17T22:50:10.868Z,1766011810.868 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-17T22:50:10.868Z,1766011810.868 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-17T22:50:11.270Z,1766011811.270 [keepstation:SurfaceComms:setStation] Stopped
2025-12-17T22:50:11.270Z,1766011811.270 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-17T22:50:11.270Z,1766011811.270 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-17T22:50:11.270Z,1766011811.270 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-17T22:50:11.270Z,1766011811.270 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-17T22:50:11.271Z,1766011811.271 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-17T22:50:11.666Z,1766011811.666 [keepstation:SurfaceComms:setSink] Stopped
2025-12-17T22:50:11.666Z,1766011811.666 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-17T22:50:11.666Z,1766011811.666 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-17T22:50:11.666Z,1766011811.666 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-17T22:50:11.666Z,1766011811.666 [keepstation:SurfaceComms] Stopped
2025-12-17T22:50:11.666Z,1766011811.666 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-17T22:50:12.075Z,1766011812.075 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-12-17T22:50:12.075Z,1766011812.075 [keepstation:StartingMission] Stopped
2025-12-17T22:50:12.075Z,1766011812.075 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-12-17T22:50:12.075Z,1766011812.075 [keepstation:TransitToStation] Running Loop=1
2025-12-17T22:50:12.075Z,1766011812.075 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-12-17T22:50:12.075Z,1766011812.075 [keepstation:TransitToStation:Dive] Running Loop=1
2025-12-17T22:50:12.075Z,1766011812.075 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-12-17T22:50:12.076Z,1766011812.076 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-12-17T22:50:12.080Z,1766011812.080 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-12-17T22:50:12.080Z,1766011812.080 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-12-17T22:50:12.080Z,1766011812.080 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-12-17T22:50:12.082Z,1766011812.082 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.791142,-121.899147
2025-12-17T22:50:12.594Z,1766011812.594 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.791142,-121.899147
2025-12-17T22:50:12.595Z,1766011812.595 [keepstation:TransitToStation:Dive] Running Loop=1
2025-12-17T22:50:12.595Z,1766011812.595 [keepstation:DiveCmd] Running Loop=1
2025-12-17T22:50:12.595Z,1766011812.595 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-12-17T22:50:12.595Z,1766011812.595 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-12-17T22:50:12.595Z,1766011812.595 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-12-17T22:50:13.479Z,1766011813.479 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-12-17T22:50:13.479Z,1766011813.479 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-17T22:50:13.479Z,1766011813.479 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-12-17T22:50:13.479Z,1766011813.479 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-17T22:50:13.479Z,1766011813.479 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-12-17T22:50:13.479Z,1766011813.479 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-12-17T22:50:13.479Z,1766011813.479 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-12-17T22:50:13.480Z,1766011813.480 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-12-17T22:50:13.480Z,1766011813.480 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-12-17T22:50:13.481Z,1766011813.481 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-12-17T22:50:13.481Z,1766011813.481 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-12-17T22:50:13.481Z,1766011813.481 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-12-17T22:50:13.481Z,1766011813.481 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-17T22:50:13.481Z,1766011813.481 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-12-17T22:50:13.481Z,1766011813.481 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-12-17T22:50:13.481Z,1766011813.481 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-12-17T22:50:13.481Z,1766011813.481 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-12-17T22:50:13.481Z,1766011813.481 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-17T22:50:13.481Z,1766011813.481 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-17T22:50:13.482Z,1766011813.482 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-17T22:50:13.482Z,1766011813.482 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-17T22:50:13.482Z,1766011813.482 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-17T22:50:13.482Z,1766011813.482 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-17T22:50:13.482Z,1766011813.482 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-17T22:50:13.483Z,1766011813.483 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-17T22:50:13.483Z,1766011813.483 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-17T22:50:13.483Z,1766011813.483 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-17T22:50:13.483Z,1766011813.483 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-17T22:50:13.483Z,1766011813.483 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-17T22:50:13.483Z,1766011813.483 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-17T22:50:13.483Z,1766011813.483 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-12-17T22:50:13.483Z,1766011813.483 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-12-17T22:50:13.483Z,1766011813.483 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-12-17T22:50:13.484Z,1766011813.484 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-12-17T22:50:13.484Z,1766011813.484 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-12-17T22:50:13.485Z,1766011813.485 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-12-17T22:50:13.485Z,1766011813.485 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-12-17T22:50:13.485Z,1766011813.485 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-17T22:50:13.485Z,1766011813.485 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-12-17T22:50:13.485Z,1766011813.485 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-17T22:50:13.485Z,1766011813.485 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-12-17T22:50:13.485Z,1766011813.485 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-12-17T22:50:13.485Z,1766011813.485 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-12-17T22:50:13.525Z,1766011813.525 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-12-17T22:50:13.526Z,1766011813.526 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-12-17T22:50:13.526Z,1766011813.526 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-12-17T22:50:18.496Z,1766011818.496 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:50:27.556Z,1766011827.556 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:50:36.396Z,1766011836.396 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:50:41.493Z,1766011841.493 [NAL9602](INFO): Powering down
2025-12-17T22:50:45.432Z,1766011845.432 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:51:16.456Z,1766011876.456 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:51:44.569Z,1766011904.569 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.139423
2025-12-17T22:51:45.572Z,1766011905.572 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:52:08.104Z,1766011928.104 [Radio_Surface](INFO): Powering down
2025-12-17T22:52:15.692Z,1766011935.692 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:52:16.696Z,1766011936.696 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-17T22:52:16.696Z,1766011936.696 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:52:46.816Z,1766011966.816 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:52:47.820Z,1766011967.820 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:53:17.961Z,1766011997.961 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:53:18.965Z,1766011998.965 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:53:49.108Z,1766012029.108 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:53:50.112Z,1766012030.112 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:54:20.240Z,1766012060.240 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:54:21.244Z,1766012061.244 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:54:51.400Z,1766012091.400 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:54:52.404Z,1766012092.404 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:55:22.552Z,1766012122.552 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:55:23.556Z,1766012123.556 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:55:53.676Z,1766012153.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:55:54.680Z,1766012154.680 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:56:24.804Z,1766012184.804 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:56:25.826Z,1766012185.826 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:56:55.944Z,1766012215.944 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:56:56.948Z,1766012216.948 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:57:27.072Z,1766012247.072 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:57:28.076Z,1766012248.076 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:57:58.205Z,1766012278.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:57:59.208Z,1766012279.208 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:58:29.332Z,1766012309.332 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:58:30.336Z,1766012310.336 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:59:00.456Z,1766012340.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:59:01.460Z,1766012341.460 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:59:09.478Z,1766012349.478 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00062402481496,35.0, +0.0,1503.6, 0
2025-12-17T22:59:31.604Z,1766012371.604 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T22:59:32.608Z,1766012372.608 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T22:59:39.388Z,1766012379.388 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0 +4.27, -0.11, 20.00, 50.00
2025-12-17T23:00:02.756Z,1766012402.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:00:03.760Z,1766012403.760 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:00:08.870Z,1766012408.870 [keepstation:SurfaceComms] Running Loop=1
2025-12-17T23:00:08.870Z,1766012408.870 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-17T23:00:08.871Z,1766012408.871 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-17T23:00:08.871Z,1766012408.871 [keepstation:SurfaceComms:A] Stopped
2025-12-17T23:00:08.871Z,1766012408.871 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-17T23:00:08.871Z,1766012408.871 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-17T23:00:08.873Z,1766012408.873 [keepstation:NeedComms] Running Loop=1
2025-12-17T23:00:08.873Z,1766012408.873 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-17T23:00:08.873Z,1766012408.873 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-17T23:00:08.873Z,1766012408.873 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-17T23:00:08.874Z,1766012408.874 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-17T23:00:08.874Z,1766012408.874 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-17T23:00:08.874Z,1766012408.874 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-17T23:00:08.875Z,1766012408.875 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-17T23:00:08.875Z,1766012408.875 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-17T23:00:08.875Z,1766012408.875 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-17T23:00:08.876Z,1766012408.876 [keepstation:NeedComms:A] Running Loop=1
2025-12-17T23:00:08.878Z,1766012408.878 [keepstation:NeedComms:A](INFO): last time_fix was: 1766011805.000000 second since 1970/01/01T00:00:00Z
2025-12-17T23:00:08.878Z,1766012408.878 [keepstation:NeedComms:A] Stopped
2025-12-17T23:00:08.878Z,1766012408.878 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-17T23:00:08.878Z,1766012408.878 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-17T23:00:08.879Z,1766012408.879 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-17T23:00:09.342Z,1766012409.342 [keepstation:TransitToStation:Dive] Stopped
2025-12-17T23:00:09.342Z,1766012409.342 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-12-17T23:00:09.342Z,1766012409.342 [keepstation:TransitToStation:Dive:A] Stopped
2025-12-17T23:00:09.342Z,1766012409.342 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-12-17T23:00:09.342Z,1766012409.342 [keepstation:DiveCmd:TransitToStation] Stopped
2025-12-17T23:00:09.342Z,1766012409.342 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-12-17T23:00:09.342Z,1766012409.342 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-12-17T23:00:09.343Z,1766012409.343 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-12-17T23:00:09.343Z,1766012409.343 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-12-17T23:00:09.343Z,1766012409.343 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-17T23:00:09.343Z,1766012409.343 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-12-17T23:00:09.343Z,1766012409.343 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-12-17T23:00:09.343Z,1766012409.343 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-12-17T23:00:09.346Z,1766012409.346 [keepstation:NeedComms:setActive:A] Stopped
2025-12-17T23:00:09.347Z,1766012409.347 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-17T23:00:09.679Z,1766012409.679 [keepstation:NeedComms:setActive:B] Stopped
2025-12-17T23:00:09.680Z,1766012409.680 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-17T23:00:09.680Z,1766012409.680 [keepstation:NeedComms:setActive] Stopped
2025-12-17T23:00:09.680Z,1766012409.680 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-17T23:00:33.880Z,1766012433.880 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:00:34.884Z,1766012434.884 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:01:05.014Z,1766012465.014 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:01:06.016Z,1766012466.016 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:01:06.934Z,1766012466.934 [keepstation:NeedComms:D] Running Loop=1
2025-12-17T23:01:07.536Z,1766012467.536 [Radio_Surface](INFO): Powering up
2025-12-17T23:01:07.729Z,1766012467.729 [NAL9602](INFO): Powering up
2025-12-17T23:01:12.788Z,1766012472.788 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11
2025-12-17T23:01:12.789Z,1766012472.789 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0
2025-12-17T23:01:18.693Z,1766012478.693 [NAL9602](INFO): NAL9602 initialized
2025-12-17T23:01:36.144Z,1766012496.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:01:37.148Z,1766012497.148 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-17T23:01:37.148Z,1766012497.148 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:02:07.269Z,1766012527.269 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:02:08.272Z,1766012528.272 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:02:31.818Z,1766012551.818 [NAL9602](INFO): SBD MO Status=0, MOMSN=6006, MT Status=0, MTMSN=0
2025-12-17T23:02:31.818Z,1766012551.818 [NAL9602](INFO): No messages in MT queue
2025-12-17T23:02:33.035Z,1766012553.035 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230232.00,A,3647.33938,N,12153.78647,W,1.400,104.86,171225,,,A*78
2025-12-17T23:02:33.037Z,1766012553.037 [NAL9602](INFO): GPS fix at 20251217T230232: (36.788990, -121.896441)
2025-12-17T23:02:33.052Z,1766012553.052 [UniversalFixResidualReporter](INFO): Fix residual: 34.6 %DT, over the last 507.9 m. Residual distance 175.6 m at bearing -44.3 degrees. Fix at (36.7890, -121.8964) with 184.2 m made good.
2025-12-17T23:02:33.053Z,1766012553.053 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.791142,-121.899147
2025-12-17T23:02:33.056Z,1766012553.056 [keepstation:NeedComms:D] Stopped
2025-12-17T23:02:33.057Z,1766012553.057 [keepstation:NeedComms:E] Running Loop=1
2025-12-17T23:02:38.392Z,1766012558.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:02:39.397Z,1766012559.397 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:02:51.046Z,1766012571.046 [NAL9602](INFO): SBD MO Status=1, MOMSN=6007, MT Status=0, MTMSN=0
2025-12-17T23:02:51.100Z,1766012571.100 [NAL9602](INFO): Sent 264 bytes from file Logs/20251217T224216/Courier0013.lzma
2025-12-17T23:02:51.100Z,1766012571.100 [NAL9602](INFO): Packets left to send: 0
2025-12-17T23:03:01.394Z,1766012581.394 [NAL9602](INFO): SBD MO Status=1, MOMSN=6008, MT Status=0, MTMSN=0
2025-12-17T23:03:01.444Z,1766012581.444 [NAL9602](INFO): Sent 295 bytes from file Logs/20251217T224216/Express0014.lzma
2025-12-17T23:03:01.444Z,1766012581.444 [NAL9602](INFO): Packets left to send: 0
2025-12-17T23:03:09.536Z,1766012589.536 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:03:10.540Z,1766012590.540 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:03:12.374Z,1766012592.374 [NAL9602](INFO): SBD MO Status=0, MOMSN=6009, MT Status=0, MTMSN=0
2025-12-17T23:03:12.465Z,1766012592.465 [keepstation:NeedComms:E] Stopped
2025-12-17T23:03:12.466Z,1766012592.466 [keepstation:NeedComms:F] Running Loop=1
2025-12-17T23:03:14.799Z,1766012594.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230314.00,A,3647.33428,N,12153.77694,W,1.186,114.34,171225,,,A*73
2025-12-17T23:03:14.802Z,1766012594.802 [NAL9602](INFO): GPS fix at 20251217T230314: (36.788905, -121.896282)
2025-12-17T23:03:14.829Z,1766012594.829 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.791142,-121.899147
2025-12-17T23:03:14.832Z,1766012594.832 [keepstation:NeedComms:F] Stopped
2025-12-17T23:03:14.832Z,1766012594.832 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-17T23:03:14.832Z,1766012594.832 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-17T23:03:14.832Z,1766012594.832 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-17T23:03:15.303Z,1766012595.303 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-17T23:03:15.303Z,1766012595.303 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-17T23:03:15.303Z,1766012595.303 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-17T23:03:15.303Z,1766012595.303 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-17T23:03:15.303Z,1766012595.303 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-17T23:03:15.303Z,1766012595.303 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-17T23:03:15.303Z,1766012595.303 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-17T23:03:15.655Z,1766012595.655 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-17T23:03:15.655Z,1766012595.655 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-17T23:03:15.655Z,1766012595.655 [keepstation:NeedComms:setInactive] Stopped
2025-12-17T23:03:15.655Z,1766012595.655 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-17T23:03:15.660Z,1766012595.660 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-17T23:03:15.660Z,1766012595.660 [keepstation:NeedComms] Stopped
2025-12-17T23:03:15.660Z,1766012595.660 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-17T23:03:15.660Z,1766012595.660 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-17T23:03:15.660Z,1766012595.660 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-17T23:03:16.031Z,1766012596.031 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-17T23:03:16.032Z,1766012596.032 [keepstation:SurfaceComms:B] Stopped
2025-12-17T23:03:16.032Z,1766012596.032 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-17T23:03:16.032Z,1766012596.032 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-17T23:03:16.032Z,1766012596.032 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-17T23:03:16.032Z,1766012596.032 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-17T23:03:16.032Z,1766012596.032 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-17T23:03:16.032Z,1766012596.032 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-17T23:03:16.427Z,1766012596.427 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-17T23:03:16.427Z,1766012596.427 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-17T23:03:16.427Z,1766012596.427 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-17T23:03:16.427Z,1766012596.427 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-17T23:03:16.427Z,1766012596.427 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-17T23:03:16.427Z,1766012596.427 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-17T23:03:16.427Z,1766012596.427 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-17T23:03:16.427Z,1766012596.427 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-17T23:03:16.837Z,1766012596.837 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-17T23:03:16.837Z,1766012596.837 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2025-12-17T23:03:16.837Z,1766012596.837 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-17T23:03:16.837Z,1766012596.837 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-17T23:03:16.837Z,1766012596.837 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-17T23:03:16.837Z,1766012596.837 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-17T23:03:16.837Z,1766012596.837 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-17T23:03:17.295Z,1766012597.295 [keepstation:TransitToStation:Dive] Running Loop=1
2025-12-17T23:03:17.295Z,1766012597.295 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-12-17T23:03:17.295Z,1766012597.295 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-12-17T23:03:17.295Z,1766012597.295 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-12-17T23:03:17.300Z,1766012597.300 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-12-17T23:03:17.300Z,1766012597.300 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-12-17T23:03:17.300Z,1766012597.300 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-12-17T23:03:17.300Z,1766012597.300 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-12-17T23:03:17.301Z,1766012597.301 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-12-17T23:03:17.301Z,1766012597.301 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-17T23:03:17.301Z,1766012597.301 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-12-17T23:03:17.301Z,1766012597.301 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-12-17T23:03:17.301Z,1766012597.301 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-12-17T23:03:17.301Z,1766012597.301 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-12-17T23:03:17.302Z,1766012597.302 [keepstation:SurfaceComms:setStation] Stopped
2025-12-17T23:03:17.302Z,1766012597.302 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-17T23:03:17.302Z,1766012597.302 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-17T23:03:17.302Z,1766012597.302 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-17T23:03:17.303Z,1766012597.303 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-17T23:03:17.303Z,1766012597.303 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-17T23:03:17.667Z,1766012597.667 [keepstation:SurfaceComms:setSink] Stopped
2025-12-17T23:03:17.667Z,1766012597.667 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-17T23:03:17.667Z,1766012597.667 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-17T23:03:17.667Z,1766012597.667 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-17T23:03:17.667Z,1766012597.667 [keepstation:SurfaceComms] Stopped
2025-12-17T23:03:17.667Z,1766012597.667 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-17T23:03:40.668Z,1766012620.668 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:03:41.672Z,1766012621.672 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:03:47.525Z,1766012627.525 [NAL9602](INFO): Powering down
2025-12-17T23:03:49.868Z,1766012629.868 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002828
2025-12-17T23:03:50.869Z,1766012630.869 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:03:59.742Z,1766012639.742 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:04:11.140Z,1766012651.140 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:04:20.172Z,1766012660.172 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:04:29.257Z,1766012669.257 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:04:38.180Z,1766012678.180 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:04:46.370Z,1766012686.370 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 14.14 mm (1 active estimators).
2025-12-17T23:04:49.881Z,1766012689.881 [DAT](INFO): DAT read: user:6>Rx Time:23:04:48.9356
2025-12-17T23:04:49.882Z,1766012689.882 [DAT](INFO): Rx dataTimestamp_ set to:1766012689.881748
2025-12-17T23:04:50.643Z,1766012690.643 [DAT](INFO): DAT read: 23:04:48.9356 LVL= 32752, 32753, 32754, 32755, AGC= 88, IDX= 74, 0.21, 3.068,-1.348,-2.255,-2.515, PHS=-0.598, 1.212, 0.216, RAW= 303.3, -10.6, CAL= 303.8, -14.9, ROT= 206.2, 14.9
2025-12-17T23:04:50.644Z,1766012690.644 [DAT](INFO): got valid direction response:
23:04:48.9356 LVL= 32752, 32753, 32754, 32755, AGC= 88, IDX= 74, 0.21, 3.068,-1.348,-2.255,-2.515, PHS=-0.598, 1.212, 0.216, RAW= 303.3, -10.6, CAL= 303.8, -14.9, ROT= 206.2, 14.9
2025-12-17T23:04:50.645Z,1766012690.645 [DAT](INFO): DAT read: $Error in header
2025-12-17T23:04:50.645Z,1766012690.645 [DAT](INFO): Received a bad header
2025-12-17T23:04:50.656Z,1766012690.656 [DAT](INFO): #Rx 1: Read direction message, but no range.
2025-12-17T23:04:50.657Z,1766012690.657 [DAT](INFO): direction in FSK: [-0.867089,-0.426661,-0.257133]
2025-12-17T23:05:09.388Z,1766012709.388 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:05:37.501Z,1766012737.501 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.323227
2025-12-17T23:05:38.504Z,1766012738.504 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:05:46.728Z,1766012746.728 [Radio_Surface](INFO): Powering down
2025-12-17T23:06:08.625Z,1766012768.625 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:06:09.628Z,1766012769.628 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-17T23:06:09.628Z,1766012769.628 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:06:39.756Z,1766012799.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:06:40.760Z,1766012800.760 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:07:10.888Z,1766012830.888 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:07:11.892Z,1766012831.892 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:07:42.020Z,1766012862.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:07:43.024Z,1766012863.024 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:08:13.148Z,1766012893.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:08:14.152Z,1766012894.152 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:08:44.297Z,1766012924.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:08:45.302Z,1766012925.302 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:09:10.615Z,1766012950.615 [RDI_Pathfinder](ERROR): only read 0 of 4 data items for bottom velocity. Device response is::BS.03
2025-12-17T23:09:15.420Z,1766012955.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:09:16.424Z,1766012956.424 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:09:46.544Z,1766012986.544 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:09:47.548Z,1766012987.548 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:10:17.672Z,1766013017.672 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:10:18.676Z,1766013018.676 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:10:48.808Z,1766013048.808 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:10:49.812Z,1766013049.812 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:11:19.932Z,1766013079.932 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:11:20.936Z,1766013080.936 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:11:51.056Z,1766013111.056 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:11:52.060Z,1766013112.060 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:12:22.181Z,1766013142.181 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:12:23.184Z,1766013143.184 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:12:53.304Z,1766013173.304 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:12:54.309Z,1766013174.309 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:13:15.014Z,1766013195.014 [keepstation:SurfaceComms] Running Loop=1
2025-12-17T23:13:15.014Z,1766013195.014 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-17T23:13:15.015Z,1766013195.015 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-17T23:13:15.015Z,1766013195.015 [keepstation:SurfaceComms:A] Stopped
2025-12-17T23:13:15.015Z,1766013195.015 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-17T23:13:15.018Z,1766013195.018 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-17T23:13:15.019Z,1766013195.019 [keepstation:NeedComms] Running Loop=1
2025-12-17T23:13:15.019Z,1766013195.019 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-17T23:13:15.020Z,1766013195.020 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-17T23:13:15.036Z,1766013195.036 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-17T23:13:15.037Z,1766013195.037 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-17T23:13:15.037Z,1766013195.037 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-17T23:13:15.038Z,1766013195.038 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-17T23:13:15.038Z,1766013195.038 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-17T23:13:15.038Z,1766013195.038 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-17T23:13:15.038Z,1766013195.038 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-17T23:13:15.039Z,1766013195.039 [keepstation:NeedComms:A] Running Loop=1
2025-12-17T23:13:15.045Z,1766013195.045 [keepstation:NeedComms:A](INFO): last time_fix was: 1766012594.000000 second since 1970/01/01T00:00:00Z
2025-12-17T23:13:15.045Z,1766013195.045 [keepstation:NeedComms:A] Stopped
2025-12-17T23:13:15.045Z,1766013195.045 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-17T23:13:15.045Z,1766013195.045 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-17T23:13:15.045Z,1766013195.045 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-17T23:13:15.451Z,1766013195.451 [keepstation:TransitToStation:Dive] Stopped
2025-12-17T23:13:15.452Z,1766013195.452 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-12-17T23:13:15.456Z,1766013195.456 [keepstation:TransitToStation:Dive:A] Stopped
2025-12-17T23:13:15.456Z,1766013195.456 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-12-17T23:13:15.456Z,1766013195.456 [keepstation:DiveCmd:TransitToStation] Stopped
2025-12-17T23:13:15.456Z,1766013195.456 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-12-17T23:13:15.457Z,1766013195.457 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-12-17T23:13:15.457Z,1766013195.457 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-12-17T23:13:15.457Z,1766013195.457 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-12-17T23:13:15.457Z,1766013195.457 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-17T23:13:15.457Z,1766013195.457 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-12-17T23:13:15.457Z,1766013195.457 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-12-17T23:13:15.457Z,1766013195.457 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-12-17T23:13:15.464Z,1766013195.464 [keepstation:NeedComms:setActive:A] Stopped
2025-12-17T23:13:15.464Z,1766013195.464 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-17T23:13:15.825Z,1766013195.825 [keepstation:NeedComms:setActive:B] Stopped
2025-12-17T23:13:15.825Z,1766013195.825 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-17T23:13:15.825Z,1766013195.825 [keepstation:NeedComms:setActive] Stopped
2025-12-17T23:13:15.825Z,1766013195.825 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-17T23:13:24.450Z,1766013204.450 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:13:25.452Z,1766013205.452 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:13:55.580Z,1766013235.580 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:13:56.584Z,1766013236.584 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:14:15.470Z,1766013255.470 [keepstation:NeedComms:D] Running Loop=1
2025-12-17T23:14:15.849Z,1766013255.849 [Radio_Surface](INFO): Powering up
2025-12-17T23:14:16.200Z,1766013256.200 [NAL9602](INFO): Powering up
2025-12-17T23:14:21.120Z,1766013261.120 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11
2025-12-17T23:14:21.121Z,1766013261.121 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0
2025-12-17T23:14:26.713Z,1766013266.713 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:14:27.718Z,1766013267.718 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-17T23:14:27.718Z,1766013267.718 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:14:33.573Z,1766013273.573 [NAL9602](INFO): NAL9602 initialized
2025-12-17T23:14:43.673Z,1766013283.673 [NAL9602](INFO): SBD MO Status=1, MOMSN=6011, MT Status=0, MTMSN=0
2025-12-17T23:14:43.673Z,1766013283.673 [NAL9602](INFO): No messages in MT queue
2025-12-17T23:14:57.836Z,1766013297.836 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:14:58.840Z,1766013298.840 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:15:20.847Z,1766013320.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231517.00,A,3647.36289,N,12153.90929,W,0.525,113.40,171225,,,A*77
2025-12-17T23:15:20.849Z,1766013320.849 [NAL9602](INFO): GPS fix at 20251217T231517: (36.789381, -121.898488)
2025-12-17T23:15:20.862Z,1766013320.862 [UniversalFixResidualReporter](INFO): Fix residual: 23.7 %DT, over the last 518.3 m. Residual distance 122.8 m at bearing 9.1 degrees. Fix at (36.7894, -121.8985) with 203.5 m made good.
2025-12-17T23:15:20.864Z,1766013320.864 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.791142,-121.899147
2025-12-17T23:15:20.866Z,1766013320.866 [keepstation:NeedComms:D] Stopped
2025-12-17T23:15:20.867Z,1766013320.867 [keepstation:NeedComms:E] Running Loop=1
2025-12-17T23:15:28.972Z,1766013328.972 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:15:29.976Z,1766013329.976 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:15:40.482Z,1766013340.482 [NAL9602](INFO): SBD MO Status=1, MOMSN=6012, MT Status=0, MTMSN=0
2025-12-17T23:15:40.532Z,1766013340.532 [NAL9602](INFO): Sent 84 bytes from file Logs/20251217T224216/Courier0016.lzma
2025-12-17T23:15:40.532Z,1766013340.532 [NAL9602](INFO): Packets left to send: 0
2025-12-17T23:15:52.953Z,1766013352.953 [NAL9602](INFO): SBD MO Status=1, MOMSN=6013, MT Status=0, MTMSN=0
2025-12-17T23:15:53.010Z,1766013353.010 [NAL9602](INFO): Sent 192 bytes from file Logs/20251217T224216/Express0017.lzma
2025-12-17T23:15:53.010Z,1766013353.010 [NAL9602](INFO): Packets left to send: 0
2025-12-17T23:16:00.111Z,1766013360.111 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:16:01.112Z,1766013361.112 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:16:05.114Z,1766013365.114 [NAL9602](INFO): SBD MO Status=0, MOMSN=6014, MT Status=0, MTMSN=0
2025-12-17T23:16:05.207Z,1766013365.207 [keepstation:NeedComms:E] Stopped
2025-12-17T23:16:05.208Z,1766013365.208 [keepstation:NeedComms:F] Running Loop=1
2025-12-17T23:16:07.539Z,1766013367.539 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231603.00,A,3647.35706,N,12153.89925,W,0.933,130.78,171225,,,A*75
2025-12-17T23:16:07.541Z,1766013367.541 [NAL9602](INFO): GPS fix at 20251217T231603: (36.789284, -121.898321)
2025-12-17T23:16:07.602Z,1766013367.602 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.791142,-121.899147
2025-12-17T23:16:07.608Z,1766013367.608 [keepstation:NeedComms:F] Stopped
2025-12-17T23:16:07.609Z,1766013367.609 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-17T23:16:07.609Z,1766013367.609 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-17T23:16:07.609Z,1766013367.609 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-17T23:16:07.975Z,1766013367.975 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-17T23:16:07.976Z,1766013367.976 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-17T23:16:07.976Z,1766013367.976 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-17T23:16:07.976Z,1766013367.976 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-17T23:16:07.976Z,1766013367.976 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-17T23:16:07.976Z,1766013367.976 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-17T23:16:07.976Z,1766013367.976 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-17T23:16:08.369Z,1766013368.369 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-17T23:16:08.369Z,1766013368.369 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-17T23:16:08.369Z,1766013368.369 [keepstation:NeedComms:setInactive] Stopped
2025-12-17T23:16:08.369Z,1766013368.369 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-17T23:16:08.370Z,1766013368.370 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-17T23:16:08.370Z,1766013368.370 [keepstation:NeedComms] Stopped
2025-12-17T23:16:08.370Z,1766013368.370 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-17T23:16:08.370Z,1766013368.370 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-17T23:16:08.370Z,1766013368.370 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-17T23:16:08.765Z,1766013368.765 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-17T23:16:08.765Z,1766013368.765 [keepstation:SurfaceComms:B] Stopped
2025-12-17T23:16:08.765Z,1766013368.765 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-17T23:16:08.765Z,1766013368.765 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-17T23:16:08.765Z,1766013368.765 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-17T23:16:08.765Z,1766013368.765 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-17T23:16:08.765Z,1766013368.765 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-17T23:16:08.765Z,1766013368.765 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-17T23:16:09.166Z,1766013369.166 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-17T23:16:09.166Z,1766013369.166 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-17T23:16:09.166Z,1766013369.166 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-17T23:16:09.167Z,1766013369.167 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-17T23:16:09.167Z,1766013369.167 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-17T23:16:09.167Z,1766013369.167 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-17T23:16:09.167Z,1766013369.167 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-17T23:16:09.167Z,1766013369.167 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-17T23:16:09.590Z,1766013369.590 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-17T23:16:09.591Z,1766013369.591 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2025-12-17T23:16:09.591Z,1766013369.591 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-17T23:16:09.591Z,1766013369.591 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-17T23:16:09.591Z,1766013369.591 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-17T23:16:09.591Z,1766013369.591 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-17T23:16:09.591Z,1766013369.591 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-17T23:16:09.983Z,1766013369.983 [keepstation:TransitToStation:Dive] Running Loop=1
2025-12-17T23:16:09.983Z,1766013369.983 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-12-17T23:16:09.983Z,1766013369.983 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-12-17T23:16:09.983Z,1766013369.983 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-12-17T23:16:09.984Z,1766013369.984 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-12-17T23:16:09.984Z,1766013369.984 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-12-17T23:16:09.984Z,1766013369.984 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-12-17T23:16:09.984Z,1766013369.984 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-12-17T23:16:09.984Z,1766013369.984 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-12-17T23:16:09.985Z,1766013369.985 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-17T23:16:09.985Z,1766013369.985 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-12-17T23:16:09.985Z,1766013369.985 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-12-17T23:16:09.985Z,1766013369.985 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-12-17T23:16:09.985Z,1766013369.985 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-12-17T23:16:09.986Z,1766013369.986 [keepstation:SurfaceComms:setStation] Stopped
2025-12-17T23:16:09.986Z,1766013369.986 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-17T23:16:09.986Z,1766013369.986 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-17T23:16:09.986Z,1766013369.986 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-17T23:16:09.986Z,1766013369.986 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-17T23:16:09.986Z,1766013369.986 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-17T23:16:10.379Z,1766013370.379 [keepstation:SurfaceComms:setSink] Stopped
2025-12-17T23:16:10.379Z,1766013370.379 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-17T23:16:10.379Z,1766013370.379 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-17T23:16:10.380Z,1766013370.380 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-17T23:16:10.380Z,1766013370.380 [keepstation:SurfaceComms] Stopped
2025-12-17T23:16:10.380Z,1766013370.380 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-17T23:16:31.240Z,1766013391.240 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:16:32.245Z,1766013392.245 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:16:39.453Z,1766013399.453 [NAL9602](INFO): Powering down
2025-12-17T23:17:00.364Z,1766013420.364 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002698
2025-12-17T23:17:01.365Z,1766013421.365 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:17:10.294Z,1766013430.294 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:17:19.108Z,1766013439.108 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:17:28.048Z,1766013448.048 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:17:36.996Z,1766013456.996 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:18:08.972Z,1766013488.972 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:18:36.083Z,1766013516.083 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.089558
2025-12-17T23:18:37.084Z,1766013517.084 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:18:45.808Z,1766013525.808 [Radio_Surface](INFO): Powering down
2025-12-17T23:19:07.218Z,1766013547.218 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:19:08.220Z,1766013548.220 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-17T23:19:08.220Z,1766013548.220 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:19:38.349Z,1766013578.349 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:19:39.353Z,1766013579.353 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:20:09.472Z,1766013609.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:20:10.478Z,1766013610.478 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:20:40.596Z,1766013640.596 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:20:41.600Z,1766013641.600 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:21:11.728Z,1766013671.728 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:21:12.734Z,1766013672.734 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:21:42.857Z,1766013702.857 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:21:43.860Z,1766013703.860 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:22:13.992Z,1766013733.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:22:14.997Z,1766013734.997 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:22:17.645Z,1766013737.645 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.791142,-121.899147
2025-12-17T23:22:17.645Z,1766013737.645 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-12-17T23:22:17.645Z,1766013737.645 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-12-17T23:22:17.645Z,1766013737.645 [keepstation:TransitToStation:C] Running Loop=1
2025-12-17T23:22:18.034Z,1766013738.034 [keepstation:TransitToStation:C] Stopped
2025-12-17T23:22:18.034Z,1766013738.034 [keepstation:TransitToStation:D] Running Loop=1
2025-12-17T23:22:18.034Z,1766013738.034 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-17T23:22:18.034Z,1766013738.034 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-17T23:22:18.034Z,1766013738.034 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-17T23:22:18.034Z,1766013738.034 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-17T23:22:18.035Z,1766013738.035 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-17T23:22:18.035Z,1766013738.035 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-17T23:22:18.035Z,1766013738.035 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-17T23:22:18.035Z,1766013738.035 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-17T23:22:18.035Z,1766013738.035 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-17T23:22:18.036Z,1766013738.036 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-17T23:22:18.036Z,1766013738.036 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-17T23:22:18.037Z,1766013738.037 [keepstation:DiveCmd:TransitToStation] Stopped
2025-12-17T23:22:18.037Z,1766013738.037 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-12-17T23:22:18.037Z,1766013738.037 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-12-17T23:22:18.037Z,1766013738.037 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-12-17T23:22:18.037Z,1766013738.037 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-12-17T23:22:18.037Z,1766013738.037 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-17T23:22:18.037Z,1766013738.037 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-12-17T23:22:18.037Z,1766013738.037 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-12-17T23:22:18.037Z,1766013738.037 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-12-17T23:22:18.447Z,1766013738.447 [keepstation:TransitToStation:D] Stopped
2025-12-17T23:22:18.447Z,1766013738.447 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-12-17T23:22:18.447Z,1766013738.447 [keepstation:TransitToStation] Stopped
2025-12-17T23:22:18.447Z,1766013738.447 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-12-17T23:22:18.448Z,1766013738.448 [keepstation:TransitToStation:Dive] Stopped
2025-12-17T23:22:18.448Z,1766013738.448 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-12-17T23:22:18.448Z,1766013738.448 [keepstation:TransitToStation:Dive:A] Stopped
2025-12-17T23:22:18.448Z,1766013738.448 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-12-17T23:22:18.448Z,1766013738.448 [keepstation:OnStation] Running Loop=1
2025-12-17T23:22:18.448Z,1766013738.448 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-12-17T23:22:18.839Z,1766013738.839 [keepstation:NeedComms] Running Loop=1
2025-12-17T23:22:18.839Z,1766013738.839 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-17T23:22:18.839Z,1766013738.839 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-17T23:22:18.839Z,1766013738.839 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-17T23:22:18.839Z,1766013738.839 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-17T23:22:18.840Z,1766013738.840 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-17T23:22:18.840Z,1766013738.840 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-17T23:22:18.840Z,1766013738.840 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-17T23:22:18.841Z,1766013738.841 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-17T23:22:18.841Z,1766013738.841 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-17T23:22:18.841Z,1766013738.841 [keepstation:NeedComms:A] Running Loop=1
2025-12-17T23:22:18.843Z,1766013738.843 [keepstation:NeedComms:A](INFO): last time_fix was: 1766013363.000000 second since 1970/01/01T00:00:00Z
2025-12-17T23:22:18.843Z,1766013738.843 [keepstation:NeedComms:A] Stopped
2025-12-17T23:22:18.843Z,1766013738.843 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-17T23:22:18.843Z,1766013738.843 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-17T23:22:18.843Z,1766013738.843 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-17T23:22:19.253Z,1766013739.253 [keepstation:NeedComms:setActive:A] Stopped
2025-12-17T23:22:19.253Z,1766013739.253 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-17T23:22:19.672Z,1766013739.672 [keepstation:NeedComms:setActive:B] Stopped
2025-12-17T23:22:19.672Z,1766013739.672 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-17T23:22:19.672Z,1766013739.672 [keepstation:NeedComms:setActive] Stopped
2025-12-17T23:22:19.672Z,1766013739.672 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-17T23:22:25.476Z,1766013745.476 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00
2025-12-17T23:22:35.615Z,1766013755.615 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,000624035.0, +13.9, 0.0,1503.4, 0
2025-12-17T23:22:45.116Z,1766013765.116 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:22:46.121Z,1766013766.121 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:23:16.240Z,1766013796.240 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:23:17.244Z,1766013797.244 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:23:19.411Z,1766013799.411 [keepstation:NeedComms:D] Running Loop=1
2025-12-17T23:23:20.128Z,1766013800.128 [Radio_Surface](INFO): Powering up
2025-12-17T23:23:20.262Z,1766013800.262 [NAL9602](INFO): Powering up
2025-12-17T23:23:20.675Z,1766013800.675 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00
2025-12-17T23:23:25.380Z,1766013805.380 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11
2025-12-17T23:23:25.381Z,1766013805.381 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0
2025-12-17T23:23:31.221Z,1766013811.221 [NAL9602](INFO): NAL9602 initialized
2025-12-17T23:23:47.364Z,1766013827.364 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:23:48.368Z,1766013828.368 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-17T23:23:48.368Z,1766013828.368 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:23:49.802Z,1766013829.802 [NAL9602](INFO): SBD MO Status=2, MOMSN=6015, MT Status=2, MTMSN=0
2025-12-17T23:23:49.802Z,1766013829.802 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-17T23:24:01.926Z,1766013841.926 [NAL9602](INFO): SBD MO Status=0, MOMSN=6015, MT Status=0, MTMSN=0
2025-12-17T23:24:01.926Z,1766013841.926 [NAL9602](INFO): No messages in MT queue
2025-12-17T23:24:03.143Z,1766013843.143 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232359.00,A,3647.42714,N,12153.86334,W,0.622,0.00,171225,,,A*78
2025-12-17T23:24:03.146Z,1766013843.146 [NAL9602](INFO): GPS fix at 20251217T232359: (36.790452, -121.897722)
2025-12-17T23:24:03.161Z,1766013843.161 [keepstation:NeedComms:D] Stopped
2025-12-17T23:24:03.161Z,1766013843.161 [keepstation:NeedComms:E] Running Loop=1
2025-12-17T23:24:08.804Z,1766013848.804 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0.,-32768,V
2025-12-17T23:24:18.488Z,1766013858.488 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:24:19.492Z,1766013859.492 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:24:36.006Z,1766013876.006 [NAL9602](INFO): SBD MO Status=1, MOMSN=6016, MT Status=0, MTMSN=0
2025-12-17T23:24:36.060Z,1766013876.060 [NAL9602](INFO): Sent 74 bytes from file Logs/20251217T224216/Courier0019.lzma
2025-12-17T23:24:36.060Z,1766013876.060 [NAL9602](INFO): Packets left to send: 0
2025-12-17T23:24:48.121Z,1766013888.121 [NAL9602](INFO): SBD MO Status=1, MOMSN=6017, MT Status=0, MTMSN=0
2025-12-17T23:24:48.168Z,1766013888.168 [NAL9602](INFO): Sent 218 bytes from file Logs/20251217T224216/Express0020.lzma
2025-12-17T23:24:48.168Z,1766013888.168 [NAL9602](INFO): Packets left to send: 0
2025-12-17T23:24:49.616Z,1766013889.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:24:50.620Z,1766013890.620 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:25:11.716Z,1766013911.716 [NAL9602](INFO): SBD MO Status=0, MOMSN=6018, MT Status=0, MTMSN=0
2025-12-17T23:25:11.799Z,1766013911.799 [keepstation:NeedComms:E] Stopped
2025-12-17T23:25:11.799Z,1766013911.799 [keepstation:NeedComms:F] Running Loop=1
2025-12-17T23:25:14.143Z,1766013914.143 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232510.00,A,3647.41770,N,12153.84740,W,1.458,120.11,171225,,,A*7A
2025-12-17T23:25:14.145Z,1766013914.145 [NAL9602](INFO): GPS fix at 20251217T232510: (36.790295, -121.897457)
2025-12-17T23:25:14.184Z,1766013914.184 [keepstation:NeedComms:F] Stopped
2025-12-17T23:25:14.184Z,1766013914.184 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-17T23:25:14.185Z,1766013914.185 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-17T23:25:14.185Z,1766013914.185 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-17T23:25:14.596Z,1766013914.596 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-17T23:25:14.596Z,1766013914.596 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-17T23:25:14.596Z,1766013914.596 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-17T23:25:14.596Z,1766013914.596 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-17T23:25:14.596Z,1766013914.596 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-17T23:25:14.596Z,1766013914.596 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-17T23:25:14.596Z,1766013914.596 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-17T23:25:14.967Z,1766013914.967 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-17T23:25:14.967Z,1766013914.967 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-17T23:25:14.967Z,1766013914.967 [keepstation:NeedComms:setInactive] Stopped
2025-12-17T23:25:14.967Z,1766013914.967 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-17T23:25:14.968Z,1766013914.968 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-17T23:25:14.968Z,1766013914.968 [keepstation:NeedComms] Stopped
2025-12-17T23:25:14.969Z,1766013914.969 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-17T23:25:14.969Z,1766013914.969 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-17T23:25:14.969Z,1766013914.969 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-17T23:25:15.371Z,1766013915.371 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-12-17T23:25:15.371Z,1766013915.371 [keepstation:OnStation] Stopped
2025-12-17T23:25:15.371Z,1766013915.371 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-12-17T23:25:15.371Z,1766013915.371 [keepstation:KeepStation] Running Loop=1
2025-12-17T23:25:15.371Z,1766013915.371 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-12-17T23:25:15.371Z,1766013915.371 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-17T23:25:15.371Z,1766013915.371 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-12-17T23:25:15.372Z,1766013915.372 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-12-17T23:25:15.372Z,1766013915.372 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-12-17T23:25:15.372Z,1766013915.372 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-12-17T23:25:15.372Z,1766013915.372 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-12-17T23:25:15.791Z,1766013915.791 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-17T23:25:20.740Z,1766013920.740 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:25:21.744Z,1766013921.744 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:25:46.495Z,1766013946.495 [NAL9602](INFO): Powering down
2025-12-17T23:25:51.868Z,1766013951.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:25:52.873Z,1766013952.873 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:26:00.660Z,1766013960.660 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002761
2025-12-17T23:26:01.660Z,1766013961.660 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:26:10.470Z,1766013970.470 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:26:19.200Z,1766013979.200 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:26:27.936Z,1766013987.936 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:26:36.848Z,1766013996.848 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:26:45.536Z,1766014005.536 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:26:54.092Z,1766014014.092 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:27:24.213Z,1766014044.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:27:25.217Z,1766014045.217 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:27:53.329Z,1766014073.329 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239555
2025-12-17T23:27:54.332Z,1766014074.332 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:27:59.320Z,1766014079.320 [Radio_Surface](INFO): Powering down
2025-12-17T23:28:14.758Z,1766014094.758 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, , +5.31, +0.13, 20.00,895.19
2025-12-17T23:28:24.468Z,1766014104.468 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:28:25.472Z,1766014105.472 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-17T23:28:25.472Z,1766014105.472 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:28:55.600Z,1766014135.600 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:28:56.604Z,1766014136.604 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:29:26.724Z,1766014166.724 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:29:27.728Z,1766014167.728 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:29:57.848Z,1766014197.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:29:58.852Z,1766014198.852 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:30:28.984Z,1766014228.984 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:30:29.988Z,1766014229.988 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:31:00.108Z,1766014260.108 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:31:01.116Z,1766014261.116 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:31:31.233Z,1766014291.233 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:31:32.236Z,1766014292.236 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:32:02.360Z,1766014322.360 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:32:03.364Z,1766014323.364 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:32:33.484Z,1766014353.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:32:34.488Z,1766014354.488 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:33:04.608Z,1766014384.608 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:33:05.612Z,1766014385.612 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:33:35.732Z,1766014415.732 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:33:36.736Z,1766014416.736 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:34:06.868Z,1766014446.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:34:07.872Z,1766014447.872 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:34:37.992Z,1766014477.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:34:38.996Z,1766014478.996 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:35:09.124Z,1766014509.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:35:10.128Z,1766014510.128 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:35:14.514Z,1766014514.514 [keepstation:SurfaceComms] Running Loop=1
2025-12-17T23:35:14.514Z,1766014514.514 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-17T23:35:14.515Z,1766014514.515 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-17T23:35:14.515Z,1766014514.515 [keepstation:SurfaceComms:A] Stopped
2025-12-17T23:35:14.515Z,1766014514.515 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-17T23:35:14.515Z,1766014514.515 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-17T23:35:14.517Z,1766014514.517 [keepstation:NeedComms] Running Loop=1
2025-12-17T23:35:14.517Z,1766014514.517 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-17T23:35:14.517Z,1766014514.517 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-17T23:35:14.517Z,1766014514.517 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-17T23:35:14.517Z,1766014514.517 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-17T23:35:14.518Z,1766014514.518 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-17T23:35:14.518Z,1766014514.518 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-17T23:35:14.518Z,1766014514.518 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-17T23:35:14.519Z,1766014514.519 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-17T23:35:14.519Z,1766014514.519 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-17T23:35:14.519Z,1766014514.519 [keepstation:NeedComms:A] Running Loop=1
2025-12-17T23:35:14.522Z,1766014514.522 [keepstation:NeedComms:A](INFO): last time_fix was: 1766013910.000000 second since 1970/01/01T00:00:00Z
2025-12-17T23:35:14.522Z,1766014514.522 [keepstation:NeedComms:A] Stopped
2025-12-17T23:35:14.522Z,1766014514.522 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-17T23:35:14.522Z,1766014514.522 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-17T23:35:14.522Z,1766014514.522 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-17T23:35:14.921Z,1766014514.921 [keepstation:KeepStation:Dive] Stopped
2025-12-17T23:35:14.921Z,1766014514.921 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-12-17T23:35:14.921Z,1766014514.921 [keepstation:KeepStation:Dive:A] Stopped
2025-12-17T23:35:14.921Z,1766014514.921 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-12-17T23:35:14.922Z,1766014514.922 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-17T23:35:14.922Z,1766014514.922 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-17T23:35:14.922Z,1766014514.922 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-17T23:35:14.922Z,1766014514.922 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-17T23:35:14.922Z,1766014514.922 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-17T23:35:14.922Z,1766014514.922 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-17T23:35:14.925Z,1766014514.925 [keepstation:NeedComms:setActive:A] Stopped
2025-12-17T23:35:14.925Z,1766014514.925 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-17T23:35:15.329Z,1766014515.329 [keepstation:NeedComms:setActive:B] Stopped
2025-12-17T23:35:15.330Z,1766014515.330 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-17T23:35:15.330Z,1766014515.330 [keepstation:NeedComms:setActive] Stopped
2025-12-17T23:35:15.330Z,1766014515.330 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-17T23:35:40.248Z,1766014540.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:35:41.253Z,1766014541.253 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:36:11.376Z,1766014571.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:36:12.393Z,1766014572.393 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:36:42.512Z,1766014602.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:36:43.516Z,1766014603.516 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:37:13.642Z,1766014633.642 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:37:14.644Z,1766014634.644 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:37:35.971Z,1766014655.971 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00062403264138,35.0, +0.0,1502.4, 0
2025-12-17T23:37:44.772Z,1766014664.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:37:45.776Z,1766014665.776 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:38:05.908Z,1766014685.908 [keepstation:NeedComms:D] Running Loop=1
2025-12-17T23:38:06.532Z,1766014686.532 [Radio_Surface](INFO): Powering up
2025-12-17T23:38:06.671Z,1766014686.671 [NAL9602](INFO): Powering up
2025-12-17T23:38:11.784Z,1766014691.784 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11
2025-12-17T23:38:11.785Z,1766014691.785 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0
2025-12-17T23:38:15.896Z,1766014695.896 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:38:16.900Z,1766014696.900 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-17T23:38:16.900Z,1766014696.900 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:38:17.573Z,1766014697.573 [NAL9602](INFO): NAL9602 initialized
2025-12-17T23:38:47.020Z,1766014727.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:38:48.024Z,1766014728.024 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:39:18.160Z,1766014758.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:39:19.164Z,1766014759.164 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:39:49.284Z,1766014789.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:39:50.289Z,1766014790.289 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:39:53.730Z,1766014793.730 [NAL9602](INFO): SBD MO Status=2, MOMSN=6019, MT Status=2, MTMSN=0
2025-12-17T23:39:53.730Z,1766014793.730 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-17T23:40:08.737Z,1766014808.737 [RDI_Pathfinder](ERROR): only read -1 of 1 data item for water mass range. Device response is::WD,
2025-12-17T23:40:17.158Z,1766014817.158 [NAL9602](INFO): SBD MO Status=2, MOMSN=6019, MT Status=2, MTMSN=0
2025-12-17T23:40:17.158Z,1766014817.158 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-17T23:40:20.412Z,1766014820.412 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:40:21.416Z,1766014821.416 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:40:49.168Z,1766014849.168 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002666
2025-12-17T23:40:50.168Z,1766014850.168 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:40:58.760Z,1766014858.760 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:41:07.477Z,1766014867.477 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:41:09.713Z,1766014869.713 [NAL9602](INFO): SBD MO Status=0, MOMSN=6019, MT Status=0, MTMSN=0
2025-12-17T23:41:09.714Z,1766014869.714 [NAL9602](INFO): No messages in MT queue
2025-12-17T23:41:10.891Z,1766014870.891 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234110.00,A,3647.23853,N,12153.67920,W,0.680,140.55,171225,,,A*77
2025-12-17T23:41:10.893Z,1766014870.893 [NAL9602](INFO): GPS fix at 20251217T234110: (36.787309, -121.894653)
2025-12-17T23:41:10.943Z,1766014870.943 [keepstation:NeedComms:D] Stopped
2025-12-17T23:41:10.943Z,1766014870.943 [keepstation:NeedComms:E] Running Loop=1
2025-12-17T23:41:13.734Z,1766014873.734 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20251217T224216/Courier0022.lzma
2025-12-17T23:41:14.534Z,1766014874.534 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Courier0022.lzma.bak
2025-12-17T23:41:14.534Z,1766014874.534 [DataOverHttps](INFO): SBD MOMSN=26517942
2025-12-17T23:41:32.048Z,1766014892.048 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20251217T224216/Express0023.lzma
2025-12-17T23:41:33.050Z,1766014893.050 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Express0023.lzma.bak
2025-12-17T23:41:33.050Z,1766014893.050 [DataOverHttps](INFO): SBD MOMSN=26517946
2025-12-17T23:41:34.899Z,1766014894.899 [keepstation:NeedComms:E] Stopped
2025-12-17T23:41:34.899Z,1766014894.899 [keepstation:NeedComms:F] Running Loop=1
2025-12-17T23:41:36.887Z,1766014896.887 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234136.00,A,3647.23452,N,12153.67070,W,0.933,135.34,171225,,,A*70
2025-12-17T23:41:36.889Z,1766014896.889 [NAL9602](INFO): GPS fix at 20251217T234136: (36.787242, -121.894512)
2025-12-17T23:41:36.962Z,1766014896.962 [keepstation:NeedComms:F] Stopped
2025-12-17T23:41:36.962Z,1766014896.962 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-17T23:41:36.962Z,1766014896.962 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-17T23:41:36.962Z,1766014896.962 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-17T23:41:37.331Z,1766014897.331 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-17T23:41:37.331Z,1766014897.331 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-17T23:41:37.331Z,1766014897.331 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-17T23:41:37.331Z,1766014897.331 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-17T23:41:37.331Z,1766014897.331 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-17T23:41:37.331Z,1766014897.331 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-17T23:41:37.331Z,1766014897.331 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-17T23:41:37.724Z,1766014897.724 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-17T23:41:37.724Z,1766014897.724 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-17T23:41:37.724Z,1766014897.724 [keepstation:NeedComms:setInactive] Stopped
2025-12-17T23:41:37.725Z,1766014897.725 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-17T23:41:37.725Z,1766014897.725 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-17T23:41:37.725Z,1766014897.725 [keepstation:NeedComms] Stopped
2025-12-17T23:41:37.725Z,1766014897.725 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-17T23:41:37.725Z,1766014897.725 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-17T23:41:37.726Z,1766014897.726 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-17T23:41:38.129Z,1766014898.129 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-17T23:41:38.129Z,1766014898.129 [keepstation:SurfaceComms:B] Stopped
2025-12-17T23:41:38.130Z,1766014898.130 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-17T23:41:38.130Z,1766014898.130 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-17T23:41:38.130Z,1766014898.130 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-17T23:41:38.130Z,1766014898.130 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-17T23:41:38.130Z,1766014898.130 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-17T23:41:38.130Z,1766014898.130 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-17T23:41:38.518Z,1766014898.518 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-17T23:41:38.518Z,1766014898.518 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-17T23:41:38.518Z,1766014898.518 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-17T23:41:38.518Z,1766014898.518 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-17T23:41:38.518Z,1766014898.518 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-17T23:41:38.518Z,1766014898.518 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-17T23:41:38.518Z,1766014898.518 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-17T23:41:38.518Z,1766014898.518 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-17T23:41:38.994Z,1766014898.994 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-17T23:41:38.994Z,1766014898.994 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-17T23:41:38.994Z,1766014898.994 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-17T23:41:38.994Z,1766014898.994 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-17T23:41:38.994Z,1766014898.994 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-17T23:41:38.994Z,1766014898.994 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-17T23:41:39.335Z,1766014899.335 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-17T23:41:39.335Z,1766014899.335 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-12-17T23:41:39.335Z,1766014899.335 [keepstation:SurfaceComms:setStation] Stopped
2025-12-17T23:41:39.336Z,1766014899.336 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-17T23:41:39.340Z,1766014899.340 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-17T23:41:39.340Z,1766014899.340 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-17T23:41:39.340Z,1766014899.340 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-17T23:41:39.749Z,1766014899.749 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-17T23:41:39.749Z,1766014899.749 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-12-17T23:41:39.749Z,1766014899.749 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-12-17T23:41:39.749Z,1766014899.749 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-12-17T23:41:39.750Z,1766014899.750 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-17T23:41:39.750Z,1766014899.750 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-17T23:41:39.750Z,1766014899.750 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-17T23:41:39.750Z,1766014899.750 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-17T23:41:39.750Z,1766014899.750 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-17T23:41:39.750Z,1766014899.750 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-17T23:41:39.750Z,1766014899.750 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-17T23:41:39.751Z,1766014899.751 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-12-17T23:41:39.757Z,1766014899.757 [keepstation:SurfaceComms:setSink] Stopped
2025-12-17T23:41:39.757Z,1766014899.757 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-17T23:41:39.757Z,1766014899.757 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-17T23:41:39.757Z,1766014899.757 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-17T23:41:39.757Z,1766014899.757 [keepstation:SurfaceComms] Stopped
2025-12-17T23:41:39.757Z,1766014899.757 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-17T23:41:43.026Z,1766014903.026 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:41:52.212Z,1766014912.212 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:42:01.424Z,1766014921.424 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:42:09.595Z,1766014929.595 [NAL9602](INFO): Powering down
2025-12-17T23:42:12.309Z,1766014932.309 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:42:21.392Z,1766014941.392 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:42:30.564Z,1766014950.564 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:42:39.568Z,1766014959.568 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:42:48.676Z,1766014968.676 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:42:57.896Z,1766014977.896 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:43:06.829Z,1766014986.829 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:43:15.725Z,1766014995.725 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:43:24.693Z,1766015004.693 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:43:33.692Z,1766015013.692 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:43:42.676Z,1766015022.676 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:43:51.608Z,1766015031.608 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:44:00.492Z,1766015040.492 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:44:10.787Z,1766015050.787 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:44:19.861Z,1766015059.861 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:44:28.790Z,1766015068.790 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:44:37.708Z,1766015077.708 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:44:46.637Z,1766015086.637 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:44:55.553Z,1766015095.553 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:45:04.548Z,1766015104.548 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:45:13.719Z,1766015113.719 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:45:22.620Z,1766015122.620 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:45:31.612Z,1766015131.612 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:45:40.692Z,1766015140.692 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:45:49.604Z,1766015149.604 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:45:58.604Z,1766015158.604 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:45:58.957Z,1766015158.957 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00
2025-12-17T23:46:10.140Z,1766015170.140 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:46:18.840Z,1766015178.840 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:46:27.908Z,1766015187.908 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:46:59.013Z,1766015219.013 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:47:27.124Z,1766015247.124 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.218361
2025-12-17T23:47:28.128Z,1766015248.128 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:47:36.220Z,1766015256.220 [Radio_Surface](INFO): Powering down
2025-12-17T23:47:58.248Z,1766015278.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:47:59.252Z,1766015279.252 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-17T23:47:59.252Z,1766015279.252 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:48:29.380Z,1766015309.380 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:48:30.384Z,1766015310.384 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:49:00.508Z,1766015340.508 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:49:01.512Z,1766015341.512 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:49:31.665Z,1766015371.665 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:49:32.668Z,1766015372.668 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:50:02.789Z,1766015402.789 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:50:03.793Z,1766015403.793 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:50:33.913Z,1766015433.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:50:34.916Z,1766015434.916 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:51:05.037Z,1766015465.037 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:51:06.040Z,1766015466.040 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:51:36.173Z,1766015496.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:51:37.176Z,1766015497.176 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:51:37.282Z,1766015497.282 [keepstation:SurfaceComms] Running Loop=1
2025-12-17T23:51:37.282Z,1766015497.282 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-17T23:51:37.283Z,1766015497.283 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-17T23:51:37.283Z,1766015497.283 [keepstation:SurfaceComms:A] Stopped
2025-12-17T23:51:37.283Z,1766015497.283 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-17T23:51:37.283Z,1766015497.283 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-17T23:51:37.301Z,1766015497.301 [keepstation:NeedComms] Running Loop=1
2025-12-17T23:51:37.301Z,1766015497.301 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-17T23:51:37.301Z,1766015497.301 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-17T23:51:37.301Z,1766015497.301 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-17T23:51:37.301Z,1766015497.301 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-17T23:51:37.302Z,1766015497.302 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-17T23:51:37.302Z,1766015497.302 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-17T23:51:37.303Z,1766015497.303 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-17T23:51:37.303Z,1766015497.303 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-17T23:51:37.303Z,1766015497.303 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-17T23:51:37.303Z,1766015497.303 [keepstation:NeedComms:A] Running Loop=1
2025-12-17T23:51:37.309Z,1766015497.309 [keepstation:NeedComms:A](INFO): last time_fix was: 1766014896.000000 second since 1970/01/01T00:00:00Z
2025-12-17T23:51:37.309Z,1766015497.309 [keepstation:NeedComms:A] Stopped
2025-12-17T23:51:37.309Z,1766015497.309 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-17T23:51:37.309Z,1766015497.309 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-17T23:51:37.310Z,1766015497.310 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-17T23:51:37.688Z,1766015497.688 [keepstation:KeepStation:Dive] Stopped
2025-12-17T23:51:37.689Z,1766015497.689 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-12-17T23:51:37.689Z,1766015497.689 [keepstation:KeepStation:Dive:A] Stopped
2025-12-17T23:51:37.689Z,1766015497.689 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-12-17T23:51:37.689Z,1766015497.689 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-17T23:51:37.689Z,1766015497.689 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-17T23:51:37.689Z,1766015497.689 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-17T23:51:37.689Z,1766015497.689 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-17T23:51:37.689Z,1766015497.689 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-17T23:51:37.689Z,1766015497.689 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-17T23:51:37.692Z,1766015497.692 [keepstation:NeedComms:setActive:A] Stopped
2025-12-17T23:51:37.692Z,1766015497.692 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-17T23:51:38.119Z,1766015498.119 [keepstation:NeedComms:setActive:B] Stopped
2025-12-17T23:51:38.119Z,1766015498.119 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-17T23:51:38.119Z,1766015498.119 [keepstation:NeedComms:setActive] Stopped
2025-12-17T23:51:38.119Z,1766015498.119 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-17T23:52:07.296Z,1766015527.296 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:52:08.300Z,1766015528.300 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:52:38.420Z,1766015558.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:52:39.425Z,1766015559.425 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:53:09.548Z,1766015589.548 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:53:10.552Z,1766015590.552 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:53:25.242Z,1766015605.242 [keepstation:NeedComms:D] Running Loop=1
2025-12-17T23:53:25.720Z,1766015605.720 [Radio_Surface](INFO): Powering up
2025-12-17T23:53:25.999Z,1766015605.999 [NAL9602](INFO): Powering up
2025-12-17T23:53:31.001Z,1766015611.001 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11
2025-12-17T23:53:31.002Z,1766015611.002 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0
2025-12-17T23:53:36.991Z,1766015616.991 [NAL9602](INFO): NAL9602 initialized
2025-12-17T23:53:38.203Z,1766015618.203 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235337.00,A,3647.29342,N,12153.72500,W,0.311,333.65,171225,,,A*72
2025-12-17T23:53:38.205Z,1766015618.205 [NAL9602](INFO): GPS fix at 20251217T235337: (36.788224, -121.895417)
2025-12-17T23:53:38.219Z,1766015618.219 [keepstation:NeedComms:D] Stopped
2025-12-17T23:53:38.219Z,1766015618.219 [keepstation:NeedComms:E] Running Loop=1
2025-12-17T23:53:40.676Z,1766015620.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:53:41.680Z,1766015621.680 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-17T23:53:41.680Z,1766015621.680 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:54:07.399Z,1766015647.399 [NAL9602](INFO): SBD MO Status=1, MOMSN=6021, MT Status=0, MTMSN=0
2025-12-17T23:54:07.448Z,1766015647.448 [NAL9602](INFO): Sent 74 bytes from file Logs/20251217T224216/Courier0025.lzma
2025-12-17T23:54:07.448Z,1766015647.448 [NAL9602](INFO): Packets left to send: 0
2025-12-17T23:54:11.805Z,1766015651.805 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:54:12.808Z,1766015652.808 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:54:16.194Z,1766015656.194 [NAL9602](INFO): SBD MO Status=1, MOMSN=6022, MT Status=0, MTMSN=0
2025-12-17T23:54:16.248Z,1766015656.248 [NAL9602](INFO): Sent 176 bytes from file Logs/20251217T224216/Express0026.lzma
2025-12-17T23:54:16.248Z,1766015656.248 [NAL9602](INFO): Packets left to send: 0
2025-12-17T23:54:28.019Z,1766015668.019 [NAL9602](INFO): SBD MO Status=0, MOMSN=6023, MT Status=0, MTMSN=0
2025-12-17T23:54:28.141Z,1766015668.141 [keepstation:NeedComms:E] Stopped
2025-12-17T23:54:28.141Z,1766015668.141 [keepstation:NeedComms:F] Running Loop=1
2025-12-17T23:54:30.439Z,1766015670.439 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235429.00,A,3647.28973,N,12153.71942,W,0.350,177.38,171225,,,A*75
2025-12-17T23:54:30.442Z,1766015670.442 [NAL9602](INFO): GPS fix at 20251217T235429: (36.788162, -121.895324)
2025-12-17T23:54:30.475Z,1766015670.475 [keepstation:NeedComms:F] Stopped
2025-12-17T23:54:30.475Z,1766015670.475 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-17T23:54:30.475Z,1766015670.475 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-17T23:54:30.475Z,1766015670.475 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-17T23:54:30.875Z,1766015670.875 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-17T23:54:30.875Z,1766015670.875 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-17T23:54:30.875Z,1766015670.875 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-17T23:54:30.875Z,1766015670.875 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-17T23:54:30.875Z,1766015670.875 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-17T23:54:30.875Z,1766015670.875 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-17T23:54:30.876Z,1766015670.876 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-17T23:54:31.258Z,1766015671.258 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-17T23:54:31.258Z,1766015671.258 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-17T23:54:31.258Z,1766015671.258 [keepstation:NeedComms:setInactive] Stopped
2025-12-17T23:54:31.258Z,1766015671.258 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-17T23:54:31.259Z,1766015671.259 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-17T23:54:31.259Z,1766015671.259 [keepstation:NeedComms] Stopped
2025-12-17T23:54:31.259Z,1766015671.259 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-17T23:54:31.259Z,1766015671.259 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-17T23:54:31.259Z,1766015671.259 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-17T23:54:31.683Z,1766015671.683 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-17T23:54:31.683Z,1766015671.683 [keepstation:SurfaceComms:B] Stopped
2025-12-17T23:54:31.683Z,1766015671.683 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-17T23:54:31.683Z,1766015671.683 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-17T23:54:31.683Z,1766015671.683 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-17T23:54:31.683Z,1766015671.683 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-17T23:54:31.683Z,1766015671.683 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-17T23:54:31.683Z,1766015671.683 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-17T23:54:32.130Z,1766015672.130 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-17T23:54:32.130Z,1766015672.130 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-17T23:54:32.130Z,1766015672.130 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-17T23:54:32.130Z,1766015672.130 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-17T23:54:32.130Z,1766015672.130 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-17T23:54:32.131Z,1766015672.131 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-17T23:54:32.131Z,1766015672.131 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-17T23:54:32.131Z,1766015672.131 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-17T23:54:32.479Z,1766015672.479 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-17T23:54:32.480Z,1766015672.480 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-17T23:54:32.480Z,1766015672.480 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-17T23:54:32.480Z,1766015672.480 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-17T23:54:32.480Z,1766015672.480 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-17T23:54:32.480Z,1766015672.480 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-17T23:54:32.878Z,1766015672.878 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-17T23:54:32.878Z,1766015672.878 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-12-17T23:54:32.878Z,1766015672.878 [keepstation:SurfaceComms:setStation] Stopped
2025-12-17T23:54:32.878Z,1766015672.878 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-17T23:54:32.878Z,1766015672.878 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-17T23:54:32.878Z,1766015672.878 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-17T23:54:32.878Z,1766015672.878 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-17T23:54:33.275Z,1766015673.275 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-17T23:54:33.276Z,1766015673.276 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-12-17T23:54:33.276Z,1766015673.276 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-12-17T23:54:33.276Z,1766015673.276 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-12-17T23:54:33.276Z,1766015673.276 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-17T23:54:33.277Z,1766015673.277 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-17T23:54:33.277Z,1766015673.277 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-17T23:54:33.277Z,1766015673.277 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-17T23:54:33.277Z,1766015673.277 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-17T23:54:33.277Z,1766015673.277 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-17T23:54:33.277Z,1766015673.277 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-17T23:54:33.278Z,1766015673.278 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-12-17T23:54:33.279Z,1766015673.279 [keepstation:SurfaceComms:setSink] Stopped
2025-12-17T23:54:33.279Z,1766015673.279 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-17T23:54:33.279Z,1766015673.279 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-17T23:54:33.280Z,1766015673.280 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-17T23:54:33.280Z,1766015673.280 [keepstation:SurfaceComms] Stopped
2025-12-17T23:54:33.280Z,1766015673.280 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-17T23:54:42.936Z,1766015682.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:54:43.942Z,1766015683.942 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:55:02.128Z,1766015702.128 [NAL9602](INFO): Powering down
2025-12-17T23:55:14.074Z,1766015714.074 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:55:15.076Z,1766015715.076 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:55:45.196Z,1766015745.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:55:46.200Z,1766015746.200 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:56:02.366Z,1766015762.366 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00062403450780,35.0, +14.7, 0.0, 0
2025-12-17T23:56:14.568Z,1766015774.568 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002665
2025-12-17T23:56:15.568Z,1766015775.568 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:56:24.556Z,1766015784.556 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:56:33.664Z,1766015793.664 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:56:42.873Z,1766015802.873 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:57:14.073Z,1766015834.073 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:57:42.189Z,1766015862.189 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.319866
2025-12-17T23:57:43.192Z,1766015863.192 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:57:52.816Z,1766015872.816 [Radio_Surface](INFO): Powering down
2025-12-17T23:58:13.320Z,1766015893.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:58:14.325Z,1766015894.325 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-17T23:58:14.325Z,1766015894.325 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:58:44.444Z,1766015924.444 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:58:45.448Z,1766015925.448 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:59:15.576Z,1766015955.576 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:59:16.580Z,1766015956.580 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-17T23:59:46.716Z,1766015986.716 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-17T23:59:47.722Z,1766015987.722 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:00:17.840Z,1766016017.840 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:00:18.844Z,1766016018.844 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:00:48.964Z,1766016048.964 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:00:49.968Z,1766016049.968 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:01:20.092Z,1766016080.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:01:21.096Z,1766016081.096 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:01:51.216Z,1766016111.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:01:52.220Z,1766016112.220 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:02:22.340Z,1766016142.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:02:23.344Z,1766016143.344 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:02:53.480Z,1766016173.480 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:02:54.484Z,1766016174.484 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:03:24.608Z,1766016204.608 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:03:25.614Z,1766016205.614 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:03:55.732Z,1766016235.732 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:03:56.736Z,1766016236.736 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:04:26.868Z,1766016266.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:04:27.872Z,1766016267.872 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:04:30.617Z,1766016270.617 [keepstation:SurfaceComms] Running Loop=1
2025-12-18T00:04:30.617Z,1766016270.617 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-18T00:04:30.617Z,1766016270.617 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-18T00:04:30.617Z,1766016270.617 [keepstation:SurfaceComms:A] Stopped
2025-12-18T00:04:30.617Z,1766016270.617 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-18T00:04:30.618Z,1766016270.618 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-18T00:04:30.619Z,1766016270.619 [keepstation:NeedComms] Running Loop=1
2025-12-18T00:04:30.619Z,1766016270.619 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-18T00:04:30.619Z,1766016270.619 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-18T00:04:30.619Z,1766016270.619 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-18T00:04:30.620Z,1766016270.620 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-18T00:04:30.620Z,1766016270.620 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-18T00:04:30.621Z,1766016270.621 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-18T00:04:30.621Z,1766016270.621 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-18T00:04:30.621Z,1766016270.621 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-18T00:04:30.622Z,1766016270.622 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-18T00:04:30.622Z,1766016270.622 [keepstation:NeedComms:A] Running Loop=1
2025-12-18T00:04:30.623Z,1766016270.623 [keepstation:NeedComms:A](INFO): last time_fix was: 1766015669.000000 second since 1970/01/01T00:00:00Z
2025-12-18T00:04:30.623Z,1766016270.623 [keepstation:NeedComms:A] Stopped
2025-12-18T00:04:30.623Z,1766016270.623 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-18T00:04:30.624Z,1766016270.624 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-18T00:04:30.624Z,1766016270.624 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-18T00:04:30.901Z,1766016270.901 [keepstation:KeepStation:Dive] Stopped
2025-12-18T00:04:30.901Z,1766016270.901 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-12-18T00:04:30.901Z,1766016270.901 [keepstation:KeepStation:Dive:A] Stopped
2025-12-18T00:04:30.901Z,1766016270.901 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-12-18T00:04:30.902Z,1766016270.902 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-18T00:04:30.902Z,1766016270.902 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-18T00:04:30.902Z,1766016270.902 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-18T00:04:30.902Z,1766016270.902 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-18T00:04:30.902Z,1766016270.902 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-18T00:04:30.902Z,1766016270.902 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-18T00:04:30.925Z,1766016270.925 [keepstation:NeedComms:setActive:A] Stopped
2025-12-18T00:04:30.925Z,1766016270.925 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-18T00:04:31.307Z,1766016271.307 [keepstation:NeedComms:setActive:B] Stopped
2025-12-18T00:04:31.307Z,1766016271.307 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-18T00:04:31.307Z,1766016271.307 [keepstation:NeedComms:setActive] Stopped
2025-12-18T00:04:31.307Z,1766016271.307 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-18T00:04:58.004Z,1766016298.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:04:59.010Z,1766016299.010 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:05:29.129Z,1766016329.129 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:05:30.132Z,1766016330.132 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:06:00.264Z,1766016360.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:06:01.264Z,1766016361.264 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:06:18.283Z,1766016378.283 [keepstation:NeedComms:D] Running Loop=1
2025-12-18T00:06:18.692Z,1766016378.692 [Radio_Surface](INFO): Powering up
2025-12-18T00:06:19.018Z,1766016379.018 [NAL9602](INFO): Powering up
2025-12-18T00:06:23.944Z,1766016383.944 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11
2025-12-18T00:06:23.945Z,1766016383.945 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0
2025-12-18T00:06:29.977Z,1766016389.977 [NAL9602](INFO): NAL9602 initialized
2025-12-18T00:06:31.384Z,1766016391.384 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:06:32.388Z,1766016392.388 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-18T00:06:32.388Z,1766016392.388 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:07:02.508Z,1766016422.508 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:07:03.512Z,1766016423.512 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:07:33.632Z,1766016453.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:07:34.637Z,1766016454.637 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:08:04.773Z,1766016484.773 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:08:05.776Z,1766016485.776 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:08:27.955Z,1766016507.955 [NAL9602](INFO): SBD MO Status=2, MOMSN=6024, MT Status=2, MTMSN=0
2025-12-18T00:08:27.955Z,1766016507.955 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-18T00:08:35.904Z,1766016515.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-18T00:08:36.908Z,1766016516.908 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:09:04.722Z,1766016544.722 [DataOverHttps](IMPORTANT): SBD MTMSN=20251218T000903
2025-12-18T00:09:04.725Z,1766016544.725 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003062
2025-12-18T00:09:04.730Z,1766016544.730 [NAL9602](INFO): SBD MO Status=0, MOMSN=6024, MT Status=0, MTMSN=0
2025-12-18T00:09:04.731Z,1766016544.731 [NAL9602](INFO): No messages in MT queue
2025-12-18T00:09:05.927Z,1766016545.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000905.00,A,3647.35238,N,12153.83218,W,0.952,125.89,181225,,,A*79
2025-12-18T00:09:05.929Z,1766016545.929 [NAL9602](INFO): GPS fix at 20251218T000905: (36.789206, -121.897203)
2025-12-18T00:09:05.942Z,1766016545.942 [keepstation:NeedComms:D] Stopped
2025-12-18T00:09:05.943Z,1766016545.943 [keepstation:NeedComms:E] Running Loop=1
2025-12-18T00:09:12.279Z,1766016552.279 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20251217T224216/Courier0028.lzma
2025-12-18T00:09:12.282Z,1766016552.282 [DataOverHttps](INFO): Received command: stop
2025-12-18T00:09:12.325Z,1766016552.325 [CommandExec](IMPORTANT): got command stop
2025-12-18T00:09:12.325Z,1766016552.325 [CommandExec](IMPORTANT): Scheduling is paused
2025-12-18T00:09:12.325Z,1766016552.325 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-12-18T00:09:12.414Z,1766016552.414 [MissionManager](INFO): MissionManager is completed.
2025-12-18T00:09:12.414Z,1766016552.414 [MissionManager](INFO): Uninitializing Mission keepstation
2025-12-18T00:09:12.414Z,1766016552.414 [keepstation] Stopped
2025-12-18T00:09:12.414Z,1766016552.414 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-12-18T00:09:12.414Z,1766016552.414 [keepstation:NeedComms] Stopped
2025-12-18T00:09:12.414Z,1766016552.414 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-18T00:09:12.414Z,1766016552.414 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-18T00:09:12.414Z,1766016552.414 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:NeedComms:E] Stopped
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:StandardEnvelopes] Stopped
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:BackseatDriver] Stopped
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-12-18T00:09:12.415Z,1766016552.415 [keepstation:PowerOnly] Stopped
2025-12-18T00:09:12.416Z,1766016552.416 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-12-18T00:09:12.416Z,1766016552.416 [keepstation:PowerOnly:E.Wait] Stopped
2025-12-18T00:09:12.416Z,1766016552.416 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-12-18T00:09:12.416Z,1766016552.416 [keepstation:H.Pitch] Stopped
2025-12-18T00:09:12.416Z,1766016552.416 [keepstation:SurfaceComms] Stopped
2025-12-18T00:09:12.416Z,1766016552.416 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-18T00:09:12.416Z,1766016552.416 [keepstation:SurfaceComms:B] Stopped
2025-12-18T00:09:12.416Z,1766016552.416 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-18T00:09:12.416Z,1766016552.416 [keepstation:DiveCmd] Stopped
2025-12-18T00:09:12.417Z,1766016552.417 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd
2025-12-18T00:09:12.417Z,1766016552.417 [keepstation:KeepStation] Stopped
2025-12-18T00:09:12.417Z,1766016552.417 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-12-18T00:09:12.417Z,1766016552.417 [keepstation:KeepStation:B.Wait] Stopped
2025-12-18T00:09:12.417Z,1766016552.417 [keepstation:KeepStation:B.Wait](DEBUG): Uninitialize Wait Component.
2025-12-18T00:09:12.817Z,1766016552.817 [MissionManager](IMPORTANT): Started mission Default
2025-12-18T00:09:12.817Z,1766016552.817 [Default] Running Loop=1
2025-12-18T00:09:12.817Z,1766016552.817 [Default](DEBUG): Aggregate::initialize Default
2025-12-18T00:09:12.817Z,1766016552.817 [Default:B.GoToSurface] Running Loop=1
2025-12-18T00:09:12.817Z,1766016552.817 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-18T00:09:12.817Z,1766016552.817 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-12-18T00:09:12.818Z,1766016552.818 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-12-18T00:09:12.818Z,1766016552.818 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-12-18T00:09:12.818Z,1766016552.818 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-12-18T00:09:12.818Z,1766016552.818 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-18T00:09:12.819Z,1766016552.819 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-18T00:09:12.819Z,1766016552.819 [Default:A.Wait] Running Loop=1
2025-12-18T00:09:12.819Z,1766016552.819 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-12-18T00:09:13.282Z,1766016553.282 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Courier0028.lzma.bak
2025-12-18T00:09:13.283Z,1766016553.283 [DataOverHttps](INFO): SBD MOMSN=26517976
2025-12-18T00:09:23.808Z,1766016563.808 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:09:26.184Z,1766016566.184 [Default:A.Wait](INFO): Done Waiting.
2025-12-18T00:09:26.184Z,1766016566.184 [Default:A.Wait] Stopped
2025-12-18T00:09:26.184Z,1766016566.184 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-12-18T00:09:26.569Z,1766016566.569 [Default:CheckIn] Running Loop=1
2025-12-18T00:09:26.569Z,1766016566.569 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-18T00:09:26.569Z,1766016566.569 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-18T00:09:28.571Z,1766016568.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000927.00,A,3647.34880,N,12153.82757,W,0.253,156.96,181225,,,A*7E
2025-12-18T00:09:28.573Z,1766016568.573 [NAL9602](INFO): GPS fix at 20251218T000927: (36.789147, -121.897126)
2025-12-18T00:09:28.597Z,1766016568.597 [Default:CheckIn:Read_GPS] Stopped
2025-12-18T00:09:28.597Z,1766016568.597 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-18T00:09:30.484Z,1766016570.484 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20251217T224216/Courier0031.lzma
2025-12-18T00:09:30.746Z,1766016570.746 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Courier0031.lzma.bak
2025-12-18T00:09:30.746Z,1766016570.746 [DataOverHttps](INFO): SBD MOMSN=26517980
2025-12-18T00:09:47.180Z,1766016587.180 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20251217T224216/Express0029.lzma
2025-12-18T00:09:48.182Z,1766016588.182 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Express0029.lzma.bak
2025-12-18T00:09:48.182Z,1766016588.182 [DataOverHttps](INFO): SBD MOMSN=26517984
2025-12-18T00:10:00.197Z,1766016600.197 [NAL9602](INFO): Powering down
2025-12-18T00:10:08.011Z,1766016608.011 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20251217T224216/Express0032.lzma
2025-12-18T00:10:09.014Z,1766016609.014 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Express0032.lzma.bak
2025-12-18T00:10:09.014Z,1766016609.014 [DataOverHttps](INFO): SBD MOMSN=26517989
2025-12-18T00:10:25.219Z,1766016625.219 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20251217T224216/Express0035.lzma
2025-12-18T00:10:26.222Z,1766016626.222 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Express0035.lzma.bak
2025-12-18T00:10:26.222Z,1766016626.222 [DataOverHttps](INFO): SBD MOMSN=26517994
2025-12-18T00:10:28.111Z,1766016628.111 [Default:CheckIn:Read_Iridium] Stopped
2025-12-18T00:10:28.111Z,1766016628.111 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-18T00:10:28.111Z,1766016628.111 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-18T00:10:36.116Z,1766016636.116 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:10:44.952Z,1766016644.952 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:10:53.612Z,1766016653.612 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:11:02.288Z,1766016662.288 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:11:02.617Z,1766016662.617 [DAT](INFO): DAT read: Rx Time:00:11:01.4593
2025-12-18T00:11:02.617Z,1766016662.617 [DAT](INFO): Rx dataTimestamp_ set to:1766016662.617150
2025-12-18T00:11:02.618Z,1766016662.618 [DAT](INFO): DAT read:
2025-12-18T00:11:02.618Z,1766016662.618 [DAT](INFO): DAT read: $Packet for address 0
2025-12-18T00:11:02.619Z,1766016662.619 [DAT](INFO): received a packet notification
2025-12-18T00:11:11.040Z,1766016671.040 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:11:19.736Z,1766016679.736 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:11:28.292Z,1766016688.292 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:11:37.088Z,1766016697.088 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:11:45.749Z,1766016705.749 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:11:54.276Z,1766016714.276 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:12:03.068Z,1766016723.068 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:12:11.776Z,1766016731.776 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:12:20.440Z,1766016740.440 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:12:29.212Z,1766016749.212 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:12:37.862Z,1766016757.862 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:12:46.610Z,1766016766.610 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:12:55.288Z,1766016775.288 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:13:03.952Z,1766016783.952 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:13:12.536Z,1766016792.536 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:13:21.224Z,1766016801.224 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:13:29.924Z,1766016809.924 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:13:38.490Z,1766016818.490 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:13:47.204Z,1766016827.204 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:13:55.824Z,1766016835.824 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:14:04.372Z,1766016844.372 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:14:13.056Z,1766016853.056 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:14:21.652Z,1766016861.652 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:14:30.316Z,1766016870.316 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:14:39.076Z,1766016879.076 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:14:47.664Z,1766016887.664 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:14:56.324Z,1766016896.324 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:15:04.929Z,1766016904.929 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:15:13.612Z,1766016913.612 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:15:22.260Z,1766016922.260 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:15:28.708Z,1766016928.708 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-12-18T00:15:28.709Z,1766016928.709 [Default:CheckIn:C.Wait] Stopped
2025-12-18T00:15:28.709Z,1766016928.709 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-12-18T00:15:28.709Z,1766016928.709 [Default:CheckIn:D] Running Loop=1
2025-12-18T00:15:29.097Z,1766016929.097 [Default:CheckIn:D] Stopped
2025-12-18T00:15:29.097Z,1766016929.097 [Default:CheckIn:E] Running Loop=1
2025-12-18T00:15:29.486Z,1766016929.486 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.271329 min
2025-12-18T00:15:29.487Z,1766016929.487 [Default:CheckIn:E] Stopped
2025-12-18T00:15:29.487Z,1766016929.487 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-12-18T00:15:29.487Z,1766016929.487 [Default:CheckIn] Stopped
2025-12-18T00:15:29.487Z,1766016929.487 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-18T00:15:29.487Z,1766016929.487 [Default:CheckIn](INFO): Running loop #2
2025-12-18T00:15:29.487Z,1766016929.487 [Default:CheckIn] Running Loop=2
2025-12-18T00:15:29.487Z,1766016929.487 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-18T00:15:29.487Z,1766016929.487 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-18T00:15:30.285Z,1766016930.285 [NAL9602](INFO): Powering up
2025-12-18T00:15:30.708Z,1766016930.708 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:15:39.432Z,1766016939.432 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:15:48.100Z,1766016948.100 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:15:49.265Z,1766016949.265 [NAL9602](INFO): NAL9602 initialized
2025-12-18T00:15:50.511Z,1766016950.511 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001549.00,A,3647.28163,N,12153.75107,W,0.544,158.76,181225,,,A*78
2025-12-18T00:15:50.513Z,1766016950.513 [NAL9602](INFO): GPS fix at 20251218T001549: (36.788027, -121.895851)
2025-12-18T00:15:50.581Z,1766016950.581 [Default:CheckIn:Read_GPS] Stopped
2025-12-18T00:15:50.581Z,1766016950.581 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-12-18T00:15:54.406Z,1766016954.406 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20251217T224216/Courier0037.lzma
2025-12-18T00:15:55.138Z,1766016955.138 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Courier0037.lzma.bak
2025-12-18T00:15:55.138Z,1766016955.138 [DataOverHttps](INFO): SBD MOMSN=26518001
2025-12-18T00:16:12.020Z,1766016972.020 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20251217T224216/Express0038.lzma
2025-12-18T00:16:13.022Z,1766016973.022 [DataOverHttps](INFO): Moved sent file to Logs/20251217T224216/Express0038.lzma.bak
2025-12-18T00:16:13.022Z,1766016973.022 [DataOverHttps](INFO): SBD MOMSN=26518037
2025-12-18T00:16:14.386Z,1766016974.386 [NAL9602](INFO): SBD MO Status=1, MOMSN=6027, MT Status=0, MTMSN=0
2025-12-18T00:16:14.387Z,1766016974.387 [NAL9602](INFO): No messages in MT queue
2025-12-18T00:16:14.820Z,1766016974.820 [Default:CheckIn:Read_Iridium] Stopped
2025-12-18T00:16:14.820Z,1766016974.820 [Default:CheckIn:C.Wait] Running Loop=1
2025-12-18T00:16:14.820Z,1766016974.820 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-12-18T00:16:22.856Z,1766016982.856 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:16:31.624Z,1766016991.624 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:16:45.097Z,1766017005.097 [NAL9602](INFO): Powering down
2025-12-18T00:16:45.684Z,1766017005.684 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:16:54.344Z,1766017014.344 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:17:02.738Z,1766017022.738 [DAT](INFO): DAT read: 00:17:01.6979 LVL= 17376, 15505, 15586, 29939, AGC= 84, IDX= 351,-0.10, 0.360, 0.117,-0.928, 0.583, PHS=-0.121,-0.421,-1.555, RAW= 41.4, 29.5, CAL= 38.4, 35.2, ROT= 111.6, -35.2
2025-12-18T00:17:02.739Z,1766017022.739 [DAT](INFO): got valid direction response:
00:17:01.6979 LVL= 17376, 15505, 15586, 29939, AGC= 84, IDX= 351,-0.10, 0.360, 0.117,-0.928, 0.583, PHS=-0.121,-0.421,-1.555, RAW= 41.4, 29.5, CAL= 38.4, 35.2, ROT= 111.6, -35.2
2025-12-18T00:17:02.739Z,1766017022.739 [DAT](INFO): DAT read: Rx Time:00:17:01.6979
2025-12-18T00:17:02.740Z,1766017022.740 [DAT](INFO): Rx dataTimestamp_ set to:1766017022.739698
2025-12-18T00:17:02.741Z,1766017022.741 [DAT](INFO): DAT read: $Low SNR acquisition
2025-12-18T00:17:02.741Z,1766017022.741 [DAT](INFO): Received low SNR in chirp
2025-12-18T00:17:02.757Z,1766017022.757 [DAT](INFO): #Rx 2: Read direction message, but no range.
2025-12-18T00:17:02.759Z,1766017022.759 [DAT](INFO): direction in FSK: [-0.300811,0.759762,0.576432]
2025-12-18T00:17:04.220Z,1766017024.220 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:17:12.936Z,1766017032.936 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:17:21.640Z,1766017041.640 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:17:30.188Z,1766017050.188 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:17:38.948Z,1766017058.948 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:17:47.536Z,1766017067.536 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:17:56.216Z,1766017076.216 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:18:05.544Z,1766017085.544 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:18:14.200Z,1766017094.200 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:18:22.817Z,1766017102.817 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:18:32.248Z,1766017112.248 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:18:40.860Z,1766017120.860 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:18:49.460Z,1766017129.460 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:18:58.128Z,1766017138.128 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:19:07.917Z,1766017147.917 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:19:15.505Z,1766017155.505 [DataOverHttps](IMPORTANT): SBD MTMSN=20251218T001914
2025-12-18T00:19:23.476Z,1766017163.476 [DataOverHttps](INFO): Received command: schedule resume
2025-12-18T00:19:23.522Z,1766017163.522 [CommandExec](IMPORTANT): got command schedule resume
2025-12-18T00:19:23.522Z,1766017163.522 [CommandExec](IMPORTANT): Scheduling is resumed
2025-12-18T00:19:25.480Z,1766017165.480 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:19:34.084Z,1766017174.084 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:19:42.780Z,1766017182.780 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:19:46.221Z,1766017186.221 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-12-18T00:19:51.508Z,1766017191.508 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:19:59.908Z,1766017199.908 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:20:11.448Z,1766017211.448 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:20:20.168Z,1766017220.168 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:20:29.084Z,1766017229.084 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:20:29.088Z,1766017229.088 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-12-18T00:20:29.089Z,1766017229.089 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-18T00:20:29.089Z,1766017229.089 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-12-18T00:20:29.090Z,1766017229.090 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-12-18T00:20:29.091Z,1766017229.091 [BPC1](ERROR): Battery stick #28 (s/n: 0151) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-18T00:20:29.091Z,1766017229.091 [BPC1](ERROR): Battery stick #44 (s/n: 00E4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-18T00:20:29.092Z,1766017229.092 [BPC1](ERROR): Battery stick #47 (s/n: 00EC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-18T00:20:29.093Z,1766017229.093 [BPC1](ERROR): Battery stick #54 (s/n: 009C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-18T00:20:29.094Z,1766017229.094 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-12-18T00:20:29.094Z,1766017229.094 [BPC1](ERROR): Battery stick #62 (s/n: 0126) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-12-18T00:20:29.095Z,1766017229.095 [BPC1](INFO): Calculating totals. Valid battery stick count: 27. Valid reserve battery stick count: 1.
2025-12-18T00:20:29.097Z,1766017229.097 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-12-18T00:20:36.869Z,1766017236.869 [DataOverHttps](IMPORTANT): SBD MTMSN=20251218T002035
2025-12-18T00:20:44.524Z,1766017244.524 [DataOverHttps](INFO): Received command: configSet ESPComponent.sampleTimeout 5 minute persist
2025-12-18T00:20:44.971Z,1766017244.971 [CommandExec](IMPORTANT): got command configSet ESPComponent.sampleTimeout 5 minute persist
2025-12-18T00:20:46.528Z,1766017246.528 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:20:55.025Z,1766017255.025 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-12-18T00:21:03.761Z,1766017263.761 [DataOverHttps](IMPORTANT): SBD MTMSN=20251218T002102
2025-12-18T00:21:11.411Z,1766017271.411 [DataOverHttps](INFO): Received command: restart logs