2025-07-18T05:23:52.686Z,1752816232.686 [Supervisor](DEBUG): Initializing supervisor.
2025-07-18T05:23:52.690Z,1752816232.690 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-07-18T05:23:52.691Z,1752816232.691 [SyncHandler](INFO): Protected caller Thread ID is 5327
2025-07-18T05:23:52.691Z,1752816232.691 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-07-18T05:23:52.692Z,1752816232.692 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-07-18T05:23:52.693Z,1752816232.693 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5328
2025-07-18T05:23:52.697Z,1752816232.697 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-07-18T05:23:52.717Z,1752816232.717 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-07-18T05:23:52.718Z,1752816232.718 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-07-18T05:23:52.719Z,1752816232.719 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5329
2025-07-18T05:23:52.723Z,1752816232.723 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-07-18T05:23:52.724Z,1752816232.724 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-07-18T05:23:52.724Z,1752816232.724 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5330
2025-07-18T05:23:52.727Z,1752816232.727 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-07-18T05:23:52.728Z,1752816232.728 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-07-18T05:23:52.728Z,1752816232.728 [logger ThreadHandler](INFO): Protected caller Thread ID is 5331
2025-07-18T05:23:52.732Z,1752816232.732 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-07-18T05:23:52.732Z,1752816232.732 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-07-18T05:23:52.734Z,1752816232.734 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-07-18T05:23:53.077Z,1752816233.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-07-18T05:23:53.077Z,1752816233.077 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-07-18T05:23:53.913Z,1752816233.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-07-18T05:23:53.913Z,1752816233.913 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-07-18T05:23:54.101Z,1752816234.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-07-18T05:23:54.101Z,1752816234.101 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-07-18T05:23:54.667Z,1752816234.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-07-18T05:23:54.668Z,1752816234.668 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-07-18T05:23:54.774Z,1752816234.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-07-18T05:23:54.774Z,1752816234.774 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-07-18T05:23:54.874Z,1752816234.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-07-18T05:23:54.875Z,1752816234.875 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-07-18T05:23:55.297Z,1752816235.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-07-18T05:23:55.298Z,1752816235.298 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-07-18T05:23:55.526Z,1752816235.526 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-07-18T05:23:55.527Z,1752816235.527 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-07-18T05:23:55.674Z,1752816235.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-07-18T05:23:55.675Z,1752816235.675 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-07-18T05:23:55.931Z,1752816235.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-07-18T05:23:55.932Z,1752816235.932 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-07-18T05:23:56.499Z,1752816236.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-07-18T05:23:56.500Z,1752816236.500 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-07-18T05:23:56.896Z,1752816236.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-07-18T05:23:56.897Z,1752816236.897 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-07-18T05:23:56.986Z,1752816236.986 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-07-18T05:23:57.178Z,1752816237.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-07-18T05:23:57.178Z,1752816237.178 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-07-18T05:23:57.437Z,1752816237.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-07-18T05:23:57.438Z,1752816237.438 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-07-18T05:23:57.750Z,1752816237.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-07-18T05:23:57.752Z,1752816237.752 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2025-07-18T05:23:57.753Z,1752816237.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2025-07-18T05:23:57.862Z,1752816237.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2025-07-18T05:23:58.023Z,1752816238.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2025-07-18T05:23:58.119Z,1752816238.119 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2025-07-18T05:23:58.212Z,1752816238.212 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2025-07-18T05:23:58.334Z,1752816238.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg
2025-07-18T05:23:58.429Z,1752816238.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2025-07-18T05:23:58.544Z,1752816238.544 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2025-07-18T05:23:58.825Z,1752816238.825 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-07-18T05:23:58.825Z,1752816238.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2025-07-18T05:23:58.997Z,1752816238.997 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2025-07-18T05:23:59.238Z,1752816239.238 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2025-07-18T05:23:59.439Z,1752816239.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2025-07-18T05:23:59.850Z,1752816239.850 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/
2025-07-18T05:23:59.851Z,1752816239.851 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-07-18T05:23:59.857Z,1752816239.857 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-07-18T05:23:59.998Z,1752816239.998 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-07-18T05:23:59.998Z,1752816239.998 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-07-18T05:24:00.008Z,1752816240.008 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-07-18T05:24:00.009Z,1752816240.009 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-07-18T05:24:00.061Z,1752816240.061 [DepthRateCalculator] Loaded
2025-07-18T05:24:00.062Z,1752816240.062 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-07-18T05:24:00.067Z,1752816240.067 [PitchRateCalculator] Loaded
2025-07-18T05:24:00.068Z,1752816240.068 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-07-18T05:24:00.078Z,1752816240.078 [SpeedCalculator] Loaded
2025-07-18T05:24:00.078Z,1752816240.078 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-07-18T05:24:00.084Z,1752816240.084 [YawRateCalculator] Loaded
2025-07-18T05:24:00.084Z,1752816240.084 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-07-18T05:24:00.106Z,1752816240.106 [ElevatorOffsetCalculator] Loaded
2025-07-18T05:24:00.106Z,1752816240.106 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-07-18T05:24:00.107Z,1752816240.107 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-07-18T05:24:00.107Z,1752816240.107 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-07-18T05:24:00.164Z,1752816240.164 [VerticalControl](DEBUG): Construct VerticalControl.
2025-07-18T05:24:00.223Z,1752816240.223 [VerticalControl] Loaded
2025-07-18T05:24:00.223Z,1752816240.223 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-07-18T05:24:00.226Z,1752816240.226 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-07-18T05:24:00.274Z,1752816240.274 [HorizontalControl] Loaded
2025-07-18T05:24:00.274Z,1752816240.274 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-07-18T05:24:00.277Z,1752816240.277 [SpeedControl](DEBUG): Construct SpeedControl.
2025-07-18T05:24:00.280Z,1752816240.280 [SpeedControl] Loaded
2025-07-18T05:24:00.280Z,1752816240.280 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-07-18T05:24:00.283Z,1752816240.283 [LoopControl](DEBUG): Construct LoopControl.
2025-07-18T05:24:00.283Z,1752816240.283 [LoopControl] Loaded
2025-07-18T05:24:00.283Z,1752816240.283 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-07-18T05:24:00.284Z,1752816240.284 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-07-18T05:24:00.284Z,1752816240.284 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-07-18T05:24:00.402Z,1752816240.402 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-07-18T05:24:00.402Z,1752816240.402 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-07-18T05:24:00.416Z,1752816240.416 [NavChart] Loaded
2025-07-18T05:24:00.416Z,1752816240.416 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-07-18T05:24:00.422Z,1752816240.422 [UniversalFixResidualReporter] Loaded
2025-07-18T05:24:00.422Z,1752816240.422 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-07-18T05:24:00.423Z,1752816240.423 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-07-18T05:24:00.423Z,1752816240.423 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-07-18T05:24:00.606Z,1752816240.606 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-07-18T05:24:00.607Z,1752816240.607 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-07-18T05:24:01.125Z,1752816241.125 [AHRS_M2] Loaded
2025-07-18T05:24:01.125Z,1752816241.125 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-07-18T05:24:01.165Z,1752816241.165 [BackseatComponent] Loaded
2025-07-18T05:24:01.165Z,1752816241.165 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-07-18T05:24:01.166Z,1752816241.166 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2025-07-18T05:24:01.166Z,1752816241.166 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5416
2025-07-18T05:24:01.169Z,1752816241.169 [LcmUniversalReporter] Loaded
2025-07-18T05:24:01.169Z,1752816241.169 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-07-18T05:24:02.023Z,1752816242.023 [BPC1] Loaded
2025-07-18T05:24:02.023Z,1752816242.023 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-07-18T05:24:02.124Z,1752816242.124 [DAT] Loaded
2025-07-18T05:24:02.125Z,1752816242.125 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-07-18T05:24:02.126Z,1752816242.126 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2025-07-18T05:24:02.126Z,1752816242.126 [DAT ThreadHandler](INFO): Protected caller Thread ID is 5417
2025-07-18T05:24:02.204Z,1752816242.204 [DataOverHttps] Loaded
2025-07-18T05:24:02.204Z,1752816242.204 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-07-18T05:24:02.205Z,1752816242.205 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4099E4E0
2025-07-18T05:24:02.205Z,1752816242.205 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5418
2025-07-18T05:24:02.229Z,1752816242.229 [Depth_Keller] Loaded
2025-07-18T05:24:02.229Z,1752816242.229 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-07-18T05:24:02.295Z,1752816242.295 [NAL9602] Loaded
2025-07-18T05:24:02.295Z,1752816242.295 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-07-18T05:24:02.326Z,1752816242.326 [Onboard] Loaded
2025-07-18T05:24:02.326Z,1752816242.326 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-07-18T05:24:02.327Z,1752816242.327 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409CE4E0
2025-07-18T05:24:02.327Z,1752816242.327 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5419
2025-07-18T05:24:02.342Z,1752816242.342 [Power24vConverter] Loaded
2025-07-18T05:24:02.342Z,1752816242.342 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-07-18T05:24:02.358Z,1752816242.358 [Radio_Surface] Loaded
2025-07-18T05:24:02.358Z,1752816242.358 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-07-18T05:24:02.359Z,1752816242.359 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409FE4E0
2025-07-18T05:24:02.360Z,1752816242.360 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5420
2025-07-18T05:24:02.407Z,1752816242.407 [RDI_Pathfinder] Loaded
2025-07-18T05:24:02.407Z,1752816242.407 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-07-18T05:24:02.423Z,1752816242.423 [Sonardyne_Nano] Loaded
2025-07-18T05:24:02.423Z,1752816242.423 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-07-18T05:24:02.424Z,1752816242.424 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-07-18T05:24:02.424Z,1752816242.424 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-07-18T05:24:02.567Z,1752816242.567 [BuoyancyServo] Loaded
2025-07-18T05:24:02.567Z,1752816242.567 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-07-18T05:24:02.591Z,1752816242.591 [ElevatorServo] Loaded
2025-07-18T05:24:02.591Z,1752816242.591 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-07-18T05:24:02.622Z,1752816242.622 [DockingStepper] Loaded
2025-07-18T05:24:02.623Z,1752816242.623 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2025-07-18T05:24:02.646Z,1752816242.646 [MassServo] Loaded
2025-07-18T05:24:02.646Z,1752816242.646 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-07-18T05:24:02.667Z,1752816242.667 [RudderServo] Loaded
2025-07-18T05:24:02.668Z,1752816242.668 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-07-18T05:24:02.685Z,1752816242.685 [ThrusterHE] Loaded
2025-07-18T05:24:02.685Z,1752816242.685 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-07-18T05:24:02.685Z,1752816242.685 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-07-18T05:24:02.686Z,1752816242.686 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-07-18T05:24:02.747Z,1752816242.747 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-07-18T05:24:02.748Z,1752816242.748 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-07-18T05:24:02.777Z,1752816242.777 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-07-18T05:24:02.778Z,1752816242.778 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-07-18T05:24:03.295Z,1752816243.295 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-07-18T05:24:03.295Z,1752816243.295 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-07-18T05:24:03.887Z,1752816243.887 [CTD_Seabird] Loaded
2025-07-18T05:24:03.888Z,1752816243.888 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-07-18T05:24:03.889Z,1752816243.889 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BBE4E0
2025-07-18T05:24:03.889Z,1752816243.889 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5421
2025-07-18T05:24:03.926Z,1752816243.926 [ESPComponent] Loaded
2025-07-18T05:24:03.927Z,1752816243.927 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-07-18T05:24:03.949Z,1752816243.949 [PAR_Licor] Loaded
2025-07-18T05:24:03.950Z,1752816243.950 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-07-18T05:24:03.997Z,1752816243.997 [WetLabsBB2FL] Loaded
2025-07-18T05:24:03.997Z,1752816243.997 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-07-18T05:24:03.998Z,1752816243.998 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BEE4E0
2025-07-18T05:24:03.998Z,1752816243.998 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5422
2025-07-18T05:24:03.999Z,1752816243.999 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-07-18T05:24:03.000Z,1752816244.000 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-07-18T05:24:04.223Z,1752816244.223 [SBIT](DEBUG): Construct Startup Built In Test.
2025-07-18T05:24:04.233Z,1752816244.233 [SBIT] Loaded
2025-07-18T05:24:04.233Z,1752816244.233 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-07-18T05:24:04.236Z,1752816244.236 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-07-18T05:24:04.249Z,1752816244.249 [IBIT] Loaded
2025-07-18T05:24:04.249Z,1752816244.249 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-07-18T05:24:04.255Z,1752816244.255 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-07-18T05:24:04.339Z,1752816244.339 [CBIT] Loaded
2025-07-18T05:24:04.339Z,1752816244.339 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-07-18T05:24:04.372Z,1752816244.372 [GFScanner] Loaded
2025-07-18T05:24:04.373Z,1752816244.373 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-07-18T05:24:04.373Z,1752816244.373 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-07-18T05:24:04.380Z,1752816244.380 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-07-18T05:24:04.383Z,1752816244.383 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-07-18T05:24:04.394Z,1752816244.394 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-07-18T05:24:04.396Z,1752816244.395 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0
2025-07-18T05:24:04.396Z,1752816244.396 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5423
2025-07-18T05:24:04.400Z,1752816244.400 [Supervisor](INFO): Main Thread ID is 825
2025-07-18T05:24:04.400Z,1752816244.400 [Supervisor](DEBUG): Running supervisor.
2025-07-18T05:24:04.401Z,1752816244.401 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5424
2025-07-18T05:24:04.401Z,1752816244.401 [CommandExec](INFO): Initializing the command executive.
2025-07-18T05:24:04.403Z,1752816244.403 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5425
2025-07-18T05:24:04.406Z,1752816244.406 [controlThread ThreadHandler](INFO): Handler Thread ID is 5426
2025-07-18T05:24:04.407Z,1752816244.407 [controlThread](DEBUG): Initializing ControlThread
2025-07-18T05:24:04.407Z,1752816244.407 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-07-18T05:24:04.408Z,1752816244.408 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-07-18T05:24:04.408Z,1752816244.408 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-07-18T05:24:04.408Z,1752816244.408 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-07-18T05:24:04.409Z,1752816244.409 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-07-18T05:24:04.409Z,1752816244.409 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-07-18T05:24:04.411Z,1752816244.411 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-07-18T05:24:04.412Z,1752816244.412 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-07-18T05:24:04.412Z,1752816244.412 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-07-18T05:24:04.413Z,1752816244.413 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-07-18T05:24:04.413Z,1752816244.413 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-07-18T05:24:04.418Z,1752816244.418 [SBIT](INFO): Initialize SBIT Component.
2025-07-18T05:24:04.419Z,1752816244.419 [SBIT](IMPORTANT): git: 2025-07-16
2025-07-18T05:24:04.419Z,1752816244.419 [SBIT](INFO): git hash: d69d2715ddd00fa751675e8858faf22d094d5d81
2025-07-18T05:24:04.419Z,1752816244.419 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-07-18T05:24:04.420Z,1752816244.420 [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-07-18T05:24:04.421Z,1752816244.421 [SBIT](INFO): Beginning SBIT in 93.000000 seconds.
2025-07-18T05:24:04.422Z,1752816244.422 [IBIT](INFO): Initialize IBIT Component.
2025-07-18T05:24:04.423Z,1752816244.423 [CBIT](DEBUG): Initialize CBIT Component.
2025-07-18T05:24:04.424Z,1752816244.424 [logger ThreadHandler](INFO): Handler Thread ID is 5427
2025-07-18T05:24:04.434Z,1752816244.434 [CBIT](DEBUG): Initialized mux pins.
2025-07-18T05:24:04.434Z,1752816244.434 [CBIT](DEBUG): Initializing the watchdog timer.
2025-07-18T05:24:04.442Z,1752816244.442 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5428
2025-07-18T05:24:04.454Z,1752816244.454 [DAT ThreadHandler](INFO): Handler Thread ID is 5429
2025-07-18T05:24:04.455Z,1752816244.455 [DAT](INFO): Powering up
2025-07-18T05:24:04.455Z,1752816244.455 [DAT](DEBUG): Initializing DAT.
2025-07-18T05:24:04.458Z,1752816244.458 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-07-18T05:24:04.458Z,1752816244.458 [CBIT](DEBUG): Initializing heartbeat.
2025-07-18T05:24:04.460Z,1752816244.460 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5431
2025-07-18T05:24:04.462Z,1752816244.462 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-07-18T05:24:04.471Z,1752816244.471 [Onboard ThreadHandler](INFO): Handler Thread ID is 5432
2025-07-18T05:24:04.488Z,1752816244.488 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5433
2025-07-18T05:24:04.507Z,1752816244.507 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5434
2025-07-18T05:24:04.508Z,1752816244.508 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-07-18T05:24:04.511Z,1752816244.511 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5436
2025-07-18T05:24:04.514Z,1752816244.514 [WetLabsBB2FL](INFO): Powering up
2025-07-18T05:24:04.515Z,1752816244.515 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5438
2025-07-18T05:24:04.518Z,1752816244.518 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-07-18T05:24:04.518Z,1752816244.518 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-07-18T05:24:04.518Z,1752816244.518 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-07-18T05:24:04.518Z,1752816244.518 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-07-18T05:24:04.519Z,1752816244.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-07-18T05:24:04.519Z,1752816244.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-07-18T05:24:04.519Z,1752816244.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-07-18T05:24:04.519Z,1752816244.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-07-18T05:24:04.530Z,1752816244.530 [CBIT](DEBUG): Deactivating emergency mode.
2025-07-18T05:24:04.570Z,1752816244.570 [CBIT](DEBUG): Backplane powered.
2025-07-18T05:24:04.570Z,1752816244.570 [GFScanner](DEBUG): Initializing GFScanner
2025-07-18T05:24:04.570Z,1752816244.570 [GFScanner](DEBUG): Deactivating GF circuits.
2025-07-18T05:24:04.575Z,1752816244.575 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-07-18T05:24:04.576Z,1752816244.576 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-07-18T05:24:04.576Z,1752816244.576 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-07-18T05:24:04.577Z,1752816244.577 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-07-18T05:24:04.630Z,1752816244.630 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-07-18T05:24:04.643Z,1752816244.643 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-18T05:24:04.695Z,1752816244.695 [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-07-18T05:24:04.695Z,1752816244.695 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-07-18T05:24:04.696Z,1752816244.696 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-07-18T05:24:04.697Z,1752816244.697 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-07-18T05:24:04.894Z,1752816244.894 [Radio_Surface](INFO): Powering up
2025-07-18T05:24:05.037Z,1752816245.037 [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-07-18T05:24:05.047Z,1752816245.047 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-07-18T05:24:05.049Z,1752816245.049 [Default:A.Wait](DEBUG): Construct Wait.
2025-07-18T05:24:05.071Z,1752816245.071 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-18T05:24:05.093Z,1752816245.093 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-07-18T05:24:05.124Z,1752816245.124 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-07-18T05:24:05.142Z,1752816245.142 [Default:E.Execute](DEBUG): Construct Execute.
2025-07-18T05:24:05.145Z,1752816245.145 [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-07-18T05:24:05.166Z,1752816245.166 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-07-18T05:24:05.173Z,1752816245.173 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-07-18T05:24:05.238Z,1752816245.238 [Depth_Keller](INFO): Initializing.
2025-07-18T05:24:05.239Z,1752816245.239 [Power24vConverter](INFO): Powering up.
2025-07-18T05:24:05.240Z,1752816245.240 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-07-18T05:24:05.246Z,1752816245.246 [Sonardyne_Nano](INFO): Initializing.
2025-07-18T05:24:05.290Z,1752816245.290 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-07-18T05:24:05.316Z,1752816245.316 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-07-18T05:24:05.322Z,1752816245.322 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-07-18T05:24:05.323Z,1752816245.323 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-18T05:24:05.330Z,1752816245.330 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-18T05:24:05.331Z,1752816245.331 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-07-18T05:24:05.339Z,1752816245.339 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-07-18T05:24:05.339Z,1752816245.339 [MassServo](DEBUG): Initializing EZServoServo.
2025-07-18T05:24:05.346Z,1752816245.346 [MassServo](DEBUG): Initializing MassServo.
2025-07-18T05:24:05.347Z,1752816245.347 [RudderServo](DEBUG): Initializing EZServoServo.
2025-07-18T05:24:05.354Z,1752816245.354 [RudderServo](DEBUG): Initializing RudderServo.
2025-07-18T05:24:05.355Z,1752816245.355 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-07-18T05:24:05.362Z,1752816245.362 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-07-18T05:24:06.627Z,1752816246.627 [WetLabsBB2FL](INFO): Powering down
2025-07-18T05:24:07.196Z,1752816247.196 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-07-18T05:24:08.854Z,1752816248.854 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207
2025-07-18T05:24:09.260Z,1752816249.260 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-07-18T05:24:11.071Z,1752816251.071 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-07-18T05:24:11.538Z,1752816251.538 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-07-18T05:24:16.555Z,1752816256.555 [DAT](INFO): DAT read:
2025-07-18T05:24:16.557Z,1752816256.557 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-07-18T05:24:18.320Z,1752816258.320 [DAT](INFO): DAT read: MF Frequency Band
2025-07-18T05:24:18.572Z,1752816258.572 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-07-18T05:24:18.573Z,1752816258.573 [DAT](INFO): DAT read: Jul 18 2025 05:24:12
2025-07-18T05:24:19.579Z,1752816259.579 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-07-18T05:24:19.580Z,1752816259.580 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP
2025-07-18T05:24:19.581Z,1752816259.581 [DAT](INFO): commRate: 600
2025-07-18T05:24:21.646Z,1752816261.646 [DAT](INFO): entering command mode
2025-07-18T05:24:21.848Z,1752816261.848 [DAT](INFO): DAT read:
2025-07-18T05:24:21.851Z,1752816261.851 [DAT](INFO): DAT read: user:1>
2025-07-18T05:24:21.852Z,1752816261.852 [DAT](INFO): setting verbose to 3
2025-07-18T05:24:22.099Z,1752816262.099 [DAT](INFO): DAT read: user:1>
2025-07-18T05:24:22.100Z,1752816262.100 [DAT](INFO): DAT read: Verbose | 3
2025-07-18T05:24:22.100Z,1752816262.100 [DAT](INFO): set verbose to 3
2025-07-18T05:24:22.100Z,1752816262.100 [DAT](INFO): setting DatVerbose to 27440
2025-07-18T05:24:22.351Z,1752816262.351 [DAT](INFO): DAT read: user:2>
2025-07-18T05:24:22.352Z,1752816262.352 [DAT](INFO): DAT read: DatVerbose | 27440
2025-07-18T05:24:22.352Z,1752816262.352 [DAT](INFO): set DatVerbose to 27440
2025-07-18T05:24:22.352Z,1752816262.352 [DAT](INFO): setting transmit power to 8
2025-07-18T05:24:22.603Z,1752816262.603 [DAT](INFO): DAT read: user:3>
2025-07-18T05:24:22.604Z,1752816262.604 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-07-18T05:24:22.604Z,1752816262.604 [DAT](INFO): set transmit power to 8
2025-07-18T05:24:22.604Z,1752816262.604 [DAT](INFO): setting local address to 11
2025-07-18T05:24:22.855Z,1752816262.855 [DAT](INFO): DAT read: user:4>
2025-07-18T05:24:22.856Z,1752816262.856 [DAT](INFO): DAT read: LocalAddr | 11
2025-07-18T05:24:22.856Z,1752816262.856 [DAT](INFO): set local address to 11
2025-07-18T05:24:22.857Z,1752816262.857 [DAT](INFO): Setting time to: 5:24:22 And date to:7/18/2025
2025-07-18T05:24:23.107Z,1752816263.107 [DAT](INFO): DAT read: user:5>
2025-07-18T05:24:23.107Z,1752816263.107 [DAT](INFO): DAT read: Fri Jul 18, 2025 05:24:22
2025-07-18T05:24:23.108Z,1752816263.108 [DAT](INFO): Local DAT time set to Fri Jul 18, 2025 05:24:22
2025-07-18T05:24:32.651Z,1752816272.651 [NAL9602](INFO): Powering up NAL9602
2025-07-18T05:24:43.563Z,1752816283.563 [NAL9602](INFO): NAL9602 initialized
2025-07-18T05:25:38.146Z,1752816338.146 [SBIT](IMPORTANT): Beginning Startup BIT
2025-07-18T05:25:38.151Z,1752816338.151 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-18T05:25:42.624Z,1752816342.624 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003858
CHAN A1 (24V): -0.001536
CHAN A2 (12V): 0.000188
CHAN A3 (5V): 0.000176
CHAN B0 (3.3V): 0.000175
CHAN B1 (3.15aV): 0.000719
CHAN B2 (3.15bV): 0.000762
CHAN B3 (GND): 0.000437
OPEN: 0.005158
Full Scale: +/- 1 mA
2025-07-18T05:26:32.311Z,1752816392.311 [SBIT](IMPORTANT): SBIT PASSED
2025-07-18T05:26:32.312Z,1752816392.312 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-07-18T05:26:32.312Z,1752816392.312 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2025-07-18T05:26:32.312Z,1752816392.312 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=5 minute;
2025-07-18T05:26:32.312Z,1752816392.312 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter;
2025-07-18T05:26:32.313Z,1752816392.313 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=159.857109 cubic_centimeter;
2025-07-18T05:26:32.313Z,1752816392.313 [SBIT](IMPORTANT): VerticalControl.massDefault=18.418426 millimeter;
2025-07-18T05:26:32.700Z,1752816392.700 [MissionManager](IMPORTANT): Started mission Startup
2025-07-18T05:26:32.700Z,1752816392.700 [Startup] Running Loop=1
2025-07-18T05:26:32.701Z,1752816392.701 [Startup](DEBUG): Aggregate::initialize Startup
2025-07-18T05:26:32.701Z,1752816392.701 [Startup:A.GoToSurface] Running Loop=1
2025-07-18T05:26:32.701Z,1752816392.701 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T05:26:32.701Z,1752816392.701 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-18T05:26:32.702Z,1752816392.702 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-18T05:26:32.702Z,1752816392.702 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-18T05:26:32.703Z,1752816392.703 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-18T05:26:32.703Z,1752816392.703 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T05:26:32.703Z,1752816392.703 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T05:26:32.705Z,1752816392.705 [Startup:StartupSatComms] Running Loop=1
2025-07-18T05:26:32.705Z,1752816392.705 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-07-18T05:26:32.705Z,1752816392.705 [Startup:StartupSatComms:A] Running Loop=1
2025-07-18T05:26:33.128Z,1752816393.128 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-07-18T05:26:52.595Z,1752816412.595 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004135
2025-07-18T05:27:04.655Z,1752816424.655 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-07-18T05:27:04.655Z,1752816424.655 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-18T05:27:04.704Z,1752816424.704 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-18T05:27:05.105Z,1752816425.105 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-18T05:27:05.105Z,1752816425.105 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-07-18T05:27:32.958Z,1752816452.958 [Startup:StartupSatComms:A](INFO): Timed out from 2025-07-18T05:26:32.7Z
2025-07-18T05:27:32.958Z,1752816452.958 [Startup:StartupSatComms:A] Stopped
2025-07-18T05:27:32.958Z,1752816452.958 [Startup:StartupSatComms:B] Running Loop=1
2025-07-18T05:27:33.315Z,1752816453.315 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-18T05:27:40.699Z,1752816460.699 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250718T045733/Courier0003.lzma
2025-07-18T05:27:41.700Z,1752816461.700 [DataOverHttps](INFO): Moved sent file to Logs/20250718T045733/Courier0003.lzma.bak
2025-07-18T05:27:41.700Z,1752816461.700 [DataOverHttps](INFO): SBD MOMSN=25544115
2025-07-18T05:27:57.557Z,1752816477.557 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250718T045733/Courier0006.lzma
2025-07-18T05:27:58.560Z,1752816478.560 [DataOverHttps](INFO): Moved sent file to Logs/20250718T045733/Courier0006.lzma.bak
2025-07-18T05:27:58.560Z,1752816478.560 [DataOverHttps](INFO): SBD MOMSN=25544118
2025-07-18T05:28:14.494Z,1752816494.494 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250718T052352/Courier0000.lzma
2025-07-18T05:28:15.496Z,1752816495.496 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0000.lzma.bak
2025-07-18T05:28:15.496Z,1752816495.496 [DataOverHttps](INFO): SBD MOMSN=25544121
2025-07-18T05:28:31.453Z,1752816511.453 [DataOverHttps](INFO): Sending 1326 bytes from file Logs/20250718T045733/Express0004.lzma
2025-07-18T05:28:32.452Z,1752816512.452 [DataOverHttps](INFO): Moved sent file to Logs/20250718T045733/Express0004.lzma.bak
2025-07-18T05:28:32.452Z,1752816512.452 [DataOverHttps](INFO): SBD MOMSN=25544124
2025-07-18T05:28:33.189Z,1752816513.189 [Startup:StartupSatComms:B](INFO): Timed out from 2025-07-18T05:27:32.0Z
2025-07-18T05:28:33.189Z,1752816513.189 [Startup:StartupSatComms:B] Stopped
2025-07-18T05:28:33.189Z,1752816513.189 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-07-18T05:28:33.189Z,1752816513.189 [Startup:StartupSatComms] Stopped
2025-07-18T05:28:33.189Z,1752816513.189 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-07-18T05:28:33.226Z,1752816513.226 [Startup](INFO): Completed Startup
2025-07-18T05:28:33.227Z,1752816513.227 [MissionManager](INFO): Startup is completed.
2025-07-18T05:28:33.227Z,1752816513.227 [MissionManager](INFO): Uninitializing Mission Startup
2025-07-18T05:28:33.227Z,1752816513.227 [Startup] Stopped
2025-07-18T05:28:33.227Z,1752816513.227 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-07-18T05:28:33.227Z,1752816513.227 [Startup:A.GoToSurface] Stopped
2025-07-18T05:28:33.227Z,1752816513.227 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T05:28:33.527Z,1752816513.527 [MissionManager](IMPORTANT): Started mission Default
2025-07-18T05:28:33.527Z,1752816513.527 [Default] Running Loop=1
2025-07-18T05:28:33.527Z,1752816513.527 [Default](DEBUG): Aggregate::initialize Default
2025-07-18T05:28:33.527Z,1752816513.527 [Default:B.GoToSurface] Running Loop=1
2025-07-18T05:28:33.527Z,1752816513.527 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T05:28:33.527Z,1752816513.527 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-18T05:28:33.528Z,1752816513.528 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-18T05:28:33.528Z,1752816513.528 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-18T05:28:33.528Z,1752816513.528 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-18T05:28:33.528Z,1752816513.528 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T05:28:33.529Z,1752816513.529 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T05:28:33.529Z,1752816513.529 [Default:A.Wait] Running Loop=1
2025-07-18T05:28:33.529Z,1752816513.529 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-07-18T05:28:46.842Z,1752816526.842 [Default:A.Wait](INFO): Done Waiting.
2025-07-18T05:28:46.842Z,1752816526.842 [Default:A.Wait] Stopped
2025-07-18T05:28:46.842Z,1752816526.842 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-07-18T05:28:47.268Z,1752816527.268 [Default:CheckIn] Running Loop=1
2025-07-18T05:28:47.268Z,1752816527.268 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-18T05:28:47.268Z,1752816527.268 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-18T05:28:47.711Z,1752816527.711 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-18T05:28:54.111Z,1752816534.111 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-18T05:29:02.583Z,1752816542.583 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-07-18T05:29:47.447Z,1752816587.447 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T05:29:48.645Z,1752816588.645 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,052947.00,A,3646.81963,N,12151.73907,W,0.311,133.98,180725,,,D*70
2025-07-18T05:29:48.648Z,1752816588.648 [NAL9602](INFO): GPS fix at 20250718T052947: (36.780327, -121.862318)
2025-07-18T05:29:48.676Z,1752816588.676 [Default:CheckIn:Read_GPS] Stopped
2025-07-18T05:29:48.676Z,1752816588.676 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-18T05:29:49.128Z,1752816589.128 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-18T05:29:51.591Z,1752816591.591 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20250718T052352/Courier0004.lzma
2025-07-18T05:29:52.592Z,1752816592.592 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0004.lzma.bak
2025-07-18T05:29:52.592Z,1752816592.592 [DataOverHttps](INFO): SBD MOMSN=25544179
2025-07-18T05:30:09.677Z,1752816609.677 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250718T045733/Express0007.lzma
2025-07-18T05:30:10.680Z,1752816610.680 [DataOverHttps](INFO): Moved sent file to Logs/20250718T045733/Express0007.lzma.bak
2025-07-18T05:30:10.680Z,1752816610.680 [DataOverHttps](INFO): SBD MOMSN=25544182
2025-07-18T05:30:21.520Z,1752816621.520 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T05:30:31.208Z,1752816631.208 [DataOverHttps](INFO): Sending 870 bytes from file Logs/20250718T052352/Express0001.lzma
2025-07-18T05:30:32.208Z,1752816632.208 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0001.lzma.bak
2025-07-18T05:30:32.208Z,1752816632.208 [DataOverHttps](INFO): SBD MOMSN=25544184
2025-07-18T05:30:39.642Z,1752816639.642 [CommandExec](IMPORTANT): got command get latitude
2025-07-18T05:30:39.643Z,1752816639.643 [CommandExec](IMPORTANT): latitude 36.780288 arcdeg
2025-07-18T05:30:49.155Z,1752816649.155 [DataOverHttps](INFO): Sending 412 bytes from file Logs/20250718T052352/Express0005.lzma
2025-07-18T05:30:50.156Z,1752816650.156 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0005.lzma.bak
2025-07-18T05:30:50.156Z,1752816650.156 [DataOverHttps](INFO): SBD MOMSN=25544203
2025-07-18T05:30:51.447Z,1752816651.447 [Default:CheckIn:Read_Iridium] Stopped
2025-07-18T05:30:51.448Z,1752816651.448 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-18T05:30:51.448Z,1752816651.448 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-18T05:31:35.057Z,1752816695.057 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012309182313,35.0, +14.7, 0.0,1505
2025-07-18T05:31:56.187Z,1752816716.187 [DAT](INFO): DAT read: user:6>Rx Time:05:31:54.6790
2025-07-18T05:31:56.187Z,1752816716.187 [DAT](INFO): Rx dataTimestamp_ set to:1752816716.187148
2025-07-18T05:31:56.188Z,1752816716.188 [DAT](INFO): DAT read:
2025-07-18T05:31:56.188Z,1752816716.188 [DAT](INFO): DAT read: $Packet for address 0
2025-07-18T05:31:56.189Z,1752816716.189 [DAT](INFO): received a packet notification
2025-07-18T05:35:51.999Z,1752816951.999 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-18T05:35:51.999Z,1752816951.999 [Default:CheckIn:C.Wait] Stopped
2025-07-18T05:35:51.999Z,1752816951.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-18T05:35:51.999Z,1752816951.999 [Default:CheckIn:D] Running Loop=1
2025-07-18T05:35:52.404Z,1752816952.404 [Default:CheckIn:D] Stopped
2025-07-18T05:35:52.404Z,1752816952.404 [Default:CheckIn:E] Running Loop=1
2025-07-18T05:35:52.815Z,1752816952.815 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.314619 min
2025-07-18T05:35:52.815Z,1752816952.815 [Default:CheckIn:E] Stopped
2025-07-18T05:35:52.815Z,1752816952.815 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-18T05:35:52.816Z,1752816952.816 [Default:CheckIn] Stopped
2025-07-18T05:35:52.816Z,1752816952.816 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-18T05:35:52.816Z,1752816952.816 [Default:CheckIn](INFO): Running loop #2
2025-07-18T05:35:52.816Z,1752816952.816 [Default:CheckIn] Running Loop=2
2025-07-18T05:35:52.816Z,1752816952.816 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-18T05:35:52.816Z,1752816952.816 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-18T05:35:54.818Z,1752816954.818 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053554.00,A,3646.80697,N,12151.72397,W,0.194,121.01,180725,,,D*74
2025-07-18T05:35:54.821Z,1752816954.821 [NAL9602](INFO): GPS fix at 20250718T053554: (36.780116, -121.862066)
2025-07-18T05:35:54.849Z,1752816954.849 [Default:CheckIn:Read_GPS] Stopped
2025-07-18T05:35:54.849Z,1752816954.849 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-18T05:36:01.949Z,1752816961.949 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250718T052352/Courier0007.lzma
2025-07-18T05:36:02.952Z,1752816962.952 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0007.lzma.bak
2025-07-18T05:36:02.952Z,1752816962.952 [DataOverHttps](INFO): SBD MOMSN=25544215
2025-07-18T05:36:20.247Z,1752816980.247 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20250718T052352/Express0008.lzma
2025-07-18T05:36:21.248Z,1752816981.248 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0008.lzma.bak
2025-07-18T05:36:21.249Z,1752816981.249 [DataOverHttps](IMPORTANT): SBD MOMSN=25544219, MTMSN=20250718T053620
2025-07-18T05:36:22.313Z,1752816982.313 [Default:CheckIn:Read_Iridium] Stopped
2025-07-18T05:36:22.313Z,1752816982.313 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-18T05:36:22.313Z,1752816982.313 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-18T05:36:25.523Z,1752816985.523 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-18T05:36:25.602Z,1752816985.602 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-07-18T05:36:29.058Z,1752816989.058 [DataOverHttps](INFO): Received command: load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.78343 degree;set keepstation.Longitude -121.8649 degree;run
2025-07-18T05:36:29.094Z,1752816989.094 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-07-18T05:36:29.094Z,1752816989.094 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-07-18T05:36:29.094Z,1752816989.094 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-07-18T05:36:29.098Z,1752816989.098 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-07-18T05:36:30.437Z,1752816990.437 [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-07-18T05:36:30.446Z,1752816990.446 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-07-18T05:36:30.449Z,1752816990.449 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-07-18T05:36:30.452Z,1752816990.452 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-07-18T05:36:30.456Z,1752816990.456 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-07-18T05:36:30.459Z,1752816990.459 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-07-18T05:36:30.462Z,1752816990.462 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-07-18T05:36:30.465Z,1752816990.465 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-07-18T05:36:30.496Z,1752816990.496 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-07-18T05:36:30.501Z,1752816990.501 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-07-18T05:36:30.550Z,1752816990.550 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-07-18T05:36:30.553Z,1752816990.553 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-07-18T05:36:30.560Z,1752816990.560 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-07-18T05:36:30.572Z,1752816990.572 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-07-18T05:36:30.591Z,1752816990.591 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-07-18T05:36:30.609Z,1752816990.609 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-07-18T05:36:30.620Z,1752816990.620 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-07-18T05:36:30.629Z,1752816990.629 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-07-18T05:36:30.636Z,1752816990.636 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-07-18T05:36:30.641Z,1752816990.641 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-07-18T05:36:30.659Z,1752816990.659 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-07-18T05:36:30.660Z,1752816990.660 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-07-18T05:36:30.660Z,1752816990.660 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-07-18T05:36:30.661Z,1752816990.661 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-07-18T05:36:31.341Z,1752816991.341 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1000
7
30
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2025-07-18T05:36:31.349Z,1752816991.349 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-07-18T05:36:31.368Z,1752816991.368 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-07-18T05:36:31.372Z,1752816991.372 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-07-18T05:36:31.375Z,1752816991.375 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-07-18T05:36:31.394Z,1752816991.394 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-07-18T05:36:31.397Z,1752816991.397 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-07-18T05:36:31.405Z,1752816991.405 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-07-18T05:36:31.412Z,1752816991.412 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-07-18T05:36:31.440Z,1752816991.440 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-18T05:36:31.544Z,1752816991.544 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-07-18T05:36:31.544Z,1752816991.544 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-07-18T05:36:31.545Z,1752816991.545 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-07-18T05:36:31.766Z,1752816991.766 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2025-07-18T05:36:31.771Z,1752816991.771 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-07-18T05:36:31.784Z,1752816991.784 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-07-18T05:36:31.787Z,1752816991.787 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-07-18T05:36:31.798Z,1752816991.798 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-07-18T05:36:31.799Z,1752816991.799 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-07-18T05:36:31.828Z,1752816991.828 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-07-18T05:36:31.866Z,1752816991.866 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-07-18T05:36:31.889Z,1752816991.889 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-07-18T05:36:31.906Z,1752816991.906 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-07-18T05:36:31.907Z,1752816991.907 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-07-18T05:36:31.947Z,1752816991.947 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-07-18T05:36:31.957Z,1752816991.957 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-07-18T05:36:31.964Z,1752816991.964 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-07-18T05:36:31.967Z,1752816991.967 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-07-18T05:36:31.967Z,1752816991.967 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-07-18T05:36:31.968Z,1752816991.968 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-07-18T05:36:32.260Z,1752816992.260 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-07-18T05:36:32.265Z,1752816992.265 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-07-18T05:36:32.268Z,1752816992.268 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-07-18T05:36:32.271Z,1752816992.271 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-07-18T05:36:32.273Z,1752816992.273 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-07-18T05:36:32.277Z,1752816992.277 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2025-07-18T05:36:32.285Z,1752816992.285 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-07-18T05:36:32.288Z,1752816992.288 [keepstation:H.Pitch](DEBUG): Construct.
2025-07-18T05:36:32.365Z,1752816992.365 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-07-18T05:36:32.369Z,1752816992.369 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-07-18T05:36:32.373Z,1752816992.373 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-07-18T05:36:32.461Z,1752816992.461 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-07-18T05:36:32.473Z,1752816992.473 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-07-18T05:36:32.508Z,1752816992.508 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-07-18T05:36:32.526Z,1752816992.526 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-07-18T05:36:32.533Z,1752816992.533 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-07-18T05:36:32.559Z,1752816992.559 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-07-18T05:36:32.591Z,1752816992.591 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-07-18T05:36:32.623Z,1752816992.623 [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 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-07-18T05:36:32.624Z,1752816992.624 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-07-18T05:36:46.425Z,1752817006.425 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 11 hour
2025-07-18T05:36:46.426Z,1752817006.426 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute
2025-07-18T05:36:46.427Z,1752817006.427 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.78343 degree
2025-07-18T05:36:46.428Z,1752817006.428 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.8649 degree
2025-07-18T05:36:46.428Z,1752817006.428 [CommandExec](IMPORTANT): got command run
2025-07-18T05:36:46.431Z,1752817006.431 [CommandExec](IMPORTANT): Running
2025-07-18T05:36:46.624Z,1752817006.624 [Default] Stopped
2025-07-18T05:36:46.624Z,1752817006.624 [Default](DEBUG): Aggregate::uninitialize Default
2025-07-18T05:36:46.625Z,1752817006.625 [Default:B.GoToSurface] Stopped
2025-07-18T05:36:46.625Z,1752817006.625 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T05:36:46.625Z,1752817006.625 [Default:CheckIn] Stopped
2025-07-18T05:36:46.625Z,1752817006.625 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-18T05:36:46.625Z,1752817006.625 [Default:CheckIn:C.Wait] Stopped
2025-07-18T05:36:46.625Z,1752817006.625 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-18T05:36:46.625Z,1752817006.625 [MissionManager](IMPORTANT): Started mission keepstation
2025-07-18T05:36:46.625Z,1752817006.625 [keepstation] Running Loop=1
2025-07-18T05:36:46.625Z,1752817006.625 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-07-18T05:36:46.626Z,1752817006.626 [keepstation:StandardEnvelopes] Running Loop=1
2025-07-18T05:36:46.626Z,1752817006.626 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-07-18T05:36:46.626Z,1752817006.626 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-07-18T05:36:46.626Z,1752817006.626 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-07-18T05:36:46.626Z,1752817006.626 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-07-18T05:36:46.626Z,1752817006.626 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-07-18T05:36:46.627Z,1752817006.627 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-07-18T05:36:46.627Z,1752817006.627 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-07-18T05:36:46.627Z,1752817006.627 [keepstation:BackseatDriver] Running Loop=1
2025-07-18T05:36:46.627Z,1752817006.627 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-07-18T05:36:46.627Z,1752817006.627 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-07-18T05:36:46.627Z,1752817006.627 [keepstation:PowerOnly] Running Loop=1
2025-07-18T05:36:46.627Z,1752817006.627 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-07-18T05:36:46.627Z,1752817006.627 [keepstation:PowerOnly:A] Running Loop=1
2025-07-18T05:36:46.627Z,1752817006.627 [keepstation:PowerOnly:B] Running Loop=1
2025-07-18T05:36:46.627Z,1752817006.627 [keepstation:PowerOnly:C] Running Loop=1
2025-07-18T05:36:46.627Z,1752817006.627 [keepstation:PowerOnly:D] Running Loop=1
2025-07-18T05:36:46.628Z,1752817006.628 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-07-18T05:36:46.628Z,1752817006.628 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-07-18T05:36:46.628Z,1752817006.628 [keepstation:H.Pitch] Running Loop=1
2025-07-18T05:36:46.628Z,1752817006.628 [keepstation:H.Pitch](DEBUG): Initialize.
2025-07-18T05:36:46.628Z,1752817006.628 [keepstation:B] Running Loop=1
2025-07-18T05:36:46.629Z,1752817006.629 [keepstation:H.Pitch] Running Loop=1
2025-07-18T05:36:46.629Z,1752817006.629 [keepstation:PowerOnly] Running Loop=1
2025-07-18T05:36:46.630Z,1752817006.630 [keepstation:PowerOnly:D] Stopped
2025-07-18T05:36:46.630Z,1752817006.630 [keepstation:PowerOnly:C] Stopped
2025-07-18T05:36:46.630Z,1752817006.630 [keepstation:PowerOnly:B] Stopped
2025-07-18T05:36:46.630Z,1752817006.630 [keepstation:PowerOnly:A] Stopped
2025-07-18T05:36:46.630Z,1752817006.630 [keepstation:BackseatDriver] Running Loop=1
2025-07-18T05:36:46.631Z,1752817006.631 [keepstation:StandardEnvelopes] Running Loop=1
2025-07-18T05:36:46.631Z,1752817006.631 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-07-18T05:36:46.631Z,1752817006.631 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-07-18T05:36:46.632Z,1752817006.632 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-07-18T05:36:46.633Z,1752817006.633 [keepstation:B] Stopped
2025-07-18T05:36:46.633Z,1752817006.633 [keepstation:D] Running Loop=1
2025-07-18T05:36:47.057Z,1752817007.057 [keepstation:D] Stopped
2025-07-18T05:36:47.057Z,1752817007.057 [keepstation:E] Running Loop=1
2025-07-18T05:36:47.428Z,1752817007.428 [keepstation:E] Stopped
2025-07-18T05:36:47.428Z,1752817007.428 [keepstation:StartingMission] Running Loop=1
2025-07-18T05:36:47.428Z,1752817007.428 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-07-18T05:36:47.833Z,1752817007.833 [keepstation:SurfaceComms] Running Loop=1
2025-07-18T05:36:47.833Z,1752817007.833 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-07-18T05:36:47.833Z,1752817007.833 [keepstation:SurfaceComms:A] Running Loop=1
2025-07-18T05:36:47.834Z,1752817007.834 [keepstation:SurfaceComms:A] Stopped
2025-07-18T05:36:47.834Z,1752817007.834 [keepstation:SurfaceComms:B] Running Loop=1
2025-07-18T05:36:47.834Z,1752817007.834 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-07-18T05:36:48.213Z,1752817008.213 [keepstation:NeedComms] Running Loop=1
2025-07-18T05:36:48.213Z,1752817008.213 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-07-18T05:36:48.213Z,1752817008.213 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-07-18T05:36:48.213Z,1752817008.213 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T05:36:48.213Z,1752817008.213 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-18T05:36:48.214Z,1752817008.214 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-18T05:36:48.214Z,1752817008.214 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-18T05:36:48.215Z,1752817008.215 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-18T05:36:48.215Z,1752817008.215 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T05:36:48.215Z,1752817008.215 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T05:36:48.216Z,1752817008.216 [keepstation:NeedComms:A] Running Loop=1
2025-07-18T05:36:48.217Z,1752817008.217 [keepstation:NeedComms:A](INFO): last time_fix was: 1752816954.000000 second since 1970/01/01T00:00:00Z
2025-07-18T05:36:48.217Z,1752817008.217 [keepstation:NeedComms:A] Stopped
2025-07-18T05:36:48.626Z,1752817008.626 [keepstation:NeedComms:C] Running Loop=1
2025-07-18T05:36:49.053Z,1752817009.053 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-18T05:40:56.901Z,1752817256.901 [DAT](INFO): DAT read: Rx Time:05:40:55.5821
2025-07-18T05:40:56.902Z,1752817256.902 [DAT](INFO): Rx dataTimestamp_ set to:1752817256.901393
2025-07-18T05:40:57.499Z,1752817257.499 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T05:40:57.660Z,1752817257.660 [DAT](INFO): DAT read: 05:40:55.5821 LVL= 32752, 32753, 32754, 32755, AGC= 86, IDX= 512, 0.05,-1.161, 0.153,-0.785,-0.187, PHS=-0.872, 0.384,-0.643, RAW= 320.1, 19.0, CAL= 319.5, 23.8, ROT= 190.5, -23.8
2025-07-18T05:40:57.661Z,1752817257.661 [DAT](INFO): got valid direction response:
05:40:55.5821 LVL= 32752, 32753, 32754, 32755, AGC= 86, IDX= 512, 0.05,-1.161, 0.153,-0.785,-0.187, PHS=-0.872, 0.384,-0.643, RAW= 320.1, 19.0, CAL= 319.5, 23.8, ROT= 190.5, -23.8
2025-07-18T05:40:57.662Z,1752817257.662 [DAT](INFO): DAT read: $Error in header
2025-07-18T05:40:57.663Z,1752817257.663 [DAT](INFO): Received a bad header
2025-07-18T05:40:57.676Z,1752817257.676 [DAT](INFO): #Rx 1: Read direction message, but no range.
2025-07-18T05:40:57.677Z,1752817257.677 [DAT](INFO): direction in FSK: [-0.899639,-0.166738,0.403545]
2025-07-18T05:41:01.943Z,1752817261.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,054101.00,A,3646.79303,N,12151.71240,W,0.369,169.48,180725,,,D*70
2025-07-18T05:41:01.956Z,1752817261.956 [NAL9602](INFO): GPS fix at 20250718T054101: (36.779884, -121.861873)
2025-07-18T05:41:01.971Z,1752817261.971 [keepstation:NeedComms:C] Stopped
2025-07-18T05:41:01.972Z,1752817261.972 [keepstation:NeedComms:D] Running Loop=1
2025-07-18T05:41:02.392Z,1752817262.392 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-18T05:41:10.085Z,1752817270.085 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250718T052352/Courier0010.lzma
2025-07-18T05:41:11.087Z,1752817271.088 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0010.lzma.bak
2025-07-18T05:41:11.088Z,1752817271.088 [DataOverHttps](INFO): SBD MOMSN=25544231
2025-07-18T05:41:27.503Z,1752817287.503 [DataOverHttps](INFO): Sending 446 bytes from file Logs/20250718T052352/Express0011.lzma
2025-07-18T05:41:28.504Z,1752817288.504 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0011.lzma.bak
2025-07-18T05:41:28.504Z,1752817288.504 [DataOverHttps](INFO): SBD MOMSN=25544234
2025-07-18T05:41:34.667Z,1752817294.667 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T05:41:45.185Z,1752817305.185 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250718T052352/Express0014.lzma
2025-07-18T05:41:46.188Z,1752817306.188 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0014.lzma.bak
2025-07-18T05:41:46.188Z,1752817306.188 [DataOverHttps](INFO): SBD MOMSN=25544247
2025-07-18T05:41:47.297Z,1752817307.297 [keepstation:NeedComms:D] Stopped
2025-07-18T05:41:47.297Z,1752817307.297 [keepstation:NeedComms:E] Running Loop=1
2025-07-18T05:41:47.637Z,1752817307.637 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-18T05:41:49.263Z,1752817309.263 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,054148.00,A,3646.79070,N,12151.71105,W,0.972,149.02,180725,,,D*74
2025-07-18T05:41:49.265Z,1752817309.265 [NAL9602](INFO): GPS fix at 20250718T054148: (36.779845, -121.861851)
2025-07-18T05:41:49.358Z,1752817309.358 [keepstation:NeedComms:E] Stopped
2025-07-18T05:41:49.359Z,1752817309.359 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-07-18T05:41:49.359Z,1752817309.359 [keepstation:NeedComms] Stopped
2025-07-18T05:41:49.360Z,1752817309.360 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-07-18T05:41:49.360Z,1752817309.360 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-07-18T05:41:49.360Z,1752817309.360 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T05:41:49.699Z,1752817309.699 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-07-18T05:41:49.699Z,1752817309.699 [keepstation:SurfaceComms:B] Stopped
2025-07-18T05:41:49.699Z,1752817309.699 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-07-18T05:41:49.699Z,1752817309.699 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-07-18T05:41:49.700Z,1752817309.700 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-07-18T05:41:49.700Z,1752817309.700 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-07-18T05:41:50.072Z,1752817310.072 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-07-18T05:41:50.072Z,1752817310.072 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2025-07-18T05:41:50.072Z,1752817310.072 [keepstation:SurfaceComms:setTransit] Stopped
2025-07-18T05:41:50.072Z,1752817310.072 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-07-18T05:41:50.072Z,1752817310.072 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-07-18T05:41:50.072Z,1752817310.072 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-07-18T05:41:50.072Z,1752817310.072 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-07-18T05:41:50.484Z,1752817310.484 [keepstation:SurfaceComms:setStation] Stopped
2025-07-18T05:41:50.485Z,1752817310.485 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-07-18T05:41:50.485Z,1752817310.485 [keepstation:SurfaceComms:setStation:A] Stopped
2025-07-18T05:41:50.485Z,1752817310.485 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-07-18T05:41:50.485Z,1752817310.485 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-07-18T05:41:50.485Z,1752817310.485 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-07-18T05:41:51.027Z,1752817311.027 [keepstation:SurfaceComms:setSink] Stopped
2025-07-18T05:41:51.027Z,1752817311.027 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-07-18T05:41:51.027Z,1752817311.027 [keepstation:SurfaceComms:setSink:A] Stopped
2025-07-18T05:41:51.027Z,1752817311.027 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-07-18T05:41:51.027Z,1752817311.027 [keepstation:SurfaceComms] Stopped
2025-07-18T05:41:51.027Z,1752817311.027 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-07-18T05:41:51.282Z,1752817311.282 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-07-18T05:41:51.282Z,1752817311.282 [keepstation:StartingMission] Stopped
2025-07-18T05:41:51.283Z,1752817311.283 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-07-18T05:41:51.283Z,1752817311.283 [keepstation:TransitToStation] Running Loop=1
2025-07-18T05:41:51.283Z,1752817311.283 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-07-18T05:41:51.283Z,1752817311.283 [keepstation:TransitToStation:Dive] Running Loop=1
2025-07-18T05:41:51.283Z,1752817311.283 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-07-18T05:41:51.283Z,1752817311.283 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-07-18T05:41:51.283Z,1752817311.283 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-07-18T05:41:51.283Z,1752817311.283 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-07-18T05:41:51.283Z,1752817311.283 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-07-18T05:41:51.285Z,1752817311.285 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.783428,-121.864899
2025-07-18T05:41:51.688Z,1752817311.688 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.783428,-121.864899
2025-07-18T05:41:51.689Z,1752817311.689 [keepstation:TransitToStation:Dive] Running Loop=1
2025-07-18T05:41:51.689Z,1752817311.689 [keepstation:DiveCmd] Running Loop=1
2025-07-18T05:41:51.689Z,1752817311.689 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-07-18T05:41:51.689Z,1752817311.689 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-07-18T05:41:51.689Z,1752817311.689 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-07-18T05:41:51.694Z,1752817311.694 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-07-18T05:41:51.694Z,1752817311.694 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-07-18T05:41:51.694Z,1752817311.694 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-07-18T05:41:51.694Z,1752817311.694 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-07-18T05:41:51.694Z,1752817311.694 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-07-18T05:41:51.694Z,1752817311.694 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-07-18T05:41:51.694Z,1752817311.694 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-07-18T05:41:51.694Z,1752817311.694 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-07-18T05:41:51.695Z,1752817311.695 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-07-18T05:41:51.695Z,1752817311.695 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-07-18T05:41:51.695Z,1752817311.695 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-07-18T05:41:51.695Z,1752817311.695 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-07-18T05:41:51.695Z,1752817311.695 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-07-18T05:41:51.695Z,1752817311.695 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-07-18T05:41:51.695Z,1752817311.695 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-07-18T05:41:51.695Z,1752817311.695 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-07-18T05:41:51.695Z,1752817311.695 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-07-18T05:41:51.696Z,1752817311.696 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-07-18T05:41:51.696Z,1752817311.696 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-07-18T05:41:51.696Z,1752817311.696 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-07-18T05:41:51.696Z,1752817311.696 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-07-18T05:41:51.696Z,1752817311.696 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-07-18T05:41:51.696Z,1752817311.696 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-07-18T05:41:51.696Z,1752817311.696 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-07-18T05:41:51.697Z,1752817311.697 [keepstation:DiveCmd:StationKeep] Stopped
2025-07-18T05:41:51.697Z,1752817311.697 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-07-18T05:41:51.697Z,1752817311.697 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-07-18T05:41:51.697Z,1752817311.697 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-07-18T05:41:51.697Z,1752817311.697 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-07-18T05:41:51.697Z,1752817311.697 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-07-18T05:41:51.697Z,1752817311.697 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-07-18T05:41:51.697Z,1752817311.697 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-07-18T05:41:51.702Z,1752817311.702 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-07-18T05:41:51.702Z,1752817311.702 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-07-18T05:41:51.703Z,1752817311.703 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-07-18T05:41:51.703Z,1752817311.703 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-07-18T05:41:51.703Z,1752817311.703 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-07-18T05:41:51.703Z,1752817311.703 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-07-18T05:41:51.703Z,1752817311.703 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-07-18T05:41:51.703Z,1752817311.703 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-07-18T05:41:51.703Z,1752817311.703 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-07-18T05:41:51.703Z,1752817311.703 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-07-18T05:41:51.703Z,1752817311.703 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-07-18T05:41:51.748Z,1752817311.748 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-07-18T05:41:51.748Z,1752817311.748 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-07-18T05:41:51.748Z,1752817311.748 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-07-18T05:42:19.667Z,1752817339.667 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-18T05:42:19.750Z,1752817339.750 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-07-18T05:44:06.398Z,1752817446.398 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.327278
2025-07-18T05:44:38.530Z,1752817478.530 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T05:44:40.022Z,1752817480.022 [Radio_Surface](INFO): Powering down
2025-07-18T05:44:40.538Z,1752817480.538 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-18T05:46:51.547Z,1752817611.547 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T05:47:22.253Z,1752817642.253 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T05:50:44.308Z,1752817844.308 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T05:51:21.081Z,1752817881.081 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012309380913,35.0, +13.2, 0., 0
2025-07-18T05:51:40.999Z,1752817900.999 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 784.47, 665.10, 736.72
2025-07-18T05:56:14.969Z,1752818174.969 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.783428,-121.864899
2025-07-18T05:56:14.969Z,1752818174.969 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-07-18T05:56:14.969Z,1752818174.969 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-07-18T05:56:14.969Z,1752818174.969 [keepstation:TransitToStation:C] Running Loop=1
2025-07-18T05:56:15.317Z,1752818175.317 [keepstation:TransitToStation:C] Stopped
2025-07-18T05:56:15.317Z,1752818175.317 [keepstation:TransitToStation:D] Running Loop=1
2025-07-18T05:56:15.319Z,1752818175.319 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-07-18T05:56:15.319Z,1752818175.319 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-07-18T05:56:15.319Z,1752818175.319 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-07-18T05:56:15.319Z,1752818175.319 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-07-18T05:56:15.319Z,1752818175.319 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-07-18T05:56:15.319Z,1752818175.319 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-07-18T05:56:15.319Z,1752818175.319 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-07-18T05:56:15.319Z,1752818175.319 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-07-18T05:56:15.320Z,1752818175.320 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-07-18T05:56:15.320Z,1752818175.320 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-07-18T05:56:15.321Z,1752818175.321 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-07-18T05:56:15.321Z,1752818175.321 [keepstation:DiveCmd:TransitToStation] Stopped
2025-07-18T05:56:15.321Z,1752818175.321 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-07-18T05:56:15.321Z,1752818175.321 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-07-18T05:56:15.321Z,1752818175.321 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-07-18T05:56:15.321Z,1752818175.321 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-07-18T05:56:15.321Z,1752818175.321 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-07-18T05:56:15.321Z,1752818175.321 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-07-18T05:56:15.321Z,1752818175.321 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-07-18T05:56:15.322Z,1752818175.322 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-07-18T05:56:15.715Z,1752818175.715 [keepstation:TransitToStation:D] Stopped
2025-07-18T05:56:15.716Z,1752818175.716 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-07-18T05:56:15.716Z,1752818175.716 [keepstation:TransitToStation] Stopped
2025-07-18T05:56:15.716Z,1752818175.716 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-07-18T05:56:15.716Z,1752818175.716 [keepstation:TransitToStation:Dive] Stopped
2025-07-18T05:56:15.716Z,1752818175.716 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-07-18T05:56:15.716Z,1752818175.716 [keepstation:TransitToStation:Dive:A] Stopped
2025-07-18T05:56:15.716Z,1752818175.716 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-07-18T05:56:15.716Z,1752818175.716 [keepstation:OnStation] Running Loop=1
2025-07-18T05:56:15.716Z,1752818175.716 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-07-18T05:56:16.212Z,1752818176.212 [keepstation:NeedComms] Running Loop=1
2025-07-18T05:56:16.213Z,1752818176.213 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-07-18T05:56:16.213Z,1752818176.213 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-07-18T05:56:16.213Z,1752818176.213 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T05:56:16.213Z,1752818176.213 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-18T05:56:16.213Z,1752818176.213 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-18T05:56:16.214Z,1752818176.214 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-18T05:56:16.214Z,1752818176.214 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-18T05:56:16.215Z,1752818176.215 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T05:56:16.215Z,1752818176.215 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T05:56:16.215Z,1752818176.215 [keepstation:NeedComms:A] Running Loop=1
2025-07-18T05:56:16.217Z,1752818176.217 [keepstation:NeedComms:A](INFO): last time_fix was: 1752817308.000000 second since 1970/01/01T00:00:00Z
2025-07-18T05:56:16.217Z,1752818176.217 [keepstation:NeedComms:A] Stopped
2025-07-18T05:56:18.926Z,1752818178.926 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T05:56:18.927Z,1752818178.927 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T05:56:18.928Z,1752818178.928 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-18T05:56:18.928Z,1752818178.928 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T05:56:18.929Z,1752818178.929 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T05:56:18.930Z,1752818178.930 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T05:56:18.930Z,1752818178.930 [BPC1](INFO): Calculating totals. Valid battery stick count: 31. Valid reserve battery stick count: 2.
2025-07-18T05:56:18.934Z,1752818178.934 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-07-18T05:57:26.434Z,1752818246.434 [keepstation:NeedComms:C] Running Loop=1
2025-07-18T05:57:26.883Z,1752818246.883 [Radio_Surface](INFO): Powering up
2025-07-18T05:57:32.783Z,1752818252.783 [DataOverHttps](INFO): Radio surface powered ON.
2025-07-18T05:57:59.160Z,1752818279.160 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-18T05:57:59.238Z,1752818279.238 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-07-18T05:58:02.918Z,1752818282.918 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T05:58:38.066Z,1752818318.066 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T05:59:13.206Z,1752818353.206 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T05:59:48.346Z,1752818388.346 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T06:00:04.146Z,1752818404.146 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002825
2025-07-18T06:02:30.244Z,1752818550.244 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T06:02:35.096Z,1752818555.096 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,060234.00,A,3647.03409,N,12151.94185,W,0.544,266.67,180725,,,D*7A
2025-07-18T06:02:35.098Z,1752818555.098 [NAL9602](INFO): GPS fix at 20250718T060234: (36.783901, -121.865697)
2025-07-18T06:02:35.131Z,1752818555.131 [UniversalFixResidualReporter](INFO): Fix residual: 1.8 %DT, over the last 637.0 m. Residual distance 11.2 m at bearing -74.8 degrees. Fix at (36.7839, -121.8657) with 566.4 m made good.
2025-07-18T06:02:35.152Z,1752818555.152 [keepstation:NeedComms:C] Stopped
2025-07-18T06:02:35.152Z,1752818555.152 [keepstation:NeedComms:D] Running Loop=1
2025-07-18T06:02:43.202Z,1752818563.202 [DataOverHttps](INFO): Sending 264 bytes from file Logs/20250718T052352/Courier0016.lzma
2025-07-18T06:02:44.204Z,1752818564.204 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0016.lzma.bak
2025-07-18T06:02:44.204Z,1752818564.204 [DataOverHttps](INFO): SBD MOMSN=25544289
2025-07-18T06:03:00.514Z,1752818580.514 [DataOverHttps](INFO): Sending 425 bytes from file Logs/20250718T052352/Express0017.lzma
2025-07-18T06:03:01.516Z,1752818581.516 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0017.lzma.bak
2025-07-18T06:03:01.516Z,1752818581.516 [DataOverHttps](INFO): SBD MOMSN=25544292
2025-07-18T06:03:02.603Z,1752818582.603 [keepstation:NeedComms:D] Stopped
2025-07-18T06:03:02.603Z,1752818582.603 [keepstation:NeedComms:E] Running Loop=1
2025-07-18T06:03:04.607Z,1752818584.607 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,060303.00,A,3647.03441,N,12151.94256,W,0.389,260.01,180725,,,D*7F
2025-07-18T06:03:04.609Z,1752818584.609 [NAL9602](INFO): GPS fix at 20250718T060303: (36.783907, -121.865709)
2025-07-18T06:03:04.660Z,1752818584.660 [keepstation:NeedComms:E] Stopped
2025-07-18T06:03:04.661Z,1752818584.661 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-07-18T06:03:04.661Z,1752818584.661 [keepstation:NeedComms] Stopped
2025-07-18T06:03:04.670Z,1752818584.670 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-07-18T06:03:04.670Z,1752818584.670 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-07-18T06:03:04.670Z,1752818584.670 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T06:03:05.049Z,1752818585.049 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-07-18T06:03:05.049Z,1752818585.049 [keepstation:OnStation] Stopped
2025-07-18T06:03:05.049Z,1752818585.049 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-07-18T06:03:05.049Z,1752818585.049 [keepstation:KeepStation] Running Loop=1
2025-07-18T06:03:05.049Z,1752818585.049 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-07-18T06:03:05.070Z,1752818585.070 [keepstation:KeepStation:Dive] Running Loop=1
2025-07-18T06:03:05.071Z,1752818585.071 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-07-18T06:03:05.071Z,1752818585.071 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-07-18T06:03:05.071Z,1752818585.071 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-07-18T06:03:05.072Z,1752818585.072 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-07-18T06:03:05.072Z,1752818585.072 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-07-18T06:03:05.463Z,1752818585.463 [keepstation:KeepStation:Dive] Running Loop=1
2025-07-18T06:03:36.523Z,1752818616.523 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T06:04:09.645Z,1752818649.645 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-07-18T06:05:19.363Z,1752818719.363 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T06:05:21.372Z,1752818721.372 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.240295
2025-07-18T06:05:54.502Z,1752818754.502 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T06:05:56.510Z,1752818756.510 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-18T06:05:56.822Z,1752818756.822 [Radio_Surface](INFO): Powering down
2025-07-18T06:24:09.967Z,1752819849.967 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T06:24:10.336Z,1752819850.336 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 18.42 mm (1 active estimators).
2025-07-18T06:38:14.347Z,1752820694.347 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T06:49:03.993Z,1752821343.993 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +2 +769.89, -27.19, 62.44, 0.00
2025-07-18T07:03:04.722Z,1752822184.722 [keepstation:SurfaceComms] Running Loop=1
2025-07-18T07:03:04.722Z,1752822184.722 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-07-18T07:03:04.723Z,1752822184.723 [keepstation:SurfaceComms:A] Running Loop=1
2025-07-18T07:03:04.723Z,1752822184.723 [keepstation:SurfaceComms:A] Stopped
2025-07-18T07:03:04.723Z,1752822184.723 [keepstation:SurfaceComms:B] Running Loop=1
2025-07-18T07:03:04.723Z,1752822184.723 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-07-18T07:03:05.110Z,1752822185.110 [keepstation:KeepStation:Dive] Stopped
2025-07-18T07:03:05.110Z,1752822185.110 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-07-18T07:03:05.110Z,1752822185.110 [keepstation:KeepStation:Dive:A] Stopped
2025-07-18T07:03:05.110Z,1752822185.110 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-07-18T07:03:05.111Z,1752822185.111 [keepstation:DiveCmd:StationKeep] Stopped
2025-07-18T07:03:05.111Z,1752822185.111 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-07-18T07:03:05.111Z,1752822185.111 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-07-18T07:03:05.111Z,1752822185.111 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-07-18T07:03:05.111Z,1752822185.111 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-07-18T07:03:05.111Z,1752822185.111 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-07-18T07:03:05.113Z,1752822185.113 [keepstation:NeedComms] Running Loop=1
2025-07-18T07:03:05.113Z,1752822185.113 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-07-18T07:03:05.113Z,1752822185.113 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-07-18T07:03:05.113Z,1752822185.113 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T07:03:05.113Z,1752822185.113 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-18T07:03:05.114Z,1752822185.114 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-18T07:03:05.114Z,1752822185.114 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-18T07:03:05.115Z,1752822185.115 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-18T07:03:05.115Z,1752822185.115 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T07:03:05.115Z,1752822185.115 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T07:03:05.116Z,1752822185.116 [keepstation:NeedComms:A] Running Loop=1
2025-07-18T07:03:05.117Z,1752822185.117 [keepstation:NeedComms:A](INFO): last time_fix was: 1752818583.000000 second since 1970/01/01T00:00:00Z
2025-07-18T07:03:05.117Z,1752822185.117 [keepstation:NeedComms:A] Stopped
2025-07-18T07:05:18.260Z,1752822318.260 [keepstation:NeedComms:C] Running Loop=1
2025-07-18T07:05:19.034Z,1752822319.034 [Radio_Surface](INFO): Powering up
2025-07-18T07:05:25.278Z,1752822325.278 [DataOverHttps](INFO): Radio surface powered ON.
2025-07-18T07:05:51.032Z,1752822351.032 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-18T07:05:51.106Z,1752822351.106 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-07-18T07:05:55.398Z,1752822355.398 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T07:06:30.544Z,1752822390.544 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T07:07:05.690Z,1752822425.690 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T07:07:40.830Z,1752822460.830 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T07:08:02.631Z,1752822482.631 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003139
2025-07-18T07:08:39.868Z,1752822519.868 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for water velocity. Device response is::WS, -40, +142, -1WE, -40, +142, -160,A
2025-07-18T07:09:19.854Z,1752822559.854 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +387.14, +760. -22.25, 104.06, 0.00
2025-07-18T07:10:22.453Z,1752822622.453 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T07:10:23.666Z,1752822623.666 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,071022.00,A,3646.98834,N,12151.86305,W,0.544,290.19,180725,,,D*77
2025-07-18T07:10:23.669Z,1752822623.669 [NAL9602](INFO): GPS fix at 20250718T071022: (36.783139, -121.864384)
2025-07-18T07:10:23.698Z,1752822623.698 [UniversalFixResidualReporter](INFO): Fix residual: 0.8 %DT, over the last 1101.4 m. Residual distance 8.9 m at bearing -103.3 degrees. Fix at (36.7831, -121.8644) with 145.7 m made good.
2025-07-18T07:10:23.701Z,1752822623.701 [keepstation:NeedComms:C] Stopped
2025-07-18T07:10:23.701Z,1752822623.701 [keepstation:NeedComms:D] Running Loop=1
2025-07-18T07:10:31.077Z,1752822631.077 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20250718T052352/Courier0019.lzma
2025-07-18T07:10:32.080Z,1752822632.080 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0019.lzma.bak
2025-07-18T07:10:32.080Z,1752822632.080 [DataOverHttps](INFO): SBD MOMSN=25544395
2025-07-18T07:10:48.354Z,1752822648.354 [DataOverHttps](INFO): Sending 373 bytes from file Logs/20250718T052352/Express0020.lzma
2025-07-18T07:10:49.356Z,1752822649.356 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0020.lzma.bak
2025-07-18T07:10:49.356Z,1752822649.356 [DataOverHttps](INFO): SBD MOMSN=25544398
2025-07-18T07:10:50.776Z,1752822650.776 [keepstation:NeedComms:D] Stopped
2025-07-18T07:10:50.776Z,1752822650.776 [keepstation:NeedComms:E] Running Loop=1
2025-07-18T07:10:52.908Z,1752822652.908 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,071052.00,A,3646.98944,N,12151.86571,W,0.467,312.21,180725,,,D*73
2025-07-18T07:10:52.920Z,1752822652.920 [NAL9602](INFO): GPS fix at 20250718T071052: (36.783157, -121.864429)
2025-07-18T07:10:52.950Z,1752822652.950 [keepstation:NeedComms:E] Stopped
2025-07-18T07:10:52.951Z,1752822652.951 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-07-18T07:10:52.951Z,1752822652.951 [keepstation:NeedComms] Stopped
2025-07-18T07:10:52.952Z,1752822652.952 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-07-18T07:10:52.952Z,1752822652.952 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-07-18T07:10:52.952Z,1752822652.952 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T07:10:53.264Z,1752822653.264 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-07-18T07:10:53.264Z,1752822653.264 [keepstation:SurfaceComms:B] Stopped
2025-07-18T07:10:53.264Z,1752822653.264 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-07-18T07:10:53.264Z,1752822653.264 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-07-18T07:10:53.264Z,1752822653.264 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-07-18T07:10:53.264Z,1752822653.264 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-07-18T07:10:53.639Z,1752822653.639 [keepstation:SurfaceComms:setTransit] Stopped
2025-07-18T07:10:53.639Z,1752822653.639 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-07-18T07:10:53.639Z,1752822653.639 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-07-18T07:10:53.639Z,1752822653.639 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-07-18T07:10:53.639Z,1752822653.639 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-07-18T07:10:53.639Z,1752822653.639 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-07-18T07:10:54.085Z,1752822654.085 [keepstation:SurfaceComms:setStation:A] Stopped
2025-07-18T07:10:54.085Z,1752822654.085 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-07-18T07:10:54.085Z,1752822654.085 [keepstation:SurfaceComms:setStation] Stopped
2025-07-18T07:10:54.085Z,1752822654.085 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-07-18T07:10:54.085Z,1752822654.085 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-07-18T07:10:54.090Z,1752822654.090 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-07-18T07:10:54.090Z,1752822654.090 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-07-18T07:10:54.423Z,1752822654.423 [keepstation:KeepStation:Dive] Running Loop=1
2025-07-18T07:10:54.423Z,1752822654.423 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-07-18T07:10:54.424Z,1752822654.424 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-07-18T07:10:54.424Z,1752822654.424 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-07-18T07:10:54.424Z,1752822654.424 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-07-18T07:10:54.424Z,1752822654.424 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-07-18T07:10:54.424Z,1752822654.424 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-07-18T07:10:54.424Z,1752822654.424 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-07-18T07:10:54.424Z,1752822654.424 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-07-18T07:10:54.425Z,1752822654.425 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-07-18T07:10:54.425Z,1752822654.425 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-07-18T07:10:54.451Z,1752822654.451 [keepstation:SurfaceComms:setSink] Stopped
2025-07-18T07:10:54.451Z,1752822654.451 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-07-18T07:10:54.451Z,1752822654.451 [keepstation:SurfaceComms:setSink:A] Stopped
2025-07-18T07:10:54.451Z,1752822654.451 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-07-18T07:10:54.451Z,1752822654.451 [keepstation:SurfaceComms] Stopped
2025-07-18T07:10:54.451Z,1752822654.451 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-07-18T07:11:25.510Z,1752822685.510 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T07:14:22.084Z,1752822862.084 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +236.06, , 20.00, 0.00
2025-07-18T07:14:53.502Z,1752822893.502 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T07:14:55.510Z,1752822895.510 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239292
2025-07-18T07:15:28.650Z,1752822928.650 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T07:15:34.845Z,1752822934.845 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-18T07:15:35.538Z,1752822935.538 [Radio_Surface](INFO): Powering down
2025-07-18T07:15:48.129Z,1752822948.129 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for beam range. Device response is::RA, 0.00,1170.74
2025-07-18T07:16:03.810Z,1752822963.810 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T07:16:04.814Z,1752822964.814 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-18T07:27:19.793Z,1752823639.793 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T07:27:19.793Z,1752823639.793 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T07:27:19.794Z,1752823639.794 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-18T07:27:19.795Z,1752823639.795 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T07:27:19.796Z,1752823639.796 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T07:27:19.797Z,1752823639.797 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T07:27:19.797Z,1752823639.797 [BPC1](INFO): Calculating totals. Valid battery stick count: 33. Valid reserve battery stick count: 2.
2025-07-18T07:27:19.800Z,1752823639.800 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-07-18T07:36:25.197Z,1752824185.197 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T07:48:01.788Z,1752824881.788 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012311345006,359, 0.0,1496.8, 0
2025-07-18T07:48:21.950Z,1752824901.950 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for water velocity. Device response is::WS, +22 +49,A
2025-07-18T07:48:41.804Z,1752824921.804 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +222.38, +1675.2576.40, 20.00, 0.00
2025-07-18T07:48:56.706Z,1752824936.706 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T08:06:57.800Z,1752826017.800 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012311534606,35.0 0.0,1496.3, 0
2025-07-18T08:07:29.806Z,1752826049.806 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +369.46, +895.48, -5700
2025-07-18T08:08:08.116Z,1752826088.116 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, -124, -205, :BE, -124, -205, +46,A
2025-07-18T08:08:17.817Z,1752826097.817 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +365 +889.41, -55.87, 81.70, 0.00
2025-07-18T08:10:52.976Z,1752826252.976 [keepstation:SurfaceComms] Running Loop=1
2025-07-18T08:10:52.976Z,1752826252.976 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-07-18T08:10:52.977Z,1752826252.977 [keepstation:SurfaceComms:A] Running Loop=1
2025-07-18T08:10:52.977Z,1752826252.977 [keepstation:SurfaceComms:A] Stopped
2025-07-18T08:10:52.977Z,1752826252.977 [keepstation:SurfaceComms:B] Running Loop=1
2025-07-18T08:10:52.977Z,1752826252.977 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-07-18T08:10:52.983Z,1752826252.983 [keepstation:NeedComms] Running Loop=1
2025-07-18T08:10:52.984Z,1752826252.984 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-07-18T08:10:52.984Z,1752826252.984 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-07-18T08:10:52.984Z,1752826252.984 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T08:10:52.984Z,1752826252.984 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-18T08:10:52.985Z,1752826252.985 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-18T08:10:52.985Z,1752826252.985 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-18T08:10:52.985Z,1752826252.985 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-18T08:10:52.990Z,1752826252.990 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T08:10:52.990Z,1752826252.990 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T08:10:52.990Z,1752826252.990 [keepstation:NeedComms:A] Running Loop=1
2025-07-18T08:10:52.992Z,1752826252.992 [keepstation:NeedComms:A](INFO): last time_fix was: 1752822652.000000 second since 1970/01/01T00:00:00Z
2025-07-18T08:10:52.992Z,1752826252.992 [keepstation:NeedComms:A] Stopped
2025-07-18T08:10:53.359Z,1752826253.359 [keepstation:KeepStation:Dive] Stopped
2025-07-18T08:10:53.359Z,1752826253.359 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-07-18T08:10:53.359Z,1752826253.359 [keepstation:KeepStation:Dive:A] Stopped
2025-07-18T08:10:53.359Z,1752826253.359 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-07-18T08:10:53.360Z,1752826253.360 [keepstation:DiveCmd:StationKeep] Stopped
2025-07-18T08:10:53.361Z,1752826253.361 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-07-18T08:10:53.361Z,1752826253.361 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-07-18T08:10:53.361Z,1752826253.361 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-07-18T08:10:53.361Z,1752826253.361 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-07-18T08:10:53.362Z,1752826253.362 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-07-18T08:13:04.829Z,1752826384.829 [keepstation:NeedComms:C] Running Loop=1
2025-07-18T08:13:05.314Z,1752826385.314 [Radio_Surface](INFO): Powering up
2025-07-18T08:13:11.126Z,1752826391.126 [DataOverHttps](INFO): Radio surface powered ON.
2025-07-18T08:13:37.537Z,1752826417.537 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-18T08:13:37.610Z,1752826417.610 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-07-18T08:13:41.256Z,1752826421.256 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T08:14:16.394Z,1752826456.394 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T08:14:51.534Z,1752826491.534 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T08:15:26.675Z,1752826526.675 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T08:15:48.375Z,1752826548.375 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003137
2025-07-18T08:18:09.423Z,1752826689.423 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T08:18:10.640Z,1752826690.640 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081809.00,A,3646.98590,N,12152.01015,W,0.389,293.92,180725,,,A*76
2025-07-18T08:18:10.643Z,1752826690.643 [NAL9602](INFO): GPS fix at 20250718T081809: (36.783098, -121.866836)
2025-07-18T08:18:10.656Z,1752826690.656 [UniversalFixResidualReporter](INFO): Fix residual: 0.7 %DT, over the last 1017.4 m. Residual distance 7.3 m at bearing 82.1 degrees. Fix at (36.7831, -121.8668) with 214.5 m made good.
2025-07-18T08:18:10.659Z,1752826690.659 [keepstation:NeedComms:C] Stopped
2025-07-18T08:18:10.660Z,1752826690.660 [keepstation:NeedComms:D] Running Loop=1
2025-07-18T08:18:18.437Z,1752826698.437 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20250718T052352/Courier0022.lzma
2025-07-18T08:18:19.440Z,1752826699.440 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0022.lzma.bak
2025-07-18T08:18:19.440Z,1752826699.440 [DataOverHttps](INFO): SBD MOMSN=25544483
2025-07-18T08:18:35.803Z,1752826715.803 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20250718T052352/Express0023.lzma
2025-07-18T08:18:36.804Z,1752826716.804 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0023.lzma.bak
2025-07-18T08:18:36.804Z,1752826716.804 [DataOverHttps](INFO): SBD MOMSN=25544486
2025-07-18T08:18:38.149Z,1752826718.149 [keepstation:NeedComms:D] Stopped
2025-07-18T08:18:38.149Z,1752826718.149 [keepstation:NeedComms:E] Running Loop=1
2025-07-18T08:18:40.133Z,1752826720.133 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081839.00,A,3646.98668,N,12152.01377,W,0.156,265.62,180725,,,A*70
2025-07-18T08:18:40.135Z,1752826720.135 [NAL9602](INFO): GPS fix at 20250718T081839: (36.783111, -121.866896)
2025-07-18T08:18:40.181Z,1752826720.181 [keepstation:NeedComms:E] Stopped
2025-07-18T08:18:40.186Z,1752826720.186 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-07-18T08:18:40.186Z,1752826720.186 [keepstation:NeedComms] Stopped
2025-07-18T08:18:40.187Z,1752826720.187 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-07-18T08:18:40.187Z,1752826720.187 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-07-18T08:18:40.187Z,1752826720.187 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T08:18:40.571Z,1752826720.571 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-07-18T08:18:40.571Z,1752826720.571 [keepstation:SurfaceComms:B] Stopped
2025-07-18T08:18:40.571Z,1752826720.571 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-07-18T08:18:40.571Z,1752826720.571 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-07-18T08:18:40.571Z,1752826720.571 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-07-18T08:18:40.571Z,1752826720.571 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-07-18T08:18:40.964Z,1752826720.964 [keepstation:SurfaceComms:setTransit] Stopped
2025-07-18T08:18:40.964Z,1752826720.964 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-07-18T08:18:40.964Z,1752826720.964 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-07-18T08:18:40.964Z,1752826720.964 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-07-18T08:18:40.964Z,1752826720.964 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-07-18T08:18:40.964Z,1752826720.964 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-07-18T08:18:41.379Z,1752826721.379 [keepstation:SurfaceComms:setStation:A] Stopped
2025-07-18T08:18:41.379Z,1752826721.379 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-07-18T08:18:41.379Z,1752826721.379 [keepstation:SurfaceComms:setStation] Stopped
2025-07-18T08:18:41.379Z,1752826721.379 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-07-18T08:18:41.380Z,1752826721.380 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-07-18T08:18:41.380Z,1752826721.380 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-07-18T08:18:41.380Z,1752826721.380 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-07-18T08:18:41.867Z,1752826721.867 [keepstation:KeepStation:Dive] Running Loop=1
2025-07-18T08:18:41.867Z,1752826721.867 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-07-18T08:18:41.867Z,1752826721.867 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-07-18T08:18:41.867Z,1752826721.867 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-07-18T08:18:41.868Z,1752826721.868 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-07-18T08:18:41.868Z,1752826721.868 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-07-18T08:18:41.868Z,1752826721.868 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-07-18T08:18:41.868Z,1752826721.868 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-07-18T08:18:41.868Z,1752826721.868 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-07-18T08:18:41.868Z,1752826721.868 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-07-18T08:18:41.868Z,1752826721.868 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-07-18T08:18:41.903Z,1752826721.903 [keepstation:SurfaceComms:setSink] Stopped
2025-07-18T08:18:41.903Z,1752826721.903 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-07-18T08:18:41.903Z,1752826721.903 [keepstation:SurfaceComms:setSink:A] Stopped
2025-07-18T08:18:41.903Z,1752826721.903 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-07-18T08:18:41.903Z,1752826721.903 [keepstation:SurfaceComms] Stopped
2025-07-18T08:18:41.903Z,1752826721.903 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-07-18T08:19:12.447Z,1752826752.447 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T08:20:36.905Z,1752826836.905 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T08:20:53.926Z,1752826853.926 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T08:20:56.938Z,1752826856.938 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239334
2025-07-18T08:21:29.066Z,1752826889.066 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T08:21:34.818Z,1752826894.818 [Radio_Surface](INFO): Powering down
2025-07-18T08:22:04.218Z,1752826924.218 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T08:22:05.222Z,1752826925.222 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-18T08:30:30.830Z,1752827430.830 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T08:33:06.406Z,1752827586.406 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T08:33:24.154Z,1752827604.154 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +372.15, +1532.82, , 84.73, 0.00
2025-07-18T08:35:39.481Z,1752827739.481 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T08:45:21.680Z,1752828321.680 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T08:45:44.756Z,1752828344.756 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T08:51:10.362Z,1752828670.362 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD362.86, +1938.88, -64.92, 84.49, 0.00
2025-07-18T08:51:30.158Z,1752828690.158 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +364.65, , -65.29, 83.72, 0.00
2025-07-18T08:51:50.350Z,1752828710.350 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, +96, -61A
2025-07-18T08:56:10.104Z,1752828970.104 [BPC1](ERROR): BPC1A: No match for serial number FFFF in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-07-18T08:58:20.999Z,1752829100.999 [BPC1](FAULT): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T08:58:20.000Z,1752829101.000 [BPC1](FAULT): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T08:58:21.000Z,1752829101.000 [BPC1](FAULT): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-18T08:58:21.001Z,1752829101.001 [BPC1](FAULT): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T08:58:21.002Z,1752829101.002 [BPC1](FAULT): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T08:58:21.003Z,1752829101.003 [BPC1](FAULT): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T08:58:21.003Z,1752829101.003 [BPC1](INFO): Calculating totals. Valid battery stick count: 31. Valid reserve battery stick count: 2.
2025-07-18T08:58:21.006Z,1752829101.006 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-07-18T09:11:26.053Z,1752829886.053 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for bottom velocity. Device response is::BS, -105 -36,A
2025-07-18T09:18:40.328Z,1752830320.328 [keepstation:SurfaceComms] Running Loop=1
2025-07-18T09:18:40.328Z,1752830320.328 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-07-18T09:18:40.329Z,1752830320.329 [keepstation:SurfaceComms:A] Running Loop=1
2025-07-18T09:18:40.329Z,1752830320.329 [keepstation:SurfaceComms:A] Stopped
2025-07-18T09:18:40.329Z,1752830320.329 [keepstation:SurfaceComms:B] Running Loop=1
2025-07-18T09:18:40.329Z,1752830320.329 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-07-18T09:18:40.331Z,1752830320.331 [keepstation:NeedComms] Running Loop=1
2025-07-18T09:18:40.332Z,1752830320.332 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-07-18T09:18:40.332Z,1752830320.332 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-07-18T09:18:40.332Z,1752830320.332 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T09:18:40.332Z,1752830320.332 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-18T09:18:40.332Z,1752830320.332 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-18T09:18:40.333Z,1752830320.333 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-18T09:18:40.333Z,1752830320.333 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-18T09:18:40.333Z,1752830320.333 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T09:18:40.342Z,1752830320.342 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T09:18:40.342Z,1752830320.342 [keepstation:NeedComms:A] Running Loop=1
2025-07-18T09:18:40.344Z,1752830320.344 [keepstation:NeedComms:A](INFO): last time_fix was: 1752826719.000000 second since 1970/01/01T00:00:00Z
2025-07-18T09:18:40.344Z,1752830320.344 [keepstation:NeedComms:A] Stopped
2025-07-18T09:18:40.695Z,1752830320.695 [keepstation:KeepStation:Dive] Stopped
2025-07-18T09:18:40.695Z,1752830320.695 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-07-18T09:18:40.695Z,1752830320.695 [keepstation:KeepStation:Dive:A] Stopped
2025-07-18T09:18:40.695Z,1752830320.695 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-07-18T09:18:40.695Z,1752830320.695 [keepstation:DiveCmd:StationKeep] Stopped
2025-07-18T09:18:40.696Z,1752830320.696 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-07-18T09:18:40.696Z,1752830320.696 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-07-18T09:18:40.696Z,1752830320.696 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-07-18T09:18:40.696Z,1752830320.696 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-07-18T09:18:40.696Z,1752830320.696 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-07-18T09:20:46.553Z,1752830446.553 [keepstation:NeedComms:C] Running Loop=1
2025-07-18T09:20:47.026Z,1752830447.026 [Radio_Surface](INFO): Powering up
2025-07-18T09:20:52.750Z,1752830452.750 [DataOverHttps](INFO): Radio surface powered ON.
2025-07-18T09:21:19.291Z,1752830479.291 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-18T09:21:19.370Z,1752830479.370 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-07-18T09:21:22.870Z,1752830482.870 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T09:21:58.019Z,1752830518.019 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T09:22:33.158Z,1752830553.158 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T09:23:08.302Z,1752830588.302 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T09:23:30.098Z,1752830610.098 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003143
2025-07-18T09:25:50.371Z,1752830750.371 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T09:25:56.032Z,1752830756.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,092555.00,A,3646.92320,N,12151.98477,W,0.292,273.93,180725,,,D*75
2025-07-18T09:25:56.035Z,1752830756.035 [NAL9602](INFO): GPS fix at 20250718T092555: (36.782053, -121.866413)
2025-07-18T09:25:56.082Z,1752830756.082 [UniversalFixResidualReporter](INFO): Fix residual: 0.9 %DT, over the last 1335.7 m. Residual distance 12.0 m at bearing 9.8 degrees. Fix at (36.7821, -121.8664) with 125.3 m made good.
2025-07-18T09:25:56.085Z,1752830756.085 [keepstation:NeedComms:C] Stopped
2025-07-18T09:25:56.085Z,1752830756.085 [keepstation:NeedComms:D] Running Loop=1
2025-07-18T09:26:03.641Z,1752830763.641 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20250718T052352/Courier0025.lzma
2025-07-18T09:26:04.644Z,1752830764.644 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0025.lzma.bak
2025-07-18T09:26:04.644Z,1752830764.644 [DataOverHttps](INFO): SBD MOMSN=25544571
2025-07-18T09:26:21.071Z,1752830781.071 [DataOverHttps](INFO): Sending 543 bytes from file Logs/20250718T052352/Express0026.lzma
2025-07-18T09:26:22.072Z,1752830782.072 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0026.lzma.bak
2025-07-18T09:26:22.072Z,1752830782.072 [DataOverHttps](INFO): SBD MOMSN=25544574
2025-07-18T09:26:23.142Z,1752830783.142 [keepstation:NeedComms:D] Stopped
2025-07-18T09:26:23.142Z,1752830783.142 [keepstation:NeedComms:E] Running Loop=1
2025-07-18T09:26:25.140Z,1752830785.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,092624.00,A,3646.92407,N,12151.98835,W,0.544,285.31,180725,,,D*75
2025-07-18T09:26:25.147Z,1752830785.147 [NAL9602](INFO): GPS fix at 20250718T092624: (36.782068, -121.866473)
2025-07-18T09:26:25.177Z,1752830785.177 [keepstation:NeedComms:E] Stopped
2025-07-18T09:26:25.187Z,1752830785.187 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-07-18T09:26:25.187Z,1752830785.187 [keepstation:NeedComms] Stopped
2025-07-18T09:26:25.188Z,1752830785.188 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-07-18T09:26:25.188Z,1752830785.188 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-07-18T09:26:25.188Z,1752830785.188 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T09:26:25.600Z,1752830785.600 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-07-18T09:26:25.600Z,1752830785.600 [keepstation:SurfaceComms:B] Stopped
2025-07-18T09:26:25.601Z,1752830785.601 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-07-18T09:26:25.601Z,1752830785.601 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-07-18T09:26:25.601Z,1752830785.601 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-07-18T09:26:25.601Z,1752830785.601 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-07-18T09:26:26.035Z,1752830786.035 [keepstation:SurfaceComms:setTransit] Stopped
2025-07-18T09:26:26.035Z,1752830786.035 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-07-18T09:26:26.035Z,1752830786.035 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-07-18T09:26:26.035Z,1752830786.035 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-07-18T09:26:26.035Z,1752830786.035 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-07-18T09:26:26.036Z,1752830786.036 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-07-18T09:26:26.428Z,1752830786.428 [keepstation:SurfaceComms:setStation:A] Stopped
2025-07-18T09:26:26.428Z,1752830786.428 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-07-18T09:26:26.428Z,1752830786.428 [keepstation:SurfaceComms:setStation] Stopped
2025-07-18T09:26:26.428Z,1752830786.428 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-07-18T09:26:26.428Z,1752830786.428 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-07-18T09:26:26.428Z,1752830786.428 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-07-18T09:26:26.428Z,1752830786.428 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-07-18T09:26:26.819Z,1752830786.819 [keepstation:KeepStation:Dive] Running Loop=1
2025-07-18T09:26:26.819Z,1752830786.819 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-07-18T09:26:26.819Z,1752830786.819 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-07-18T09:26:26.819Z,1752830786.819 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-07-18T09:26:26.820Z,1752830786.820 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-07-18T09:26:26.820Z,1752830786.820 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-07-18T09:26:26.820Z,1752830786.820 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-07-18T09:26:26.820Z,1752830786.820 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-07-18T09:26:26.820Z,1752830786.820 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-07-18T09:26:26.820Z,1752830786.820 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-07-18T09:26:26.820Z,1752830786.820 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-07-18T09:26:26.821Z,1752830786.821 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T09:26:26.835Z,1752830786.835 [keepstation:SurfaceComms:setSink] Stopped
2025-07-18T09:26:26.835Z,1752830786.835 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-07-18T09:26:26.835Z,1752830786.835 [keepstation:SurfaceComms:setSink:A] Stopped
2025-07-18T09:26:26.835Z,1752830786.835 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-07-18T09:26:26.836Z,1752830786.836 [keepstation:SurfaceComms] Stopped
2025-07-18T09:26:26.836Z,1752830786.836 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-07-18T09:26:57.481Z,1752830817.481 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T09:28:38.810Z,1752830918.810 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T09:28:41.822Z,1752830921.822 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.275208
2025-07-18T09:29:04.042Z,1752830944.042 [Radio_Surface](INFO): Powering down
2025-07-18T09:29:13.955Z,1752830953.955 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T09:29:14.962Z,1752830954.962 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-18T09:30:02.331Z,1752831002.331 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 879.40, 952.89, 753.82
2025-07-18T09:37:50.581Z,1752831470.581 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T09:50:14.329Z,1752832214.329 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012313370236
2025-07-18T10:10:14.218Z,1752833414.218 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 659.05, 572.59, 665.13
2025-07-18T10:10:48.181Z,1752833448.181 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T10:22:02.901Z,1752834122.901 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T10:26:25.501Z,1752834385.501 [keepstation:SurfaceComms] Running Loop=1
2025-07-18T10:26:25.502Z,1752834385.502 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-07-18T10:26:25.502Z,1752834385.502 [keepstation:SurfaceComms:A] Running Loop=1
2025-07-18T10:26:25.502Z,1752834385.502 [keepstation:SurfaceComms:A] Stopped
2025-07-18T10:26:25.503Z,1752834385.503 [keepstation:SurfaceComms:B] Running Loop=1
2025-07-18T10:26:25.503Z,1752834385.503 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-07-18T10:26:26.055Z,1752834386.055 [keepstation:KeepStation:Dive] Stopped
2025-07-18T10:26:26.055Z,1752834386.055 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-07-18T10:26:26.055Z,1752834386.055 [keepstation:KeepStation:Dive:A] Stopped
2025-07-18T10:26:26.055Z,1752834386.055 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-07-18T10:26:26.055Z,1752834386.055 [keepstation:DiveCmd:StationKeep] Stopped
2025-07-18T10:26:26.056Z,1752834386.056 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-07-18T10:26:26.056Z,1752834386.056 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-07-18T10:26:26.056Z,1752834386.056 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-07-18T10:26:26.056Z,1752834386.056 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-07-18T10:26:26.056Z,1752834386.056 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-07-18T10:26:26.057Z,1752834386.057 [keepstation:NeedComms] Running Loop=1
2025-07-18T10:26:26.058Z,1752834386.058 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-07-18T10:26:26.058Z,1752834386.058 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-07-18T10:26:26.058Z,1752834386.058 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T10:26:26.058Z,1752834386.058 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-18T10:26:26.059Z,1752834386.059 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-18T10:26:26.059Z,1752834386.059 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-18T10:26:26.059Z,1752834386.059 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-18T10:26:26.060Z,1752834386.060 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T10:26:26.060Z,1752834386.060 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T10:26:26.060Z,1752834386.060 [keepstation:NeedComms:A] Running Loop=1
2025-07-18T10:26:26.062Z,1752834386.062 [keepstation:NeedComms:A](INFO): last time_fix was: 1752830784.000000 second since 1970/01/01T00:00:00Z
2025-07-18T10:26:26.062Z,1752834386.062 [keepstation:NeedComms:A] Stopped
2025-07-18T10:28:32.576Z,1752834512.576 [keepstation:NeedComms:C] Running Loop=1
2025-07-18T10:28:33.050Z,1752834513.050 [Radio_Surface](INFO): Powering up
2025-07-18T10:28:38.878Z,1752834518.878 [DataOverHttps](INFO): Radio surface powered ON.
2025-07-18T10:29:00.189Z,1752834540.189 [RDI_Pathfinder](ERROR): only read -1 of 1 data item for altitude. Device response is::BD, +372.74, +2559.76,
2025-07-18T10:29:05.359Z,1752834545.359 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-18T10:29:05.442Z,1752834545.442 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-07-18T10:29:09.002Z,1752834549.002 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T10:29:21.973Z,1752834561.973 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T10:29:21.974Z,1752834561.974 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T10:29:21.974Z,1752834561.974 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-18T10:29:21.975Z,1752834561.975 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T10:29:21.976Z,1752834561.976 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T10:29:21.977Z,1752834561.977 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T10:29:21.977Z,1752834561.977 [BPC1](INFO): Calculating totals. Valid battery stick count: 21. Valid reserve battery stick count: 2.
2025-07-18T10:29:21.981Z,1752834561.981 [BPC1](FAULT): Failed to receive data from 6 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 17, 18, 24, 26, 27, 58.
2025-07-18T10:29:44.159Z,1752834584.159 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T10:30:19.290Z,1752834619.290 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T10:30:54.431Z,1752834654.431 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T10:31:16.162Z,1752834676.162 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003047
2025-07-18T10:33:37.243Z,1752834817.243 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T10:33:38.456Z,1752834818.456 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,103337.00,A,3647.11896,N,12152.01561,W,0.350,289.86,180725,,,D*79
2025-07-18T10:33:38.460Z,1752834818.460 [NAL9602](INFO): GPS fix at 20250718T103337: (36.785316, -121.866927)
2025-07-18T10:33:38.502Z,1752834818.502 [UniversalFixResidualReporter](INFO): Fix residual: 2.0 %DT, over the last 1280.8 m. Residual distance 25.0 m at bearing -48.6 degrees. Fix at (36.7853, -121.8669) with 363.4 m made good.
2025-07-18T10:33:38.505Z,1752834818.505 [keepstation:NeedComms:C] Stopped
2025-07-18T10:33:38.505Z,1752834818.505 [keepstation:NeedComms:D] Running Loop=1
2025-07-18T10:33:46.137Z,1752834826.137 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20250718T052352/Courier0028.lzma
2025-07-18T10:33:47.140Z,1752834827.140 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0028.lzma.bak
2025-07-18T10:33:47.140Z,1752834827.140 [DataOverHttps](INFO): SBD MOMSN=25544700
2025-07-18T10:34:03.519Z,1752834843.519 [DataOverHttps](INFO): Sending 481 bytes from file Logs/20250718T052352/Express0029.lzma
2025-07-18T10:34:04.520Z,1752834844.520 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0029.lzma.bak
2025-07-18T10:34:04.520Z,1752834844.520 [DataOverHttps](INFO): SBD MOMSN=25544703
2025-07-18T10:34:05.986Z,1752834845.986 [keepstation:NeedComms:D] Stopped
2025-07-18T10:34:05.986Z,1752834845.986 [keepstation:NeedComms:E] Running Loop=1
2025-07-18T10:34:07.956Z,1752834847.956 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,103407.00,A,3647.12020,N,12152.01974,W,0.330,285.34,180725,,,D*70
2025-07-18T10:34:07.959Z,1752834847.959 [NAL9602](INFO): GPS fix at 20250718T103407: (36.785337, -121.866996)
2025-07-18T10:34:08.057Z,1752834848.057 [keepstation:NeedComms:E] Stopped
2025-07-18T10:34:08.067Z,1752834848.067 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-07-18T10:34:08.067Z,1752834848.067 [keepstation:NeedComms] Stopped
2025-07-18T10:34:08.067Z,1752834848.067 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-07-18T10:34:08.067Z,1752834848.067 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-07-18T10:34:08.067Z,1752834848.067 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T10:34:08.388Z,1752834848.388 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-07-18T10:34:08.388Z,1752834848.388 [keepstation:SurfaceComms:B] Stopped
2025-07-18T10:34:08.388Z,1752834848.388 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-07-18T10:34:08.388Z,1752834848.388 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-07-18T10:34:08.388Z,1752834848.388 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-07-18T10:34:08.388Z,1752834848.388 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-07-18T10:34:08.792Z,1752834848.792 [keepstation:SurfaceComms:setTransit] Stopped
2025-07-18T10:34:08.792Z,1752834848.792 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-07-18T10:34:08.792Z,1752834848.792 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-07-18T10:34:08.792Z,1752834848.792 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-07-18T10:34:08.792Z,1752834848.792 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-07-18T10:34:08.792Z,1752834848.792 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-07-18T10:34:09.194Z,1752834849.194 [keepstation:SurfaceComms:setStation:A] Stopped
2025-07-18T10:34:09.194Z,1752834849.194 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-07-18T10:34:09.194Z,1752834849.194 [keepstation:SurfaceComms:setStation] Stopped
2025-07-18T10:34:09.194Z,1752834849.194 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-07-18T10:34:09.194Z,1752834849.194 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-07-18T10:34:09.194Z,1752834849.194 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-07-18T10:34:09.194Z,1752834849.194 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-07-18T10:34:09.590Z,1752834849.590 [keepstation:KeepStation:Dive] Running Loop=1
2025-07-18T10:34:09.591Z,1752834849.591 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-07-18T10:34:09.591Z,1752834849.591 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-07-18T10:34:09.591Z,1752834849.591 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-07-18T10:34:09.591Z,1752834849.591 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-07-18T10:34:09.592Z,1752834849.592 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-07-18T10:34:09.592Z,1752834849.592 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-07-18T10:34:09.592Z,1752834849.592 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-07-18T10:34:09.592Z,1752834849.592 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-07-18T10:34:09.592Z,1752834849.592 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-07-18T10:34:09.592Z,1752834849.592 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-07-18T10:34:09.593Z,1752834849.593 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T10:34:09.619Z,1752834849.619 [keepstation:SurfaceComms:setSink] Stopped
2025-07-18T10:34:09.619Z,1752834849.619 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-07-18T10:34:09.619Z,1752834849.619 [keepstation:SurfaceComms:setSink:A] Stopped
2025-07-18T10:34:09.619Z,1752834849.619 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-07-18T10:34:09.619Z,1752834849.619 [keepstation:SurfaceComms] Stopped
2025-07-18T10:34:09.620Z,1752834849.620 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-07-18T10:34:40.671Z,1752834880.671 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T10:36:21.434Z,1752834981.434 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T10:36:24.446Z,1752834984.446 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.263351
2025-07-18T10:36:33.542Z,1752834993.542 [Radio_Surface](INFO): Powering down
2025-07-18T10:36:50.338Z,1752835010.338 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012314233865,35.0, +14.41505.0, 0
2025-07-18T10:36:56.578Z,1752835016.578 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T10:36:57.582Z,1752835017.582 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-18T10:42:20.810Z,1752835340.810 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2025-07-18T10:42:20.810Z,1752835340.810 [RDI_Pathfinder] Communications Fault, FailCount= 1
2025-07-18T10:42:20.810Z,1752835340.810 [RDI_Pathfinder](ERROR): Communications Fault
2025-07-18T10:42:20.873Z,1752835340.873 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-07-18T10:42:21.302Z,1752835341.302 [RDI_Pathfinder](INFO): Powering down
2025-07-18T10:42:22.102Z,1752835342.102 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-07-18T10:42:22.102Z,1752835342.102 [RDI_Pathfinder] No Fault, FailCount= 1
2025-07-18T10:42:22.408Z,1752835342.408 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-07-18T10:47:08.480Z,1752835628.480 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T10:47:08.867Z,1752835628.867 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 18.42 mm.
2025-07-18T10:53:07.243Z,1752835987.243 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T10:53:07.627Z,1752835987.627 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 18.42 mm (1 active estimators).
2025-07-18T11:05:12.454Z,1752836712.454 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T11:10:23.931Z,1752837023.931 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T11:10:23.932Z,1752837023.932 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T11:10:23.932Z,1752837023.932 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-18T11:10:23.933Z,1752837023.933 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T11:10:23.934Z,1752837023.934 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T11:10:23.935Z,1752837023.935 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T11:10:23.935Z,1752837023.935 [BPC1](INFO): Calculating totals. Valid battery stick count: 30. Valid reserve battery stick count: 2.
2025-07-18T11:10:23.949Z,1752837023.949 [BPC1](FAULT): Failed to receive data from 6 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 17, 18, 24, 26, 27, 58.
2025-07-18T11:24:04.470Z,1752837844.470 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-18T11:24:08.987Z,1752837848.987 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003820
CHAN A1 (24V): -0.001630
CHAN A2 (12V): -0.000096
CHAN A3 (5V): 0.000329
CHAN B0 (3.3V): 0.000834
CHAN B1 (3.15aV): 0.000074
CHAN B2 (3.15bV): 0.000391
CHAN B3 (GND): 0.000355
OPEN: 0.005230
Full Scale: +/- 1 mA
2025-07-18T11:27:49.067Z,1752838069.067 [BPC1](ERROR): Failed to receive data from all battery packs.
2025-07-18T11:27:49.067Z,1752838069.067 [BPC1] Communications Fault, FailCount= 1
2025-07-18T11:27:49.067Z,1752838069.067 [BPC1](ERROR): Communications Fault
2025-07-18T11:27:49.113Z,1752838069.113 [CBIT](ERROR): Communications Fault in component: BPC1
2025-07-18T11:28:12.115Z,1752838092.115 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T11:30:03.270Z,1752838203.270 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012315165138,35.0, +11.,1495.1, 0
2025-07-18T11:34:08.153Z,1752838448.153 [keepstation:SurfaceComms] Running Loop=1
2025-07-18T11:34:08.153Z,1752838448.153 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-07-18T11:34:08.153Z,1752838448.153 [keepstation:SurfaceComms:A] Running Loop=1
2025-07-18T11:34:08.153Z,1752838448.153 [keepstation:SurfaceComms:A] Stopped
2025-07-18T11:34:08.166Z,1752838448.166 [keepstation:SurfaceComms:B] Running Loop=1
2025-07-18T11:34:08.166Z,1752838448.166 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-07-18T11:34:08.167Z,1752838448.167 [keepstation:NeedComms] Running Loop=1
2025-07-18T11:34:08.168Z,1752838448.168 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-07-18T11:34:08.168Z,1752838448.168 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-07-18T11:34:08.168Z,1752838448.168 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T11:34:08.168Z,1752838448.168 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-18T11:34:08.168Z,1752838448.168 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-18T11:34:08.169Z,1752838448.169 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-18T11:34:08.169Z,1752838448.169 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-18T11:34:08.174Z,1752838448.174 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T11:34:08.174Z,1752838448.174 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T11:34:08.174Z,1752838448.174 [keepstation:NeedComms:A] Running Loop=1
2025-07-18T11:34:08.176Z,1752838448.176 [keepstation:NeedComms:A](INFO): last time_fix was: 1752834847.000000 second since 1970/01/01T00:00:00Z
2025-07-18T11:34:08.176Z,1752838448.176 [keepstation:NeedComms:A] Stopped
2025-07-18T11:34:08.539Z,1752838448.539 [keepstation:KeepStation:Dive] Stopped
2025-07-18T11:34:08.539Z,1752838448.539 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-07-18T11:34:08.539Z,1752838448.539 [keepstation:KeepStation:Dive:A] Stopped
2025-07-18T11:34:08.539Z,1752838448.539 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-07-18T11:34:08.539Z,1752838448.539 [keepstation:DiveCmd:StationKeep] Stopped
2025-07-18T11:34:08.539Z,1752838448.539 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-07-18T11:34:08.539Z,1752838448.539 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-07-18T11:34:08.540Z,1752838448.540 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-07-18T11:34:08.540Z,1752838448.540 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-07-18T11:34:08.540Z,1752838448.540 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-07-18T11:36:04.805Z,1752838564.805 [keepstation:NeedComms:C] Running Loop=1
2025-07-18T11:36:05.366Z,1752838565.366 [Radio_Surface](INFO): Powering up
2025-07-18T11:36:11.366Z,1752838571.366 [DataOverHttps](INFO): Radio surface powered ON.
2025-07-18T11:36:37.495Z,1752838597.495 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-18T11:36:37.570Z,1752838597.570 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-07-18T11:36:41.486Z,1752838601.486 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T11:37:16.642Z,1752838636.642 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T11:37:50.287Z,1752838670.287 [CBIT](INFO): Clearing failed state for component BPC1
2025-07-18T11:37:50.287Z,1752838670.287 [BPC1] No Fault, FailCount= 1
2025-07-18T11:37:51.782Z,1752838671.782 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T11:38:26.923Z,1752838706.923 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T11:38:42.550Z,1752838722.550 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003029
2025-07-18T11:41:09.390Z,1752838869.390 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T11:41:15.041Z,1752838875.041 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,114114.00,A,3647.02154,N,12151.86524,W,0.311,285.77,180725,,,D*73
2025-07-18T11:41:15.043Z,1752838875.043 [NAL9602](INFO): GPS fix at 20250718T114114: (36.783692, -121.864421)
2025-07-18T11:41:15.099Z,1752838875.099 [UniversalFixResidualReporter](INFO): Fix residual: 0.7 %DT, over the last 1485.0 m. Residual distance 10.5 m at bearing -72.9 degrees. Fix at (36.7837, -121.8644) with 293.3 m made good.
2025-07-18T11:41:15.108Z,1752838875.108 [keepstation:NeedComms:C] Stopped
2025-07-18T11:41:15.108Z,1752838875.108 [keepstation:NeedComms:D] Running Loop=1
2025-07-18T11:41:23.069Z,1752838883.069 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20250718T052352/Courier0031.lzma
2025-07-18T11:41:24.072Z,1752838884.072 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0031.lzma.bak
2025-07-18T11:41:24.072Z,1752838884.072 [DataOverHttps](INFO): SBD MOMSN=25544802
2025-07-18T11:41:40.629Z,1752838900.629 [DataOverHttps](INFO): Sending 752 bytes from file Logs/20250718T052352/Express0032.lzma
2025-07-18T11:41:41.624Z,1752838901.624 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0032.lzma.bak
2025-07-18T11:41:41.624Z,1752838901.624 [DataOverHttps](INFO): SBD MOMSN=25544805
2025-07-18T11:41:42.000Z,1752838903.000 [keepstation:NeedComms:D] Stopped
2025-07-18T11:41:42.000Z,1752838903.000 [keepstation:NeedComms:E] Running Loop=1
2025-07-18T11:41:44.983Z,1752838904.983 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,114144.00,A,3647.02265,N,12151.86737,W,0.272,303.96,180725,,,D*73
2025-07-18T11:41:44.990Z,1752838904.990 [NAL9602](INFO): GPS fix at 20250718T114144: (36.783711, -121.864456)
2025-07-18T11:41:45.081Z,1752838905.081 [keepstation:NeedComms:E] Stopped
2025-07-18T11:41:45.086Z,1752838905.086 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-07-18T11:41:45.086Z,1752838905.086 [keepstation:NeedComms] Stopped
2025-07-18T11:41:45.086Z,1752838905.086 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-07-18T11:41:45.086Z,1752838905.086 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-07-18T11:41:45.086Z,1752838905.086 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T11:41:45.491Z,1752838905.491 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-07-18T11:41:45.491Z,1752838905.491 [keepstation:SurfaceComms:B] Stopped
2025-07-18T11:41:45.492Z,1752838905.492 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-07-18T11:41:45.492Z,1752838905.492 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-07-18T11:41:45.492Z,1752838905.492 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-07-18T11:41:45.492Z,1752838905.492 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-07-18T11:41:45.783Z,1752838905.783 [keepstation:SurfaceComms:setTransit] Stopped
2025-07-18T11:41:45.783Z,1752838905.783 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-07-18T11:41:45.783Z,1752838905.783 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-07-18T11:41:45.783Z,1752838905.783 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-07-18T11:41:45.783Z,1752838905.783 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-07-18T11:41:45.783Z,1752838905.783 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-07-18T11:41:46.199Z,1752838906.199 [keepstation:SurfaceComms:setStation:A] Stopped
2025-07-18T11:41:46.199Z,1752838906.199 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-07-18T11:41:46.199Z,1752838906.199 [keepstation:SurfaceComms:setStation] Stopped
2025-07-18T11:41:46.199Z,1752838906.200 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-07-18T11:41:46.200Z,1752838906.200 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-07-18T11:41:46.200Z,1752838906.200 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-07-18T11:41:46.200Z,1752838906.200 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-07-18T11:41:46.599Z,1752838906.599 [keepstation:KeepStation:Dive] Running Loop=1
2025-07-18T11:41:46.600Z,1752838906.600 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-07-18T11:41:46.600Z,1752838906.600 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-07-18T11:41:46.600Z,1752838906.600 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-07-18T11:41:46.600Z,1752838906.600 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-07-18T11:41:46.600Z,1752838906.600 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-07-18T11:41:46.600Z,1752838906.600 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-07-18T11:41:46.601Z,1752838906.601 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-07-18T11:41:46.601Z,1752838906.601 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-07-18T11:41:46.601Z,1752838906.601 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-07-18T11:41:46.601Z,1752838906.601 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-07-18T11:41:46.607Z,1752838906.607 [keepstation:SurfaceComms:setSink] Stopped
2025-07-18T11:41:46.607Z,1752838906.607 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-07-18T11:41:46.607Z,1752838906.607 [keepstation:SurfaceComms:setSink:A] Stopped
2025-07-18T11:41:46.607Z,1752838906.607 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-07-18T11:41:46.607Z,1752838906.607 [keepstation:SurfaceComms] Stopped
2025-07-18T11:41:46.607Z,1752838906.607 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-07-18T11:42:17.668Z,1752838937.668 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T11:43:58.994Z,1752839038.994 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T11:44:05.018Z,1752839045.018 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.259418
2025-07-18T11:44:40.066Z,1752839080.066 [Radio_Surface](INFO): Powering down
2025-07-18T11:45:09.298Z,1752839109.298 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T11:45:10.303Z,1752839110.303 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-18T12:06:29.293Z,1752840389.293 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T12:08:51.138Z,1752840531.138 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T12:08:51.139Z,1752840531.139 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T12:08:51.140Z,1752840531.140 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-18T12:08:51.140Z,1752840531.140 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T12:08:51.141Z,1752840531.141 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T12:08:51.142Z,1752840531.142 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T12:08:51.143Z,1752840531.143 [BPC1](INFO): Calculating totals. Valid battery stick count: 35. Valid reserve battery stick count: 2.
2025-07-18T12:08:51.148Z,1752840531.148 [BPC1](FAULT): Failed to receive data from 23 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 2, 3, 4, 5, 7, 8, 11, 12, 13, 14, 16, 17, 18, 24, 26, 27, 32, 33, 34, 36, 37, 39, 58.
2025-07-18T12:10:55.106Z,1752840655.106 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 8.91, 714.15, 630.13, 0.00
2025-07-18T12:12:05.003Z,1752840725.003 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +82.60.84, 0.00
2025-07-18T12:20:06.214Z,1752841206.214 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T12:20:59.102Z,1752841259.102 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-18T12:41:45.086Z,1752842505.086 [keepstation:SurfaceComms] Running Loop=1
2025-07-18T12:41:45.086Z,1752842505.086 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-07-18T12:41:45.087Z,1752842505.087 [keepstation:SurfaceComms:A] Running Loop=1
2025-07-18T12:41:45.087Z,1752842505.087 [keepstation:SurfaceComms:A] Stopped
2025-07-18T12:41:45.087Z,1752842505.087 [keepstation:SurfaceComms:B] Running Loop=1
2025-07-18T12:41:45.087Z,1752842505.087 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-07-18T12:41:45.089Z,1752842505.089 [keepstation:NeedComms] Running Loop=1
2025-07-18T12:41:45.089Z,1752842505.089 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-07-18T12:41:45.089Z,1752842505.089 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-07-18T12:41:45.089Z,1752842505.089 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T12:41:45.089Z,1752842505.089 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-18T12:41:45.090Z,1752842505.090 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-18T12:41:45.090Z,1752842505.090 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-18T12:41:45.091Z,1752842505.091 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-18T12:41:45.091Z,1752842505.091 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T12:41:45.091Z,1752842505.091 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T12:41:45.092Z,1752842505.092 [keepstation:NeedComms:A] Running Loop=1
2025-07-18T12:41:45.093Z,1752842505.093 [keepstation:NeedComms:A](INFO): last time_fix was: 1752838904.000000 second since 1970/01/01T00:00:00Z
2025-07-18T12:41:45.093Z,1752842505.093 [keepstation:NeedComms:A] Stopped
2025-07-18T12:41:45.443Z,1752842505.443 [keepstation:KeepStation:Dive] Stopped
2025-07-18T12:41:45.444Z,1752842505.444 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-07-18T12:41:45.444Z,1752842505.444 [keepstation:KeepStation:Dive:A] Stopped
2025-07-18T12:41:45.444Z,1752842505.444 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-07-18T12:41:45.444Z,1752842505.444 [keepstation:DiveCmd:StationKeep] Stopped
2025-07-18T12:41:45.444Z,1752842505.444 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-07-18T12:41:45.444Z,1752842505.444 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-07-18T12:41:45.444Z,1752842505.444 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-07-18T12:41:45.444Z,1752842505.444 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-07-18T12:41:45.444Z,1752842505.444 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-07-18T12:43:37.436Z,1752842617.436 [keepstation:NeedComms:C] Running Loop=1
2025-07-18T12:43:37.946Z,1752842617.946 [Radio_Surface](INFO): Powering up
2025-07-18T12:43:43.859Z,1752842623.859 [DataOverHttps](INFO): Radio surface powered ON.
2025-07-18T12:44:10.145Z,1752842650.145 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-18T12:44:10.222Z,1752842650.222 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-07-18T12:44:13.978Z,1752842653.978 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T12:44:49.118Z,1752842689.118 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T12:45:04.696Z,1752842704.696 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-07-18T12:45:24.258Z,1752842724.258 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T12:45:59.411Z,1752842759.411 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T12:46:21.138Z,1752842781.138 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003040
2025-07-18T12:48:41.247Z,1752842921.247 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T12:48:42.443Z,1752842922.443 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,124841.00,A,3647.01800,N,12151.88306,W,0.369,296.30,180725,,,D*74
2025-07-18T12:48:42.445Z,1752842922.445 [NAL9602](INFO): GPS fix at 20250718T124841: (36.783633, -121.864718)
2025-07-18T12:48:42.458Z,1752842922.458 [UniversalFixResidualReporter](INFO): Fix residual: 0.8 %DT, over the last 1018.1 m. Residual distance 8.1 m at bearing -90.7 degrees. Fix at (36.7836, -121.8647) with 24.8 m made good.
2025-07-18T12:48:42.461Z,1752842922.461 [keepstation:NeedComms:C] Stopped
2025-07-18T12:48:42.461Z,1752842922.461 [keepstation:NeedComms:D] Running Loop=1
2025-07-18T12:48:50.497Z,1752842930.497 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20250718T052352/Courier0034.lzma
2025-07-18T12:48:51.501Z,1752842931.501 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0034.lzma.bak
2025-07-18T12:48:51.501Z,1752842931.501 [DataOverHttps](INFO): SBD MOMSN=25544902
2025-07-18T12:49:08.154Z,1752842948.154 [DataOverHttps](INFO): Sending 469 bytes from file Logs/20250718T052352/Express0035.lzma
2025-07-18T12:49:09.156Z,1752842949.156 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0035.lzma.bak
2025-07-18T12:49:09.156Z,1752842949.156 [DataOverHttps](INFO): SBD MOMSN=25544905
2025-07-18T12:49:10.352Z,1752842950.352 [keepstation:NeedComms:D] Stopped
2025-07-18T12:49:10.352Z,1752842950.352 [keepstation:NeedComms:E] Running Loop=1
2025-07-18T12:49:12.341Z,1752842952.341 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,124911.00,A,3647.01879,N,12151.88404,W,0.058,279.08,180725,,,D*70
2025-07-18T12:49:12.343Z,1752842952.343 [NAL9602](INFO): GPS fix at 20250718T124911: (36.783647, -121.864734)
2025-07-18T12:49:12.393Z,1752842952.393 [keepstation:NeedComms:E] Stopped
2025-07-18T12:49:12.398Z,1752842952.398 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-07-18T12:49:12.398Z,1752842952.398 [keepstation:NeedComms] Stopped
2025-07-18T12:49:12.398Z,1752842952.398 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-07-18T12:49:12.399Z,1752842952.399 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-07-18T12:49:12.399Z,1752842952.399 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T12:49:12.799Z,1752842952.799 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-07-18T12:49:12.799Z,1752842952.799 [keepstation:SurfaceComms:B] Stopped
2025-07-18T12:49:12.799Z,1752842952.799 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-07-18T12:49:12.799Z,1752842952.799 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-07-18T12:49:12.799Z,1752842952.799 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-07-18T12:49:12.799Z,1752842952.799 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-07-18T12:49:13.219Z,1752842953.219 [keepstation:SurfaceComms:setTransit] Stopped
2025-07-18T12:49:13.219Z,1752842953.219 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-07-18T12:49:13.219Z,1752842953.219 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-07-18T12:49:13.219Z,1752842953.219 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-07-18T12:49:13.219Z,1752842953.219 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-07-18T12:49:13.219Z,1752842953.219 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-07-18T12:49:13.587Z,1752842953.587 [keepstation:SurfaceComms:setStation:A] Stopped
2025-07-18T12:49:13.587Z,1752842953.587 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-07-18T12:49:13.588Z,1752842953.588 [keepstation:SurfaceComms:setStation] Stopped
2025-07-18T12:49:13.588Z,1752842953.588 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-07-18T12:49:13.588Z,1752842953.588 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-07-18T12:49:13.588Z,1752842953.588 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-07-18T12:49:13.588Z,1752842953.588 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-07-18T12:49:14.011Z,1752842954.011 [keepstation:KeepStation:Dive] Running Loop=1
2025-07-18T12:49:14.011Z,1752842954.011 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-07-18T12:49:14.011Z,1752842954.011 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-07-18T12:49:14.011Z,1752842954.011 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-07-18T12:49:14.012Z,1752842954.012 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-07-18T12:49:14.012Z,1752842954.012 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-07-18T12:49:14.012Z,1752842954.012 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-07-18T12:49:14.012Z,1752842954.012 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-07-18T12:49:14.012Z,1752842954.012 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-07-18T12:49:14.012Z,1752842954.012 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-07-18T12:49:14.013Z,1752842954.013 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-07-18T12:49:14.018Z,1752842954.018 [keepstation:SurfaceComms:setSink] Stopped
2025-07-18T12:49:14.019Z,1752842954.019 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-07-18T12:49:14.019Z,1752842954.019 [keepstation:SurfaceComms:setSink:A] Stopped
2025-07-18T12:49:14.019Z,1752842954.019 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-07-18T12:49:14.019Z,1752842954.019 [keepstation:SurfaceComms] Stopped
2025-07-18T12:49:14.019Z,1752842954.019 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-07-18T12:49:44.658Z,1752842984.658 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T12:49:53.138Z,1752842993.138 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T12:49:53.139Z,1752842993.139 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T12:49:53.140Z,1752842993.140 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-18T12:49:53.141Z,1752842993.141 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T12:49:53.141Z,1752842993.141 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T12:49:53.142Z,1752842993.142 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T12:49:53.143Z,1752842993.143 [BPC1](INFO): Calculating totals. Valid battery stick count: 30. Valid reserve battery stick count: 2.
2025-07-18T12:49:53.148Z,1752842993.148 [BPC1](FAULT): Failed to receive data from 23 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 2, 3, 4, 5, 7, 8, 11, 12, 13, 14, 16, 17, 18, 24, 26, 27, 32, 33, 34, 36, 37, 39, 58.
2025-07-18T12:51:27.074Z,1752843087.074 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T12:51:29.082Z,1752843089.082 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.267269
2025-07-18T12:51:59.346Z,1752843119.346 [Radio_Surface](INFO): Powering down
2025-07-18T12:52:02.214Z,1752843122.214 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T12:52:03.218Z,1752843123.218 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-18T12:53:27.276Z,1752843207.276 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 958.74,1197.29,1167.69
2025-07-18T12:54:17.398Z,1752843257.398 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2025-07-18T12:54:17.398Z,1752843257.398 [RDI_Pathfinder] Communications Fault, FailCount= 1
2025-07-18T12:54:17.398Z,1752843257.398 [RDI_Pathfinder](ERROR): Communications Fault
2025-07-18T12:54:17.466Z,1752843257.466 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-07-18T12:54:17.834Z,1752843257.834 [RDI_Pathfinder](INFO): Powering down
2025-07-18T12:54:18.598Z,1752843258.598 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-07-18T12:54:18.599Z,1752843258.599 [RDI_Pathfinder] No Fault, FailCount= 1
2025-07-18T12:54:18.975Z,1752843258.975 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-07-18T13:02:52.525Z,1752843772.525 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012316494099,35.0, 0.0,1496.6, 0
2025-07-18T13:03:02.564Z,1752843782.564 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012316495099,35.0, +11.8, 0.0,10
2025-07-18T13:14:16.839Z,1752844456.839 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS -44,A
2025-07-18T13:21:03.673Z,1752844863.673 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T13:22:38.617Z,1752844958.617 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS, +119, +66,A
2025-07-18T13:30:55.114Z,1752845455.114 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T13:30:55.115Z,1752845455.115 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T13:30:55.116Z,1752845455.116 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-18T13:30:55.116Z,1752845455.116 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T13:30:55.117Z,1752845455.117 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T13:30:55.135Z,1752845455.135 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T13:30:55.136Z,1752845455.136 [BPC1](INFO): Calculating totals. Valid battery stick count: 34. Valid reserve battery stick count: 2.
2025-07-18T13:30:55.141Z,1752845455.141 [BPC1](FAULT): Failed to receive data from 24 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 2, 3, 4, 5, 6, 7, 8, 11, 12, 13, 14, 15, 16, 17, 18, 24, 26, 27, 32, 33, 34, 37, 39, 58.
2025-07-18T13:33:10.531Z,1752845590.531 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T13:44:09.792Z,1752846249.792 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012317305811,35.0, +11.8, 0.0,149
2025-07-18T13:44:44.962Z,1752846284.962 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 751.03,1026.09, 892.47
2025-07-18T13:44:54.674Z,1752846294.674 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 775.53,1001.12, 0.00,
2025-07-18T13:45:14.863Z,1752846314.863 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +217.50, +405.0420.00, 0.00
2025-07-18T13:45:24.965Z,1752846324.965 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, +114, -5, +BE, +114, -5, +31,A
2025-07-18T13:45:34.695Z,1752846334.695 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD,4 +91.49, -25.38, 87.28, 0.00
2025-07-18T13:45:44.751Z,1752846344.751 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, +136, -94,
2025-07-18T13:45:53.615Z,1752846353.615 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:49:12.404Z,1752846552.404 [keepstation:SurfaceComms] Running Loop=1
2025-07-18T13:49:12.404Z,1752846552.404 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-07-18T13:49:12.404Z,1752846552.404 [keepstation:SurfaceComms:A] Running Loop=1
2025-07-18T13:49:12.405Z,1752846552.405 [keepstation:SurfaceComms:A] Stopped
2025-07-18T13:49:12.405Z,1752846552.405 [keepstation:SurfaceComms:B] Running Loop=1
2025-07-18T13:49:12.405Z,1752846552.405 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-07-18T13:49:12.832Z,1752846552.832 [keepstation:KeepStation:Dive] Stopped
2025-07-18T13:49:12.832Z,1752846552.832 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-07-18T13:49:12.832Z,1752846552.832 [keepstation:KeepStation:Dive:A] Stopped
2025-07-18T13:49:12.832Z,1752846552.832 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-07-18T13:49:12.832Z,1752846552.832 [keepstation:DiveCmd:StationKeep] Stopped
2025-07-18T13:49:12.833Z,1752846552.833 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-07-18T13:49:12.833Z,1752846552.833 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-07-18T13:49:12.833Z,1752846552.833 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-07-18T13:49:12.833Z,1752846552.833 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-07-18T13:49:12.833Z,1752846552.833 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-07-18T13:49:12.835Z,1752846552.835 [keepstation:NeedComms] Running Loop=1
2025-07-18T13:49:12.835Z,1752846552.835 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-07-18T13:49:12.835Z,1752846552.835 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-07-18T13:49:12.835Z,1752846552.835 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T13:49:12.835Z,1752846552.835 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-18T13:49:12.836Z,1752846552.836 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-18T13:49:12.836Z,1752846552.836 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-18T13:49:12.836Z,1752846552.836 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-18T13:49:12.837Z,1752846552.837 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T13:49:12.837Z,1752846552.837 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T13:49:12.837Z,1752846552.837 [keepstation:NeedComms:A] Running Loop=1
2025-07-18T13:49:12.839Z,1752846552.839 [keepstation:NeedComms:A](INFO): last time_fix was: 1752842951.000000 second since 1970/01/01T00:00:00Z
2025-07-18T13:49:12.839Z,1752846552.839 [keepstation:NeedComms:A] Stopped
2025-07-18T13:51:08.046Z,1752846668.046 [keepstation:NeedComms:C] Running Loop=1
2025-07-18T13:51:08.542Z,1752846668.542 [Radio_Surface](INFO): Powering up
2025-07-18T13:51:13.834Z,1752846673.834 [DataOverHttps](INFO): Radio surface powered ON.
2025-07-18T13:51:43.962Z,1752846703.962 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:52:19.110Z,1752846739.110 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:52:54.250Z,1752846774.250 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:53:29.390Z,1752846809.390 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:53:51.190Z,1752846831.190 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003008
2025-07-18T13:55:34.662Z,1752846934.662 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-18T13:56:12.236Z,1752846972.236 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T13:56:17.893Z,1752846977.893 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135617.00,A,3647.05499,N,12151.80434,W,0.661,53.88,180725,,,D*4A
2025-07-18T13:56:17.903Z,1752846977.903 [NAL9602](INFO): GPS fix at 20250718T135617: (36.784250, -121.863406)
2025-07-18T13:56:17.916Z,1752846977.916 [UniversalFixResidualReporter](INFO): Fix residual: 1.1 %DT, over the last 935.3 m. Residual distance 10.1 m at bearing -158.3 degrees. Fix at (36.7842, -121.8634) with 136.0 m made good.
2025-07-18T13:56:17.919Z,1752846977.919 [keepstation:NeedComms:C] Stopped
2025-07-18T13:56:17.920Z,1752846977.920 [keepstation:NeedComms:D] Running Loop=1
2025-07-18T13:56:25.453Z,1752846985.453 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20250718T052352/Courier0037.lzma
2025-07-18T13:56:26.456Z,1752846986.456 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0037.lzma.bak
2025-07-18T13:56:26.456Z,1752846986.456 [DataOverHttps](INFO): SBD MOMSN=25545165
2025-07-18T13:56:43.175Z,1752847003.175 [DataOverHttps](INFO): Sending 526 bytes from file Logs/20250718T052352/Express0038.lzma
2025-07-18T13:56:44.176Z,1752847004.176 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0038.lzma.bak
2025-07-18T13:56:44.176Z,1752847004.176 [DataOverHttps](INFO): SBD MOMSN=25545168
2025-07-18T13:56:45.447Z,1752847005.447 [keepstation:NeedComms:D] Stopped
2025-07-18T13:56:45.447Z,1752847005.447 [keepstation:NeedComms:E] Running Loop=1
2025-07-18T13:56:47.392Z,1752847007.392 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135646.00,A,3647.05711,N,12151.80311,W,0.194,24.04,180725,,,D*44
2025-07-18T13:56:47.395Z,1752847007.395 [NAL9602](INFO): GPS fix at 20250718T135646: (36.784285, -121.863385)
2025-07-18T13:56:47.485Z,1752847007.485 [keepstation:NeedComms:E] Stopped
2025-07-18T13:56:47.491Z,1752847007.491 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-07-18T13:56:47.491Z,1752847007.491 [keepstation:NeedComms] Stopped
2025-07-18T13:56:47.491Z,1752847007.491 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-07-18T13:56:47.491Z,1752847007.491 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-07-18T13:56:47.491Z,1752847007.491 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T13:56:47.847Z,1752847007.847 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-07-18T13:56:47.847Z,1752847007.847 [keepstation:SurfaceComms:B] Stopped
2025-07-18T13:56:47.847Z,1752847007.847 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-07-18T13:56:47.847Z,1752847007.847 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-07-18T13:56:47.847Z,1752847007.847 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-07-18T13:56:47.847Z,1752847007.847 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-07-18T13:56:48.231Z,1752847008.231 [keepstation:SurfaceComms:setTransit] Stopped
2025-07-18T13:56:48.231Z,1752847008.231 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-07-18T13:56:48.231Z,1752847008.231 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-07-18T13:56:48.231Z,1752847008.231 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-07-18T13:56:48.231Z,1752847008.231 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-07-18T13:56:48.231Z,1752847008.231 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-07-18T13:56:48.639Z,1752847008.639 [keepstation:SurfaceComms:setStation:A] Stopped
2025-07-18T13:56:48.639Z,1752847008.639 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-07-18T13:56:48.639Z,1752847008.639 [keepstation:SurfaceComms:setStation] Stopped
2025-07-18T13:56:48.639Z,1752847008.639 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-07-18T13:56:48.639Z,1752847008.639 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-07-18T13:56:48.639Z,1752847008.639 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-07-18T13:56:48.639Z,1752847008.639 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-07-18T13:56:49.053Z,1752847009.053 [keepstation:KeepStation:Dive] Running Loop=1
2025-07-18T13:56:49.058Z,1752847009.058 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-07-18T13:56:49.058Z,1752847009.058 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-07-18T13:56:49.058Z,1752847009.058 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-07-18T13:56:49.058Z,1752847009.058 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-07-18T13:56:49.059Z,1752847009.059 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-07-18T13:56:49.059Z,1752847009.059 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-07-18T13:56:49.059Z,1752847009.059 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-07-18T13:56:49.059Z,1752847009.059 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-07-18T13:56:49.059Z,1752847009.059 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-07-18T13:56:49.059Z,1752847009.059 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-07-18T13:56:49.061Z,1752847009.061 [keepstation:SurfaceComms:setSink] Stopped
2025-07-18T13:56:49.061Z,1752847009.061 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-07-18T13:56:49.061Z,1752847009.061 [keepstation:SurfaceComms:setSink:A] Stopped
2025-07-18T13:56:49.061Z,1752847009.061 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-07-18T13:56:49.061Z,1752847009.061 [keepstation:SurfaceComms] Stopped
2025-07-18T13:56:49.061Z,1752847009.061 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-07-18T13:57:19.707Z,1752847039.707 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T13:57:35.470Z,1752847055.470 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00,1189.06, 977.20,1365.61
2025-07-18T13:58:25.589Z,1752847105.589 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, -20, +14,A
2025-07-18T13:58:55.513Z,1752847135.513 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD+31.09, +220.69, -16.12, 125.03, 0.00
2025-07-18T13:59:02.298Z,1752847142.298 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:59:04.306Z,1752847144.306 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.271259
2025-07-18T13:59:15.671Z,1752847155.671 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012317460391,35.0, 0.0,1505.6, 0
2025-07-18T13:59:26.306Z,1752847166.306 [Radio_Surface](INFO): Powering down
2025-07-18T13:59:37.439Z,1752847177.439 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T13:59:38.442Z,1752847178.442 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-18T14:02:17.861Z,1752847337.861 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-07-18T14:02:59.910Z,1752847379.910 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T14:04:27.235Z,1752847467.235 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-18T14:06:03.331Z,1752847563.331 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-07-18T14:10:55.998Z,1752847855.998 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2025-07-18T14:10:55.998Z,1752847855.998 [RDI_Pathfinder] Communications Fault, FailCount= 1
2025-07-18T14:10:55.998Z,1752847855.998 [RDI_Pathfinder](ERROR): Communications Fault
2025-07-18T14:10:56.076Z,1752847856.076 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-07-18T14:10:56.314Z,1752847856.314 [RDI_Pathfinder](INFO): Powering down
2025-07-18T14:10:57.105Z,1752847857.105 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-07-18T14:10:57.105Z,1752847857.105 [RDI_Pathfinder] No Fault, FailCount= 1
2025-07-18T14:10:57.448Z,1752847857.448 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-07-18T14:11:57.240Z,1752847917.240 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T14:11:57.241Z,1752847917.241 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T14:11:57.242Z,1752847917.242 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-18T14:11:57.243Z,1752847917.243 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T14:11:57.243Z,1752847917.243 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T14:11:57.244Z,1752847917.244 [BPC1](ERROR): Battery stick #37 (s/n: 00A3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T14:11:57.245Z,1752847917.245 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T14:11:57.245Z,1752847917.245 [BPC1](INFO): Calculating totals. Valid battery stick count: 30. Valid reserve battery stick count: 2.
2025-07-18T14:11:57.250Z,1752847917.250 [BPC1](FAULT): Failed to receive data from 23 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 2, 3, 4, 5, 6, 7, 8, 11, 12, 13, 14, 15, 16, 17, 18, 24, 26, 27, 32, 33, 34, 39, 58.
2025-07-18T14:12:53.005Z,1752847973.005 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T14:17:53.579Z,1752848273.579 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-07-18T14:29:24.453Z,1752848964.453 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-07-18T14:30:42.028Z,1752849042.028 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +38.1+507.87, -12.50, 20.00, 0.00
2025-07-18T14:38:57.764Z,1752849537.764 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-18T14:41:31.275Z,1752849691.275 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-18T14:51:11.820Z,1752850271.820 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-18T14:52:59.247Z,1752850379.247 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T14:52:59.248Z,1752850379.248 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T14:52:59.248Z,1752850379.248 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-18T14:52:59.249Z,1752850379.249 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T14:52:59.250Z,1752850379.250 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T14:52:59.250Z,1752850379.250 [BPC1](ERROR): Battery stick #37 (s/n: 00A3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-18T14:52:59.251Z,1752850379.251 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-18T14:52:59.252Z,1752850379.252 [BPC1](INFO): Calculating totals. Valid battery stick count: 32. Valid reserve battery stick count: 2.
2025-07-18T14:52:59.262Z,1752850379.262 [BPC1](FAULT): Failed to receive data from 22 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 2, 3, 4, 5, 6, 7, 8, 11, 12, 13, 14, 15, 16, 17, 18, 24, 26, 27, 33, 34, 39, 58.
2025-07-18T14:56:47.525Z,1752850607.525 [keepstation:SurfaceComms] Running Loop=1
2025-07-18T14:56:47.526Z,1752850607.526 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-07-18T14:56:47.526Z,1752850607.526 [keepstation:SurfaceComms:A] Running Loop=1
2025-07-18T14:56:47.526Z,1752850607.526 [keepstation:SurfaceComms:A] Stopped
2025-07-18T14:56:47.527Z,1752850607.527 [keepstation:SurfaceComms:B] Running Loop=1
2025-07-18T14:56:47.527Z,1752850607.527 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-07-18T14:56:47.528Z,1752850607.528 [keepstation:NeedComms] Running Loop=1
2025-07-18T14:56:47.528Z,1752850607.528 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-07-18T14:56:47.528Z,1752850607.528 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-07-18T14:56:47.529Z,1752850607.529 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T14:56:47.529Z,1752850607.529 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-07-18T14:56:47.529Z,1752850607.529 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-07-18T14:56:47.546Z,1752850607.546 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-07-18T14:56:47.546Z,1752850607.546 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-07-18T14:56:47.547Z,1752850607.547 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T14:56:47.547Z,1752850607.547 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T14:56:47.547Z,1752850607.547 [keepstation:NeedComms:A] Running Loop=1
2025-07-18T14:56:47.549Z,1752850607.549 [keepstation:NeedComms:A](INFO): last time_fix was: 1752847006.000000 second since 1970/01/01T00:00:00Z
2025-07-18T14:56:47.549Z,1752850607.549 [keepstation:NeedComms:A] Stopped
2025-07-18T14:56:47.984Z,1752850607.984 [keepstation:KeepStation:Dive] Stopped
2025-07-18T14:56:47.984Z,1752850607.984 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-07-18T14:56:47.984Z,1752850607.984 [keepstation:KeepStation:Dive:A] Stopped
2025-07-18T14:56:47.984Z,1752850607.984 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-07-18T14:56:47.985Z,1752850607.985 [keepstation:DiveCmd:StationKeep] Stopped
2025-07-18T14:56:47.985Z,1752850607.985 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-07-18T14:56:47.985Z,1752850607.985 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-07-18T14:56:47.985Z,1752850607.985 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-07-18T14:56:47.985Z,1752850607.985 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-07-18T14:56:47.985Z,1752850607.985 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-07-18T14:58:58.266Z,1752850738.266 [keepstation:NeedComms:C] Running Loop=1
2025-07-18T14:58:59.006Z,1752850739.006 [Radio_Surface](INFO): Powering up
2025-07-18T14:59:04.259Z,1752850744.259 [DataOverHttps](INFO): Radio surface powered ON.
2025-07-18T14:59:30.959Z,1752850770.959 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-07-18T14:59:31.034Z,1752850771.034 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-07-18T14:59:34.391Z,1752850774.391 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T15:00:09.535Z,1752850809.535 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T15:00:44.674Z,1752850844.674 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T15:01:19.823Z,1752850879.823 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-18T15:01:35.499Z,1752850895.499 [DataOverHttps](IMPORTANT): SBD MTMSN=20250718T150134
2025-07-18T15:01:35.502Z,1752850895.502 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003325
2025-07-18T15:01:43.185Z,1752850903.185 [DataOverHttps](INFO): Received command: stop
2025-07-18T15:01:43.263Z,1752850903.263 [CommandExec](IMPORTANT): got command stop
2025-07-18T15:01:43.263Z,1752850903.263 [CommandExec](IMPORTANT): Scheduling is paused
2025-07-18T15:01:43.263Z,1752850903.263 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-07-18T15:01:43.530Z,1752850903.530 [MissionManager](INFO): MissionManager is completed.
2025-07-18T15:01:43.530Z,1752850903.530 [MissionManager](INFO): Uninitializing Mission keepstation
2025-07-18T15:01:43.530Z,1752850903.530 [keepstation] Stopped
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:NeedComms] Stopped
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:NeedComms:C] Stopped
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:StandardEnvelopes] Stopped
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-07-18T15:01:43.531Z,1752850903.531 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:BackseatDriver] Stopped
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:PowerOnly] Stopped
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:PowerOnly:E.Wait] Stopped
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:H.Pitch] Stopped
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:SurfaceComms] Stopped
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:SurfaceComms:B] Stopped
2025-07-18T15:01:43.532Z,1752850903.532 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-07-18T15:01:43.533Z,1752850903.533 [keepstation:DiveCmd] Stopped
2025-07-18T15:01:43.533Z,1752850903.533 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd
2025-07-18T15:01:43.533Z,1752850903.533 [keepstation:KeepStation] Stopped
2025-07-18T15:01:43.533Z,1752850903.533 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-07-18T15:01:43.533Z,1752850903.533 [keepstation:KeepStation:B.Wait] Stopped
2025-07-18T15:01:43.533Z,1752850903.533 [keepstation:KeepStation:B.Wait](DEBUG): Uninitialize Wait Component.
2025-07-18T15:01:43.907Z,1752850903.907 [MissionManager](IMPORTANT): Started mission Default
2025-07-18T15:01:43.907Z,1752850903.907 [Default] Running Loop=1
2025-07-18T15:01:43.907Z,1752850903.907 [Default](DEBUG): Aggregate::initialize Default
2025-07-18T15:01:43.907Z,1752850903.907 [Default:B.GoToSurface] Running Loop=1
2025-07-18T15:01:43.907Z,1752850903.907 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-18T15:01:43.907Z,1752850903.907 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-18T15:01:43.908Z,1752850903.908 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-18T15:01:43.908Z,1752850903.908 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-18T15:01:43.908Z,1752850903.908 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-18T15:01:43.908Z,1752850903.908 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-18T15:01:43.909Z,1752850903.909 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-18T15:01:43.909Z,1752850903.909 [Default:A.Wait] Running Loop=1
2025-07-18T15:01:43.909Z,1752850903.909 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-07-18T15:01:57.232Z,1752850917.232 [Default:A.Wait](INFO): Done Waiting.
2025-07-18T15:01:57.232Z,1752850917.232 [Default:A.Wait] Stopped
2025-07-18T15:01:57.232Z,1752850917.232 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-07-18T15:01:57.648Z,1752850917.648 [Default:CheckIn] Running Loop=1
2025-07-18T15:01:57.648Z,1752850917.648 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-18T15:01:57.648Z,1752850917.648 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-18T15:03:31.439Z,1752851011.439 [DataOverHttps](IMPORTANT): SBD MTMSN=20250718T150330
2025-07-18T15:03:38.994Z,1752851018.994 [DataOverHttps](INFO): Received command: configSet ESPComponent.simulateHardware 0 bool persist
2025-07-18T15:03:39.498Z,1752851019.498 [CommandExec](IMPORTANT): got command configSet ESPComponent.simulateHardware 0 bool persist
2025-07-18T15:03:39.499Z,1752851019.499 [CommandExec](IMPORTANT): configSet ESPComponent.simulateHardware requires a restart to take effect.
2025-07-18T15:04:02.460Z,1752851042.460 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-18T15:04:08.110Z,1752851048.110 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150407.00,A,3647.05076,N,12151.84049,W,0.292,24.20,180725,,,D*4F
2025-07-18T15:04:08.113Z,1752851048.113 [NAL9602](INFO): GPS fix at 20250718T150407: (36.784179, -121.864008)
2025-07-18T15:04:08.146Z,1752851048.146 [UniversalFixResidualReporter](INFO): Fix residual: 0.9 %DT, over the last 1231.9 m. Residual distance 11.7 m at bearing 122.1 degrees. Fix at (36.7842, -121.8640) with 56.7 m made good.
2025-07-18T15:04:08.146Z,1752851048.146 [Default:CheckIn:Read_GPS] Stopped
2025-07-18T15:04:08.147Z,1752851048.147 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-18T15:04:14.853Z,1752851054.853 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20250718T052352/Courier0040.lzma
2025-07-18T15:04:15.856Z,1752851055.856 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0040.lzma.bak
2025-07-18T15:04:15.856Z,1752851055.856 [DataOverHttps](INFO): SBD MOMSN=25545374
2025-07-18T15:04:32.288Z,1752851072.288 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250718T052352/Courier0043.lzma
2025-07-18T15:04:33.284Z,1752851073.284 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Courier0043.lzma.bak
2025-07-18T15:04:33.284Z,1752851073.284 [DataOverHttps](INFO): SBD MOMSN=25545377
2025-07-18T15:04:40.459Z,1752851080.459 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-18T15:04:49.843Z,1752851089.843 [DataOverHttps](INFO): Sending 726 bytes from file Logs/20250718T052352/Express0041.lzma
2025-07-18T15:04:50.845Z,1752851090.845 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0041.lzma.bak
2025-07-18T15:04:50.845Z,1752851090.845 [DataOverHttps](INFO): SBD MOMSN=25545379
2025-07-18T15:04:59.599Z,1752851099.599 [DataOverHttps](IMPORTANT): SBD MTMSN=20250718T150458
2025-07-18T15:05:07.253Z,1752851107.253 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250718T052352/Express0044.lzma
2025-07-18T15:05:07.256Z,1752851107.256 [DataOverHttps](INFO): Received command: restart app
2025-07-18T15:05:07.295Z,1752851107.295 [CommandExec](IMPORTANT): got command restart application
2025-07-18T15:05:08.298Z,1752851108.298 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:08.298Z,1752851108.298 [CommandExec](INFO): Uninitializing the command executive.
2025-07-18T15:05:08.298Z,1752851108.298 [CommandExec](INFO): Uninitializing the command scheduler.
2025-07-18T15:05:08.298Z,1752851108.298 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:08.374Z,1752851108.374 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-07-18T15:05:08.375Z,1752851108.375 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-07-18T15:05:08.375Z,1752851108.375 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:08.377Z,1752851108.377 [NavChartDb](INFO): Join timeout helper Thread ID is 7321
2025-07-18T15:05:08.580Z,1752851108.580 [DataOverHttps](INFO): Moved sent file to Logs/20250718T052352/Express0044.lzma.bak
2025-07-18T15:05:08.580Z,1752851108.580 [DataOverHttps](INFO): SBD MOMSN=25545395
2025-07-18T15:05:08.634Z,1752851108.634 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:08.634Z,1752851108.634 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:08.638Z,1752851108.638 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-07-18T15:05:08.638Z,1752851108.638 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:08.638Z,1752851108.638 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7322
2025-07-18T15:05:08.678Z,1752851108.678 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:08.678Z,1752851108.678 [WetLabsBB2FL](INFO): Powering down
2025-07-18T15:05:08.679Z,1752851108.679 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:08.680Z,1752851108.680 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-07-18T15:05:08.680Z,1752851108.680 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:08.682Z,1752851108.682 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7323
2025-07-18T15:05:09.190Z,1752851109.190 [CTD_Seabird](INFO): Powering down
2025-07-18T15:05:09.202Z,1752851109.202 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:09.202Z,1752851109.202 [CTD_Seabird](INFO): Powering down
2025-07-18T15:05:09.214Z,1752851109.214 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:09.226Z,1752851109.226 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-07-18T15:05:09.226Z,1752851109.226 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:09.226Z,1752851109.226 [Radio_Surface](INFO): Join timeout helper Thread ID is 7324
2025-07-18T15:05:09.474Z,1752851109.474 [Radio_Surface](INFO): Powering down
2025-07-18T15:05:09.475Z,1752851109.475 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:09.475Z,1752851109.475 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:09.478Z,1752851109.478 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-07-18T15:05:09.478Z,1752851109.478 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:09.478Z,1752851109.478 [Onboard](INFO): Join timeout helper Thread ID is 7325
2025-07-18T15:05:13.662Z,1752851113.662 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:13.662Z,1752851113.662 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:13.675Z,1752851113.675 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-07-18T15:05:13.675Z,1752851113.675 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:13.675Z,1752851113.675 [DataOverHttps](INFO): Join timeout helper Thread ID is 7326
2025-07-18T15:05:14.278Z,1752851114.278 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:14.279Z,1752851114.279 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:14.294Z,1752851114.294 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-07-18T15:05:14.295Z,1752851114.295 [DAT ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:14.295Z,1752851114.295 [DAT](INFO): Join timeout helper Thread ID is 7327
2025-07-18T15:05:14.346Z,1752851114.346 [DAT](INFO): Powering down
2025-07-18T15:05:14.420Z,1752851114.420 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:14.420Z,1752851114.420 [DAT](INFO): Powering down
2025-07-18T15:05:14.423Z,1752851114.423 [DAT ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:14.428Z,1752851114.428 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-07-18T15:05:14.428Z,1752851114.428 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:14.429Z,1752851114.429 [BackseatComponent](INFO): Join timeout helper Thread ID is 7328
2025-07-18T15:05:14.574Z,1752851114.574 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:14.574Z,1752851114.574 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:14.590Z,1752851114.590 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-07-18T15:05:14.591Z,1752851114.591 [logger ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:14.591Z,1752851114.591 [logger](INFO): Join timeout helper Thread ID is 7329
2025-07-18T15:05:14.618Z,1752851114.618 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:14.618Z,1752851114.618 [logger ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:14.631Z,1752851114.631 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-07-18T15:05:14.631Z,1752851114.631 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:14.631Z,1752851114.631 [CommandLine](INFO): Join timeout helper Thread ID is 7330
2025-07-18T15:05:14.654Z,1752851114.654 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:14.654Z,1752851114.654 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:14.670Z,1752851114.670 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-07-18T15:05:14.671Z,1752851114.671 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:14.671Z,1752851114.671 [CommandExec](INFO): Join timeout helper Thread ID is 7331
2025-07-18T15:05:14.672Z,1752851114.672 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-07-18T15:05:14.672Z,1752851114.672 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:14.673Z,1752851114.673 [controlThread](INFO): Join timeout helper Thread ID is 7332
2025-07-18T15:05:14.750Z,1752851114.750 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-18T15:05:14.750Z,1752851114.750 [controlThread](DEBUG): Uninitializing ControlThread
2025-07-18T15:05:14.751Z,1752851114.751 [AHRS_M2](INFO): Powering down
2025-07-18T15:05:14.830Z,1752851114.830 [NAL9602](INFO): Powering down
2025-07-18T15:05:14.902Z,1752851114.902 [RDI_Pathfinder](INFO): Powering down
2025-07-18T15:05:14.903Z,1752851114.903 [Sonardyne_Nano](INFO): Powering down
2025-07-18T15:05:15.110Z,1752851115.110 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-07-18T15:05:15.111Z,1752851115.111 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-07-18T15:05:15.111Z,1752851115.111 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-07-18T15:05:15.112Z,1752851115.112 [MissionManager](INFO): Uninitializing Mission Default
2025-07-18T15:05:15.112Z,1752851115.112 [Default] Stopped
2025-07-18T15:05:15.112Z,1752851115.112 [Default](DEBUG): Aggregate::uninitialize Default
2025-07-18T15:05:15.112Z,1752851115.112 [Default:B.GoToSurface] Stopped
2025-07-18T15:05:15.112Z,1752851115.112 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-18T15:05:15.112Z,1752851115.112 [Default:CheckIn] Stopped
2025-07-18T15:05:15.112Z,1752851115.112 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-18T15:05:15.112Z,1752851115.112 [Default:CheckIn:Read_Iridium] Stopped
2025-07-18T15:05:15.115Z,1752851115.115 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-07-18T15:05:15.116Z,1752851115.116 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-07-18T15:05:15.116Z,1752851115.116 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-07-18T15:05:15.116Z,1752851115.116 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-07-18T15:05:15.117Z,1752851115.117 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-07-18T15:05:15.117Z,1752851115.117 [BuoyancyServo](INFO): Powering down
2025-07-18T15:05:15.130Z,1752851115.130 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-07-18T15:05:15.130Z,1752851115.130 [ElevatorServo](INFO): Powering down
2025-07-18T15:05:15.131Z,1752851115.131 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2025-07-18T15:05:15.131Z,1752851115.131 [DockingStepper](INFO): Powering down
2025-07-18T15:05:15.132Z,1752851115.132 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-07-18T15:05:15.132Z,1752851115.132 [MassServo](INFO): Powering down
2025-07-18T15:05:15.133Z,1752851115.133 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-07-18T15:05:15.133Z,1752851115.133 [RudderServo](INFO): Powering down
2025-07-18T15:05:15.134Z,1752851115.134 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-07-18T15:05:15.134Z,1752851115.134 [ThrusterHE](INFO): Powering down
2025-07-18T15:05:15.135Z,1752851115.135 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-07-18T15:05:15.135Z,1752851115.135 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-07-18T15:05:15.135Z,1752851115.135 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-07-18T15:05:15.135Z,1752851115.135 [CBIT](DEBUG): Powering off loads.
2025-07-18T15:05:15.150Z,1752851115.150 [CBIT](DEBUG): Disabling WDT.
2025-07-18T15:05:15.162Z,1752851115.162 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-07-18T15:05:15.162Z,1752851115.162 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-07-18T15:05:15.163Z,1752851115.163 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:15.212Z,1752851115.212 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:15.215Z,1752851115.215 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:15.221Z,1752851115.221 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:15.227Z,1752851115.227 [DAT ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:15.270Z,1752851115.270 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:15.326Z,1752851115.326 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:15.332Z,1752851115.332 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:15.341Z,1752851115.341 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-07-18T15:05:15.341Z,1752851115.341 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-07-18T15:05:15.356Z,1752851115.356 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-07-18T15:05:15.431Z,1752851115.431 [logger ThreadHandler](INFO): Thread cancelled.