2025-10-01T16:28:44.924Z,1759336124.924 [Supervisor](DEBUG): Initializing supervisor.
2025-10-01T16:28:44.928Z,1759336124.928 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-10-01T16:28:44.929Z,1759336124.929 [SyncHandler](INFO): Protected caller Thread ID is 7908
2025-10-01T16:28:44.929Z,1759336124.929 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-10-01T16:28:44.930Z,1759336124.930 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-10-01T16:28:44.931Z,1759336124.931 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7909
2025-10-01T16:28:44.935Z,1759336124.935 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-10-01T16:28:44.955Z,1759336124.955 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-10-01T16:28:44.956Z,1759336124.956 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-10-01T16:28:44.956Z,1759336124.956 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7910
2025-10-01T16:28:44.961Z,1759336124.961 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-10-01T16:28:44.962Z,1759336124.962 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-10-01T16:28:44.962Z,1759336124.962 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7911
2025-10-01T16:28:44.964Z,1759336124.964 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-10-01T16:28:44.965Z,1759336124.965 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-10-01T16:28:44.966Z,1759336124.966 [logger ThreadHandler](INFO): Protected caller Thread ID is 7912
2025-10-01T16:28:44.969Z,1759336124.969 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-10-01T16:28:44.970Z,1759336124.970 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-10-01T16:28:44.971Z,1759336124.971 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-10-01T16:28:45.255Z,1759336125.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-10-01T16:28:45.256Z,1759336125.256 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-10-01T16:28:45.358Z,1759336125.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-10-01T16:28:45.358Z,1759336125.358 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-10-01T16:28:45.974Z,1759336125.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-10-01T16:28:45.975Z,1759336125.975 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-10-01T16:28:46.338Z,1759336126.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-10-01T16:28:46.338Z,1759336126.338 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-10-01T16:28:46.446Z,1759336126.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-10-01T16:28:46.447Z,1759336126.447 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-10-01T16:28:46.878Z,1759336126.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-10-01T16:28:46.879Z,1759336126.879 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-10-01T16:28:47.121Z,1759336127.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-10-01T16:28:47.121Z,1759336127.121 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-10-01T16:28:47.678Z,1759336127.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-10-01T16:28:47.678Z,1759336127.678 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-10-01T16:28:47.830Z,1759336127.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-10-01T16:28:47.831Z,1759336127.831 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-10-01T16:28:47.923Z,1759336127.923 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-10-01T16:28:48.804Z,1759336128.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-10-01T16:28:48.805Z,1759336128.805 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-10-01T16:28:49.208Z,1759336129.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-10-01T16:28:49.209Z,1759336129.209 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-10-01T16:28:49.405Z,1759336129.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-10-01T16:28:49.406Z,1759336129.406 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-10-01T16:28:49.531Z,1759336129.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-10-01T16:28:49.532Z,1759336129.532 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-10-01T16:28:49.843Z,1759336129.843 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-10-01T16:28:49.844Z,1759336129.844 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-10-01T16:28:50.469Z,1759336130.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-10-01T16:28:50.471Z,1759336130.471 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2025-10-01T16:28:50.472Z,1759336130.472 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2025-10-01T16:28:50.618Z,1759336130.618 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2025-10-01T16:28:50.888Z,1759336130.888 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2025-10-01T16:28:50.984Z,1759336130.984 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2025-10-01T16:28:51.101Z,1759336131.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2025-10-01T16:28:51.208Z,1759336131.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2025-10-01T16:28:51.339Z,1759336131.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2025-10-01T16:28:51.503Z,1759336131.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2025-10-01T16:28:51.663Z,1759336131.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2025-10-01T16:28:51.784Z,1759336131.784 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2025-10-01T16:28:52.066Z,1759336132.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-10-01T16:28:52.068Z,1759336132.068 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2025-10-01T16:28:52.068Z,1759336132.068 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-10-01T16:28:52.075Z,1759336132.075 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-10-01T16:28:52.131Z,1759336132.131 [VerticalControl](DEBUG): Construct VerticalControl.
2025-10-01T16:28:52.193Z,1759336132.193 [VerticalControl] Loaded
2025-10-01T16:28:52.193Z,1759336132.193 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-10-01T16:28:52.196Z,1759336132.196 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-10-01T16:28:52.245Z,1759336132.245 [HorizontalControl] Loaded
2025-10-01T16:28:52.245Z,1759336132.245 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-10-01T16:28:52.247Z,1759336132.247 [SpeedControl](DEBUG): Construct SpeedControl.
2025-10-01T16:28:52.251Z,1759336132.251 [SpeedControl] Loaded
2025-10-01T16:28:52.251Z,1759336132.251 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-10-01T16:28:52.254Z,1759336132.254 [LoopControl](DEBUG): Construct LoopControl.
2025-10-01T16:28:52.254Z,1759336132.254 [LoopControl] Loaded
2025-10-01T16:28:52.254Z,1759336132.254 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-10-01T16:28:52.255Z,1759336132.255 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-10-01T16:28:52.255Z,1759336132.255 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-10-01T16:28:52.265Z,1759336132.265 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-10-01T16:28:52.266Z,1759336132.266 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-10-01T16:28:52.446Z,1759336132.446 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-10-01T16:28:52.446Z,1759336132.446 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-10-01T16:28:52.585Z,1759336132.585 [BuoyancyServo] Loaded
2025-10-01T16:28:52.585Z,1759336132.585 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-10-01T16:28:52.608Z,1759336132.608 [ElevatorServo] Loaded
2025-10-01T16:28:52.608Z,1759336132.608 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-10-01T16:28:52.629Z,1759336132.629 [MassServo] Loaded
2025-10-01T16:28:52.630Z,1759336132.630 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-10-01T16:28:52.651Z,1759336132.651 [RudderServo] Loaded
2025-10-01T16:28:52.651Z,1759336132.651 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-10-01T16:28:52.668Z,1759336132.668 [ThrusterHE] Loaded
2025-10-01T16:28:52.668Z,1759336132.668 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-10-01T16:28:52.668Z,1759336132.668 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-10-01T16:28:52.669Z,1759336132.669 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-10-01T16:28:52.794Z,1759336132.794 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-10-01T16:28:52.795Z,1759336132.795 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-10-01T16:28:52.809Z,1759336132.809 [NavChart] Loaded
2025-10-01T16:28:52.809Z,1759336132.809 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-10-01T16:28:52.815Z,1759336132.815 [UniversalFixResidualReporter] Loaded
2025-10-01T16:28:52.815Z,1759336132.815 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-10-01T16:28:52.816Z,1759336132.816 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-10-01T16:28:52.816Z,1759336132.816 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-10-01T16:28:52.877Z,1759336132.877 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-10-01T16:28:52.877Z,1759336132.877 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-10-01T16:28:53.372Z,1759336133.372 [AHRS_M2] Loaded
2025-10-01T16:28:53.372Z,1759336133.372 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-10-01T16:28:54.228Z,1759336134.228 [BPC1] Loaded
2025-10-01T16:28:54.228Z,1759336134.228 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-10-01T16:28:54.331Z,1759336134.331 [DAT] Loaded
2025-10-01T16:28:54.331Z,1759336134.331 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-10-01T16:28:54.332Z,1759336134.332 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094D4E0
2025-10-01T16:28:54.333Z,1759336134.333 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7992
2025-10-01T16:28:54.408Z,1759336134.408 [DataOverHttps] Loaded
2025-10-01T16:28:54.408Z,1759336134.408 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-10-01T16:28:54.409Z,1759336134.409 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097D4E0
2025-10-01T16:28:54.410Z,1759336134.410 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7993
2025-10-01T16:28:54.434Z,1759336134.434 [Depth_Keller] Loaded
2025-10-01T16:28:54.434Z,1759336134.434 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-10-01T16:28:54.439Z,1759336134.439 [DropWeight] Loaded
2025-10-01T16:28:54.440Z,1759336134.440 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-10-01T16:28:54.506Z,1759336134.506 [NAL9602] Loaded
2025-10-01T16:28:54.506Z,1759336134.506 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-10-01T16:28:54.537Z,1759336134.537 [Onboard] Loaded
2025-10-01T16:28:54.537Z,1759336134.537 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-10-01T16:28:54.538Z,1759336134.538 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AD4E0
2025-10-01T16:28:54.539Z,1759336134.539 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7994
2025-10-01T16:28:54.554Z,1759336134.554 [Power24vConverter] Loaded
2025-10-01T16:28:54.554Z,1759336134.554 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-10-01T16:28:54.571Z,1759336134.571 [Radio_Surface] Loaded
2025-10-01T16:28:54.571Z,1759336134.571 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-10-01T16:28:54.572Z,1759336134.572 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0
2025-10-01T16:28:54.573Z,1759336134.573 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7995
2025-10-01T16:28:54.588Z,1759336134.588 [Sonardyne_Nano] Loaded
2025-10-01T16:28:54.589Z,1759336134.589 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-10-01T16:28:54.589Z,1759336134.589 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-10-01T16:28:54.590Z,1759336134.590 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-10-01T16:28:54.641Z,1759336134.641 [DepthRateCalculator] Loaded
2025-10-01T16:28:54.641Z,1759336134.641 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-10-01T16:28:54.647Z,1759336134.647 [PitchRateCalculator] Loaded
2025-10-01T16:28:54.647Z,1759336134.647 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-10-01T16:28:54.656Z,1759336134.656 [SpeedCalculator] Loaded
2025-10-01T16:28:54.656Z,1759336134.656 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-10-01T16:28:54.661Z,1759336134.661 [YawRateCalculator] Loaded
2025-10-01T16:28:54.661Z,1759336134.661 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-10-01T16:28:54.680Z,1759336134.680 [ElevatorOffsetCalculator] Loaded
2025-10-01T16:28:54.681Z,1759336134.681 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-10-01T16:28:54.681Z,1759336134.681 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-10-01T16:28:54.682Z,1759336134.682 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-10-01T16:28:54.816Z,1759336134.816 [CANONSampler] Loaded
2025-10-01T16:28:54.817Z,1759336134.817 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2025-10-01T16:28:54.880Z,1759336134.880 [CTD_Seabird] Loaded
2025-10-01T16:28:54.880Z,1759336134.880 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-10-01T16:28:54.881Z,1759336134.881 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A9B4E0
2025-10-01T16:28:54.881Z,1759336134.881 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7996
2025-10-01T16:28:54.904Z,1759336134.904 [PAR_Licor] Loaded
2025-10-01T16:28:54.905Z,1759336134.905 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-10-01T16:28:54.951Z,1759336134.951 [WetLabsBB2FL] Loaded
2025-10-01T16:28:54.951Z,1759336134.951 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-10-01T16:28:54.952Z,1759336134.952 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ACB4E0
2025-10-01T16:28:54.953Z,1759336134.953 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7997
2025-10-01T16:28:54.953Z,1759336134.953 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-10-01T16:28:54.954Z,1759336134.954 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-10-01T16:28:55.112Z,1759336135.112 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-10-01T16:28:55.113Z,1759336135.113 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-10-01T16:28:55.753Z,1759336135.753 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-10-01T16:28:55.753Z,1759336135.753 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-10-01T16:28:55.918Z,1759336135.918 [SBIT](DEBUG): Construct Startup Built In Test.
2025-10-01T16:28:55.927Z,1759336135.927 [SBIT] Loaded
2025-10-01T16:28:55.928Z,1759336135.928 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-10-01T16:28:55.931Z,1759336135.931 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-10-01T16:28:55.944Z,1759336135.944 [IBIT] Loaded
2025-10-01T16:28:55.944Z,1759336135.944 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-10-01T16:28:55.949Z,1759336135.949 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-10-01T16:28:56.034Z,1759336136.034 [CBIT] Loaded
2025-10-01T16:28:56.034Z,1759336136.034 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-10-01T16:28:56.068Z,1759336136.068 [GFScanner] Loaded
2025-10-01T16:28:56.068Z,1759336136.068 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-10-01T16:28:56.068Z,1759336136.068 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-10-01T16:28:56.069Z,1759336136.069 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-10-01T16:28:56.207Z,1759336136.207 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-10-01T16:28:56.214Z,1759336136.214 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-10-01T16:28:56.217Z,1759336136.217 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-10-01T16:28:56.228Z,1759336136.228 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-10-01T16:28:56.229Z,1759336136.229 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2025-10-01T16:28:56.230Z,1759336136.230 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7998
2025-10-01T16:28:56.234Z,1759336136.234 [Supervisor](INFO): Main Thread ID is 7907
2025-10-01T16:28:56.234Z,1759336136.234 [Supervisor](DEBUG): Running supervisor.
2025-10-01T16:28:56.235Z,1759336136.235 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7999
2025-10-01T16:28:56.236Z,1759336136.236 [CommandExec](INFO): Initializing the command executive.
2025-10-01T16:28:56.237Z,1759336136.237 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8000
2025-10-01T16:28:56.241Z,1759336136.241 [controlThread ThreadHandler](INFO): Handler Thread ID is 8001
2025-10-01T16:28:56.241Z,1759336136.241 [controlThread](DEBUG): Initializing ControlThread
2025-10-01T16:28:56.242Z,1759336136.242 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-10-01T16:28:56.244Z,1759336136.244 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-10-01T16:28:56.244Z,1759336136.244 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-10-01T16:28:56.245Z,1759336136.245 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-10-01T16:28:56.247Z,1759336136.247 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-10-01T16:28:56.247Z,1759336136.247 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-10-01T16:28:56.250Z,1759336136.250 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-10-01T16:28:56.250Z,1759336136.250 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-10-01T16:28:56.251Z,1759336136.251 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-10-01T16:28:56.251Z,1759336136.251 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-10-01T16:28:56.252Z,1759336136.252 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-10-01T16:28:56.254Z,1759336136.254 [SBIT](INFO): Initialize SBIT Component.
2025-10-01T16:28:56.255Z,1759336136.255 [SBIT](IMPORTANT): git: 2025-09-19
2025-10-01T16:28:56.255Z,1759336136.255 [SBIT](INFO): git hash: f5f7d10969816d125effdffd07357c8d50a3a0d0
2025-10-01T16:28:56.255Z,1759336136.255 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-10-01T16:28:56.256Z,1759336136.256 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-10-01T16:28:56.257Z,1759336136.257 [SBIT](INFO): Beginning SBIT in 68.000000 seconds.
2025-10-01T16:28:56.258Z,1759336136.258 [IBIT](INFO): Initialize IBIT Component.
2025-10-01T16:28:56.259Z,1759336136.259 [CBIT](DEBUG): Initialize CBIT Component.
2025-10-01T16:28:56.260Z,1759336136.260 [logger ThreadHandler](INFO): Handler Thread ID is 8002
2025-10-01T16:28:56.270Z,1759336136.270 [CBIT](DEBUG): Initialized mux pins.
2025-10-01T16:28:56.270Z,1759336136.270 [CBIT](DEBUG): Initializing the watchdog timer.
2025-10-01T16:28:56.278Z,1759336136.278 [DAT ThreadHandler](INFO): Handler Thread ID is 8003
2025-10-01T16:28:56.279Z,1759336136.279 [DAT](INFO): Powering up
2025-10-01T16:28:56.279Z,1759336136.279 [DAT](DEBUG): Initializing DAT.
2025-10-01T16:28:56.284Z,1759336136.284 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8005
2025-10-01T16:28:56.286Z,1759336136.286 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-10-01T16:28:56.294Z,1759336136.294 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-10-01T16:28:56.294Z,1759336136.294 [CBIT](DEBUG): Initializing heartbeat.
2025-10-01T16:28:56.296Z,1759336136.296 [Onboard ThreadHandler](INFO): Handler Thread ID is 8006
2025-10-01T16:28:56.315Z,1759336136.315 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8007
2025-10-01T16:28:56.330Z,1759336136.330 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8008
2025-10-01T16:28:56.331Z,1759336136.331 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-10-01T16:28:56.335Z,1759336136.335 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8010
2025-10-01T16:28:56.339Z,1759336136.339 [WetLabsBB2FL](INFO): Powering up
2025-10-01T16:28:56.340Z,1759336136.340 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8012
2025-10-01T16:28:56.343Z,1759336136.343 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-10-01T16:28:56.344Z,1759336136.344 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-10-01T16:28:56.344Z,1759336136.344 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-10-01T16:28:56.344Z,1759336136.344 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-10-01T16:28:56.344Z,1759336136.344 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-10-01T16:28:56.344Z,1759336136.344 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-10-01T16:28:56.344Z,1759336136.344 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-10-01T16:28:56.345Z,1759336136.345 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-10-01T16:28:56.366Z,1759336136.366 [CBIT](DEBUG): Deactivating emergency mode.
2025-10-01T16:28:56.406Z,1759336136.406 [CBIT](DEBUG): Backplane powered.
2025-10-01T16:28:56.406Z,1759336136.406 [GFScanner](DEBUG): Initializing GFScanner
2025-10-01T16:28:56.407Z,1759336136.407 [GFScanner](DEBUG): Deactivating GF circuits.
2025-10-01T16:28:56.411Z,1759336136.411 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-10-01T16:28:56.412Z,1759336136.412 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-10-01T16:28:56.412Z,1759336136.412 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-10-01T16:28:56.414Z,1759336136.414 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-10-01T16:28:56.482Z,1759336136.482 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-10-01T16:28:56.487Z,1759336136.487 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-10-01T16:28:56.534Z,1759336136.534 [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-10-01T16:28:56.534Z,1759336136.534 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-10-01T16:28:56.535Z,1759336136.535 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-10-01T16:28:56.536Z,1759336136.536 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-10-01T16:28:56.718Z,1759336136.718 [Radio_Surface](INFO): Powering up
2025-10-01T16:28:56.896Z,1759336136.896 [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-10-01T16:28:56.903Z,1759336136.903 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-10-01T16:28:56.904Z,1759336136.904 [Default:A.Wait](DEBUG): Construct Wait.
2025-10-01T16:28:56.911Z,1759336136.911 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-10-01T16:28:56.952Z,1759336136.952 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-10-01T16:28:56.966Z,1759336136.966 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-10-01T16:28:56.996Z,1759336136.996 [Default:E.Execute](DEBUG): Construct Execute.
2025-10-01T16:28:56.999Z,1759336136.999 [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-10-01T16:28:57.011Z,1759336137.011 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,Reporter,LogSplitter,
2025-10-01T16:28:57.026Z,1759336137.026 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-10-01T16:28:57.094Z,1759336137.094 [Depth_Keller](INFO): Initializing.
2025-10-01T16:28:57.096Z,1759336137.096 [Power24vConverter](INFO): Powering up.
2025-10-01T16:28:57.096Z,1759336137.096 [Sonardyne_Nano](INFO): Initializing.
2025-10-01T16:28:57.110Z,1759336137.110 [CANONSampler](INFO): Powering down
2025-10-01T16:28:57.138Z,1759336137.138 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-10-01T16:28:57.165Z,1759336137.165 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-10-01T16:28:57.171Z,1759336137.171 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-10-01T16:28:57.172Z,1759336137.172 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-10-01T16:28:57.178Z,1759336137.178 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-10-01T16:28:57.179Z,1759336137.179 [MassServo](DEBUG): Initializing EZServoServo.
2025-10-01T16:28:57.186Z,1759336137.186 [MassServo](DEBUG): Initializing MassServo.
2025-10-01T16:28:57.187Z,1759336137.187 [RudderServo](DEBUG): Initializing EZServoServo.
2025-10-01T16:28:57.194Z,1759336137.194 [RudderServo](DEBUG): Initializing RudderServo.
2025-10-01T16:28:57.195Z,1759336137.195 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-10-01T16:28:57.202Z,1759336137.202 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-10-01T16:28:58.446Z,1759336138.446 [WetLabsBB2FL](INFO): Powering down
2025-10-01T16:28:59.045Z,1759336139.045 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-10-01T16:29:00.687Z,1759336140.687 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2025-10-01T16:29:02.919Z,1759336142.919 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-10-01T16:29:07.883Z,1759336147.883 [DAT](INFO): DAT read:
2025-10-01T16:29:07.884Z,1759336147.884 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-10-01T16:29:09.395Z,1759336149.395 [DAT](INFO): DAT read: MF Frequency Band
2025-10-01T16:29:09.397Z,1759336149.397 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-10-01T16:29:09.397Z,1759336149.397 [DAT](INFO): DAT read: Sep 29 2025 18:09:53
2025-10-01T16:29:10.268Z,1759336150.268 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004273
2025-10-01T16:29:10.655Z,1759336150.655 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-10-01T16:29:10.656Z,1759336150.656 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-10-01T16:29:10.657Z,1759336150.657 [DAT](INFO): commRate: 1200
2025-10-01T16:29:12.722Z,1759336152.722 [DAT](INFO): entering command mode
2025-10-01T16:29:12.923Z,1759336152.923 [DAT](INFO): DAT read:
2025-10-01T16:29:12.923Z,1759336152.923 [DAT](INFO): DAT read: user:1>
2025-10-01T16:29:12.924Z,1759336152.924 [DAT](INFO): setting verbose to 3
2025-10-01T16:29:13.175Z,1759336153.175 [DAT](INFO): DAT read: user:1>
2025-10-01T16:29:13.176Z,1759336153.176 [DAT](INFO): DAT read: Verbose | 3
2025-10-01T16:29:13.176Z,1759336153.176 [DAT](INFO): set verbose to 3
2025-10-01T16:29:13.176Z,1759336153.176 [DAT](INFO): setting DatVerbose to 27440
2025-10-01T16:29:13.427Z,1759336153.427 [DAT](INFO): DAT read: user:2>
2025-10-01T16:29:13.428Z,1759336153.428 [DAT](INFO): DAT read: DatVerbose | 27440
2025-10-01T16:29:13.428Z,1759336153.428 [DAT](INFO): set DatVerbose to 27440
2025-10-01T16:29:13.428Z,1759336153.428 [DAT](INFO): setting transmit power to 8
2025-10-01T16:29:13.679Z,1759336153.679 [DAT](INFO): DAT read: user:3>
2025-10-01T16:29:13.680Z,1759336153.680 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-10-01T16:29:13.680Z,1759336153.680 [DAT](INFO): set transmit power to 8
2025-10-01T16:29:13.681Z,1759336153.681 [DAT](INFO): setting local address to 8
2025-10-01T16:29:13.931Z,1759336153.931 [DAT](INFO): DAT read: user:4>
2025-10-01T16:29:13.932Z,1759336153.932 [DAT](INFO): DAT read: LocalAddr | 8
2025-10-01T16:29:13.932Z,1759336153.932 [DAT](INFO): set local address to 8
2025-10-01T16:29:13.933Z,1759336153.933 [DAT](INFO): Setting time to: 16:29:13 And date to:10/1/2025
2025-10-01T16:29:14.183Z,1759336154.183 [DAT](INFO): DAT read: user:5>
2025-10-01T16:29:14.184Z,1759336154.184 [DAT](INFO): DAT read: Wed Oct 1, 2025 16:29:13
2025-10-01T16:29:14.184Z,1759336154.184 [DAT](INFO): Local DAT time set to Wed Oct 1, 2025 16:29:13
2025-10-01T16:29:24.900Z,1759336164.900 [NAL9602](INFO): Powering up NAL9602
2025-10-01T16:29:35.808Z,1759336175.808 [NAL9602](INFO): NAL9602 initialized
2025-10-01T16:29:52.781Z,1759336192.781 [NAL9602](INFO): SBD MO Status=0, MOMSN=64815, MT Status=0, MTMSN=0
2025-10-01T16:29:52.781Z,1759336192.781 [NAL9602](INFO): No messages in MT queue
2025-10-01T16:30:02.077Z,1759336202.077 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163000.00,A,3648.17632,N,12147.28386,W,0.019,0.00,011025,,,A*79
2025-10-01T16:30:02.081Z,1759336202.081 [NAL9602](INFO): GPS fix at 20251001T163000: (36.802939, -121.788064)
2025-10-01T16:30:02.904Z,1759336202.904 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T16:30:04.947Z,1759336204.947 [SBIT](IMPORTANT): Beginning Startup BIT
2025-10-01T16:30:04.952Z,1759336204.952 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-10-01T16:30:09.423Z,1759336209.423 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.002243
CHAN A1 (24V): 0.000648
CHAN A2 (12V): -0.002347
CHAN A3 (5V): -0.001362
CHAN B0 (3.3V): -0.000295
CHAN B1 (3.15aV): -0.000070
CHAN B2 (3.15bV): 4.767929
CHAN B3 (GND): -0.000069
OPEN: -0.000101
Full Scale: +/- 1 mA
2025-10-01T16:30:14.000Z,1759336215.000 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T16:30:26.787Z,1759336226.787 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T16:30:34.463Z,1759336234.463 [NAL9602](INFO): Not Powering down - fast GPS
2025-10-01T16:30:38.503Z,1759336238.503 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T16:30:51.053Z,1759336251.053 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T16:30:59.152Z,1759336259.152 [SBIT](IMPORTANT): SBIT PASSED
2025-10-01T16:30:59.152Z,1759336259.152 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-10-01T16:30:59.153Z,1759336259.153 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count;
2025-10-01T16:30:59.153Z,1759336259.153 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2025-10-01T16:30:59.153Z,1759336259.153 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2025-10-01T16:30:59.153Z,1759336259.153 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=214.591627 cubic_centimeter;
2025-10-01T16:30:59.153Z,1759336259.153 [SBIT](IMPORTANT): VerticalControl.massDefault=12.113491 millimeter;
2025-10-01T16:30:59.571Z,1759336259.571 [MissionManager](IMPORTANT): Started mission Startup
2025-10-01T16:30:59.571Z,1759336259.571 [Startup] Running Loop=1
2025-10-01T16:30:59.571Z,1759336259.571 [Startup](DEBUG): Aggregate::initialize Startup
2025-10-01T16:30:59.571Z,1759336259.571 [Startup:A.GoToSurface] Running Loop=1
2025-10-01T16:30:59.571Z,1759336259.571 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-10-01T16:30:59.572Z,1759336259.572 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-10-01T16:30:59.572Z,1759336259.572 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-10-01T16:30:59.573Z,1759336259.573 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-10-01T16:30:59.573Z,1759336259.573 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-10-01T16:30:59.573Z,1759336259.573 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-10-01T16:30:59.574Z,1759336259.574 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-10-01T16:30:59.575Z,1759336259.575 [Startup:StartupSatComms] Running Loop=1
2025-10-01T16:30:59.575Z,1759336259.575 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-10-01T16:30:59.575Z,1759336259.575 [Startup:StartupSatComms:A] Running Loop=1
2025-10-01T16:30:59.962Z,1759336259.962 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-10-01T16:31:01.600Z,1759336261.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163059.00,A,3648.17637,N,12147.28445,W,0.000,0.00,011025,,,A*70
2025-10-01T16:31:01.606Z,1759336261.606 [NAL9602](INFO): GPS fix at 20251001T163059: (36.802940, -121.788074)
2025-10-01T16:31:01.621Z,1759336261.621 [Startup:StartupSatComms:A] Stopped
2025-10-01T16:31:01.621Z,1759336261.621 [Startup:StartupSatComms:B] Running Loop=1
2025-10-01T16:31:02.038Z,1759336262.038 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-10-01T16:31:02.934Z,1759336262.934 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T16:31:05.358Z,1759336265.358 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250929T180106/Courier0004.lzma
2025-10-01T16:31:06.360Z,1759336266.360 [DataOverHttps](INFO): Moved sent file to Logs/20250929T180106/Courier0004.lzma.bak
2025-10-01T16:31:06.360Z,1759336266.360 [DataOverHttps](INFO): SBD MOMSN=26088290
2025-10-01T16:31:09.023Z,1759336269.023 [NAL9602](INFO): SBD MO Status=0, MOMSN=64816, MT Status=0, MTMSN=0
2025-10-01T16:31:09.023Z,1759336269.023 [NAL9602](INFO): No messages in MT queue
2025-10-01T16:31:14.280Z,1759336274.280 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T16:31:14.703Z,1759336274.703 [CommandExec](IMPORTANT): got command ibit
2025-10-01T16:31:14.736Z,1759336274.736 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-10-01T16:31:14.736Z,1759336274.736 [IBIT](IMPORTANT): Beginning control surface checks.
2025-10-01T16:31:14.739Z,1759336274.739 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-10-01T16:31:16.293Z,1759336276.293 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163114.00,A,3648.17665,N,12147.28490,W,0.019,0.00,011025,,,A*7F
2025-10-01T16:31:16.296Z,1759336276.296 [NAL9602](INFO): GPS fix at 20251001T163114: (36.802944, -121.788082)
2025-10-01T16:31:18.741Z,1759336278.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163117.00,A,3648.17669,N,12147.28503,W,0.039,0.00,011025,,,A*79
2025-10-01T16:31:18.752Z,1759336278.752 [NAL9602](INFO): GPS fix at 20251001T163117: (36.802945, -121.788084)
2025-10-01T16:31:19.266Z,1759336279.266 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002221
CHAN A1 (24V): 0.000295
CHAN A2 (12V): -0.002777
CHAN A3 (5V): -0.001328
CHAN B0 (3.3V): 0.000011
CHAN B1 (3.15aV): -0.000219
CHAN B2 (3.15bV): 0.000011
CHAN B3 (GND): 0.000335
OPEN: -0.000470
Full Scale: +/- 1 mA
2025-10-01T16:31:21.973Z,1759336281.973 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163120.00,A,3648.17674,N,12147.28514,W,0.039,0.00,011025,,,A*77
2025-10-01T16:31:21.975Z,1759336281.975 [NAL9602](INFO): GPS fix at 20251001T163120: (36.802946, -121.788086)
2025-10-01T16:31:22.923Z,1759336282.923 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20251001T162844/Courier0000.lzma
2025-10-01T16:31:23.924Z,1759336283.924 [DataOverHttps](INFO): Moved sent file to Logs/20251001T162844/Courier0000.lzma.bak
2025-10-01T16:31:23.924Z,1759336283.924 [DataOverHttps](INFO): SBD MOMSN=26088292
2025-10-01T16:31:24.814Z,1759336284.814 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163123.00,A,3648.17675,N,12147.28523,W,0.000,0.00,011025,,,A*7B
2025-10-01T16:31:24.816Z,1759336284.816 [NAL9602](INFO): GPS fix at 20251001T163123: (36.802946, -121.788087)
2025-10-01T16:31:26.007Z,1759336286.007 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T16:31:28.041Z,1759336288.041 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163126.00,A,3648.17677,N,12147.28529,W,0.019,0.00,011025,,,A*7E
2025-10-01T16:31:28.043Z,1759336288.043 [NAL9602](INFO): GPS fix at 20251001T163126: (36.802946, -121.788088)
2025-10-01T16:31:30.869Z,1759336290.869 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163129.00,A,3648.17681,N,12147.28536,W,0.019,0.00,011025,,,A*76
2025-10-01T16:31:30.871Z,1759336290.871 [NAL9602](INFO): GPS fix at 20251001T163129: (36.802947, -121.788089)
2025-10-01T16:31:34.109Z,1759336294.109 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163132.00,A,3648.17681,N,12147.28539,W,0.019,0.00,011025,,,A*73
2025-10-01T16:31:34.111Z,1759336294.111 [NAL9602](INFO): GPS fix at 20251001T163132: (36.802947, -121.788090)
2025-10-01T16:31:37.028Z,1759336297.028 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163135.00,A,3648.17681,N,12147.28542,W,0.058,0.00,011025,,,A*7D
2025-10-01T16:31:37.042Z,1759336297.042 [NAL9602](INFO): GPS fix at 20251001T163135: (36.802947, -121.788090)
2025-10-01T16:31:39.759Z,1759336299.759 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163138.00,A,3648.17676,N,12147.28544,W,0.019,0.00,011025,,,A*7B
2025-10-01T16:31:39.761Z,1759336299.761 [NAL9602](INFO): GPS fix at 20251001T163138: (36.802946, -121.788091)
2025-10-01T16:31:40.606Z,1759336300.606 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250929T180106/Express0005.lzma
2025-10-01T16:31:41.608Z,1759336301.608 [DataOverHttps](INFO): Moved sent file to Logs/20250929T180106/Express0005.lzma.bak
2025-10-01T16:31:41.608Z,1759336301.608 [DataOverHttps](INFO): SBD MOMSN=26088296
2025-10-01T16:31:42.985Z,1759336302.985 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163141.00,A,3648.17668,N,12147.28547,W,0.019,0.00,011025,,,A*79
2025-10-01T16:31:42.987Z,1759336302.987 [NAL9602](INFO): GPS fix at 20251001T163141: (36.802945, -121.788091)
2025-10-01T16:31:45.814Z,1759336305.814 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163144.00,A,3648.17660,N,12147.28551,W,0.019,0.00,011025,,,A*73
2025-10-01T16:31:45.816Z,1759336305.816 [NAL9602](INFO): GPS fix at 20251001T163144: (36.802943, -121.788092)
2025-10-01T16:31:49.049Z,1759336309.049 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163147.00,A,3648.17653,N,12147.28554,W,0.039,0.00,011025,,,A*77
2025-10-01T16:31:49.052Z,1759336309.052 [NAL9602](INFO): GPS fix at 20251001T163147: (36.802942, -121.788092)
2025-10-01T16:31:51.874Z,1759336311.874 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163150.00,A,3648.17649,N,12147.28557,W,0.058,0.00,011025,,,A*7E
2025-10-01T16:31:51.876Z,1759336311.876 [NAL9602](INFO): GPS fix at 20251001T163150: (36.802942, -121.788093)
2025-10-01T16:31:55.101Z,1759336315.101 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163153.00,A,3648.17647,N,12147.28561,W,0.019,0.00,011025,,,A*73
2025-10-01T16:31:55.103Z,1759336315.103 [NAL9602](INFO): GPS fix at 20251001T163153: (36.802941, -121.788094)
2025-10-01T16:31:57.934Z,1759336317.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163156.00,A,3648.17645,N,12147.28562,W,0.019,0.00,011025,,,A*77
2025-10-01T16:31:57.936Z,1759336317.936 [NAL9602](INFO): GPS fix at 20251001T163156: (36.802941, -121.788094)
2025-10-01T16:31:58.660Z,1759336318.660 [DataOverHttps](INFO): Sending 1016 bytes from file Logs/20251001T162844/Express0001.lzma
2025-10-01T16:31:59.664Z,1759336319.664 [DataOverHttps](INFO): Moved sent file to Logs/20251001T162844/Express0001.lzma.bak
2025-10-01T16:31:59.665Z,1759336319.665 [DataOverHttps](INFO): SBD MOMSN=26088301
2025-10-01T16:32:00.769Z,1759336320.769 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163159.00,A,3648.17645,N,12147.28560,W,0.058,0.00,011025,,,A*7F
2025-10-01T16:32:00.772Z,1759336320.772 [NAL9602](INFO): GPS fix at 20251001T163159: (36.802941, -121.788093)
2025-10-01T16:32:01.197Z,1759336321.197 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802940 Longitude: -121.788094
2025-10-01T16:32:01.586Z,1759336321.586 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2025-10-01T16:32:01.993Z,1759336321.993 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-10-01T16:32:01.993Z,1759336321.993 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-10-01T16:32:01.994Z,1759336321.994 [IBIT](IMPORTANT): Pressure:9.442718 PSI
2025-10-01T16:32:01.994Z,1759336321.994 [IBIT](IMPORTANT): Humidity:17.677938 %
2025-10-01T16:32:02.408Z,1759336322.408 [IBIT](IMPORTANT): Vehicle Pitch:-0.452021 degrees
2025-10-01T16:32:02.408Z,1759336322.408 [IBIT](IMPORTANT): Vehicle Roll:8.710530 degrees
2025-10-01T16:32:02.408Z,1759336322.408 [IBIT](IMPORTANT): Vehicle Heading:276.946075 degrees
2025-10-01T16:32:02.832Z,1759336322.832 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-10-01T16:32:02.833Z,1759336322.833 [IBIT](IMPORTANT): buoyancyNeutral: 214.591629 cc
2025-10-01T16:32:02.833Z,1759336322.833 [IBIT](IMPORTANT): massDefault: 1.211349 cm
2025-10-01T16:32:02.833Z,1759336322.833 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2025-10-01T16:32:02.834Z,1759336322.834 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2025-10-01T16:32:02.838Z,1759336322.838 [IBIT](IMPORTANT): IBIT PASSED
2025-10-01T16:32:03.206Z,1759336323.206 [Startup:StartupSatComms:B](INFO): Timed out from 2025-10-01T16:31:01.6Z
2025-10-01T16:32:03.206Z,1759336323.206 [Startup:StartupSatComms:B] Stopped
2025-10-01T16:32:03.210Z,1759336323.210 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-10-01T16:32:03.210Z,1759336323.210 [Startup:StartupSatComms] Stopped
2025-10-01T16:32:03.210Z,1759336323.210 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-10-01T16:32:03.211Z,1759336323.211 [Startup](INFO): Completed Startup
2025-10-01T16:32:03.211Z,1759336323.211 [MissionManager](INFO): Startup is completed.
2025-10-01T16:32:03.211Z,1759336323.211 [MissionManager](INFO): Uninitializing Mission Startup
2025-10-01T16:32:03.211Z,1759336323.211 [Startup] Stopped
2025-10-01T16:32:03.211Z,1759336323.211 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-10-01T16:32:03.212Z,1759336323.212 [Startup:A.GoToSurface] Stopped
2025-10-01T16:32:03.212Z,1759336323.212 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-10-01T16:32:03.730Z,1759336323.730 [MissionManager](IMPORTANT): Started mission Default
2025-10-01T16:32:03.734Z,1759336323.734 [Default] Running Loop=1
2025-10-01T16:32:03.734Z,1759336323.734 [Default](DEBUG): Aggregate::initialize Default
2025-10-01T16:32:03.734Z,1759336323.734 [Default:B.GoToSurface] Running Loop=1
2025-10-01T16:32:03.734Z,1759336323.734 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-10-01T16:32:03.735Z,1759336323.735 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-10-01T16:32:03.735Z,1759336323.735 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-10-01T16:32:03.735Z,1759336323.735 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-10-01T16:32:03.735Z,1759336323.735 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-10-01T16:32:03.736Z,1759336323.736 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-10-01T16:32:03.736Z,1759336323.736 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-10-01T16:32:03.736Z,1759336323.736 [Default:A.Wait] Running Loop=1
2025-10-01T16:32:03.736Z,1759336323.736 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-10-01T16:32:16.990Z,1759336336.990 [Default:A.Wait](INFO): Done Waiting.
2025-10-01T16:32:16.990Z,1759336336.990 [Default:A.Wait] Stopped
2025-10-01T16:32:16.990Z,1759336336.990 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-10-01T16:32:17.334Z,1759336337.334 [Default:CheckIn] Running Loop=1
2025-10-01T16:32:17.335Z,1759336337.335 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-10-01T16:32:17.335Z,1759336337.335 [Default:CheckIn:Read_GPS] Running Loop=1
2025-10-01T16:32:17.756Z,1759336337.756 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-10-01T16:32:19.343Z,1759336339.343 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163217.00,A,3648.17645,N,12147.28563,W,0.058,0.00,011025,,,A*75
2025-10-01T16:32:19.345Z,1759336339.345 [NAL9602](INFO): GPS fix at 20251001T163217: (36.802941, -121.788094)
2025-10-01T16:32:19.365Z,1759336339.365 [Default:CheckIn:Read_GPS] Stopped
2025-10-01T16:32:19.365Z,1759336339.365 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-10-01T16:32:19.772Z,1759336339.772 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-10-01T16:32:30.069Z,1759336350.069 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251001T162844/Courier0004.lzma
2025-10-01T16:32:31.068Z,1759336351.068 [DataOverHttps](INFO): Moved sent file to Logs/20251001T162844/Courier0004.lzma.bak
2025-10-01T16:32:31.068Z,1759336351.068 [DataOverHttps](INFO): SBD MOMSN=26088322
2025-10-01T16:32:47.252Z,1759336367.252 [DataOverHttps](INFO): Sending 1009 bytes from file Logs/20251001T162844/Express0005.lzma
2025-10-01T16:32:48.252Z,1759336368.252 [DataOverHttps](INFO): Moved sent file to Logs/20251001T162844/Express0005.lzma.bak
2025-10-01T16:32:48.252Z,1759336368.252 [DataOverHttps](INFO): SBD MOMSN=26088325
2025-10-01T16:32:49.747Z,1759336369.747 [Default:CheckIn:Read_Iridium] Stopped
2025-10-01T16:32:49.747Z,1759336369.747 [Default:CheckIn:C.Wait] Running Loop=1
2025-10-01T16:32:49.747Z,1759336369.747 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-10-01T16:32:51.355Z,1759336371.355 [NAL9602](INFO): Not Powering down - fast GPS
2025-10-01T16:33:50.346Z,1759336430.346 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:33:59.224Z,1759336439.224 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T16:34:11.746Z,1759336451.746 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T16:34:21.486Z,1759336461.486 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:34:24.271Z,1759336464.271 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T16:34:37.203Z,1759336477.203 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T16:34:50.530Z,1759336490.530 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T16:34:52.622Z,1759336492.622 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:35:03.459Z,1759336503.459 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T16:35:16.385Z,1759336516.385 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T16:35:23.746Z,1759336523.746 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:35:28.909Z,1759336528.909 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T16:35:42.243Z,1759336542.243 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T16:35:54.769Z,1759336554.769 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T16:35:54.870Z,1759336554.870 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:35:57.883Z,1759336557.883 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.759369
2025-10-01T16:36:08.502Z,1759336568.502 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T16:36:22.248Z,1759336582.248 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5.
2025-10-01T16:36:22.252Z,1759336582.252 [BPC1](INFO): Received data from all battery sticks.
2025-10-01T16:36:56.115Z,1759336616.115 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:37:27.238Z,1759336647.238 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:37:50.321Z,1759336670.321 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-10-01T16:37:50.321Z,1759336670.321 [Default:CheckIn:C.Wait] Stopped
2025-10-01T16:37:50.321Z,1759336670.321 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-10-01T16:37:50.321Z,1759336670.321 [Default:CheckIn:D] Running Loop=1
2025-10-01T16:37:50.733Z,1759336670.733 [Default:CheckIn:D] Stopped
2025-10-01T16:37:50.733Z,1759336670.733 [Default:CheckIn:E] Running Loop=1
2025-10-01T16:37:51.133Z,1759336671.133 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.783387 min
2025-10-01T16:37:51.133Z,1759336671.133 [Default:CheckIn:E] Stopped
2025-10-01T16:37:51.133Z,1759336671.133 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-10-01T16:37:51.133Z,1759336671.133 [Default:CheckIn] Stopped
2025-10-01T16:37:51.133Z,1759336671.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-10-01T16:37:51.133Z,1759336671.133 [Default:CheckIn](INFO): Running loop #2
2025-10-01T16:37:51.133Z,1759336671.133 [Default:CheckIn] Running Loop=2
2025-10-01T16:37:51.133Z,1759336671.133 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-10-01T16:37:51.133Z,1759336671.133 [Default:CheckIn:Read_GPS] Running Loop=1
2025-10-01T16:37:53.150Z,1759336673.150 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163751.00,A,3648.17794,N,12147.28592,W,0.039,0.00,011025,,,A*76
2025-10-01T16:37:53.152Z,1759336673.152 [NAL9602](INFO): GPS fix at 20251001T163751: (36.802966, -121.788099)
2025-10-01T16:37:53.163Z,1759336673.163 [Default:CheckIn:Read_GPS] Stopped
2025-10-01T16:37:53.163Z,1759336673.163 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-10-01T16:37:58.372Z,1759336678.372 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:38:22.268Z,1759336702.268 [NAL9602](INFO): SBD MO Status=2, MOMSN=64817, MT Status=2, MTMSN=0
2025-10-01T16:38:22.268Z,1759336702.268 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-10-01T16:38:29.494Z,1759336709.494 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:38:46.977Z,1759336726.977 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=64817, MT Status=1, MTMSN=3934
2025-10-01T16:38:47.038Z,1759336727.038 [NAL9602](INFO): Sent 202 bytes from file Logs/20251001T162844/Courier0007.lzma
2025-10-01T16:38:47.038Z,1759336727.038 [NAL9602](INFO): Packets left to send: 0
2025-10-01T16:38:47.463Z,1759336727.463 [NAL9602](INFO): Received command: failc
2025-10-01T16:38:47.474Z,1759336727.474 [CommandExec](IMPORTANT): got command failComponent
2025-10-01T16:38:47.475Z,1759336727.475 [CommandExec](IMPORTANT): Failed components:
2025-10-01T16:38:47.475Z,1759336727.475 [CommandExec](IMPORTANT): No failed Components.
2025-10-01T16:39:00.618Z,1759336740.618 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:39:18.937Z,1759336758.937 [NAL9602](INFO): SBD MO Status=1, MOMSN=64818, MT Status=0, MTMSN=0
2025-10-01T16:39:18.994Z,1759336758.994 [NAL9602](INFO): Sent 182 bytes from file Logs/20251001T162844/Express0008.lzma
2025-10-01T16:39:18.994Z,1759336758.994 [NAL9602](INFO): Packets left to send: 0
2025-10-01T16:39:31.750Z,1759336771.750 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:39:31.861Z,1759336771.861 [NAL9602](INFO): SBD MO Status=0, MOMSN=64819, MT Status=0, MTMSN=0
2025-10-01T16:39:46.881Z,1759336786.881 [NAL9602](INFO): SBD MO Status=1, MOMSN=64820, MT Status=0, MTMSN=0
2025-10-01T16:39:46.947Z,1759336786.947 [NAL9602](INFO): Sent 39 bytes from file Logs/20251001T162844/Courier0010.lzma
2025-10-01T16:39:46.947Z,1759336786.947 [NAL9602](INFO): Packets left to send: 0
2025-10-01T16:39:58.204Z,1759336798.204 [NAL9602](INFO): SBD MO Status=1, MOMSN=64821, MT Status=0, MTMSN=0
2025-10-01T16:39:58.254Z,1759336798.254 [NAL9602](INFO): Sent 205 bytes from file Logs/20251001T162844/Express0011.lzma
2025-10-01T16:39:58.254Z,1759336798.254 [NAL9602](INFO): Packets left to send: 0
2025-10-01T16:40:02.882Z,1759336802.882 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:40:08.712Z,1759336808.712 [NAL9602](INFO): SBD MO Status=0, MOMSN=64822, MT Status=0, MTMSN=0
2025-10-01T16:40:08.807Z,1759336808.807 [Default:CheckIn:Read_Iridium] Stopped
2025-10-01T16:40:08.807Z,1759336808.807 [Default:CheckIn:C.Wait] Running Loop=1
2025-10-01T16:40:08.807Z,1759336808.807 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-10-01T16:40:34.006Z,1759336834.006 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:40:39.412Z,1759336839.412 [NAL9602](INFO): Not Powering down - fast GPS
2025-10-01T16:41:05.130Z,1759336865.130 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:41:36.254Z,1759336896.254 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:42:07.378Z,1759336927.378 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:42:38.510Z,1759336958.510 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:43:09.638Z,1759336989.638 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:43:40.762Z,1759337020.762 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:44:11.886Z,1759337051.886 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:44:43.011Z,1759337083.011 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:45:09.296Z,1759337109.296 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-10-01T16:45:09.296Z,1759337109.296 [Default:CheckIn:C.Wait] Stopped
2025-10-01T16:45:09.297Z,1759337109.297 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-10-01T16:45:09.297Z,1759337109.297 [Default:CheckIn:D] Running Loop=1
2025-10-01T16:45:09.706Z,1759337109.706 [Default:CheckIn:D] Stopped
2025-10-01T16:45:09.706Z,1759337109.706 [Default:CheckIn:E] Running Loop=1
2025-10-01T16:45:10.109Z,1759337110.109 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.099596 min
2025-10-01T16:45:10.109Z,1759337110.109 [Default:CheckIn:E] Stopped
2025-10-01T16:45:10.109Z,1759337110.109 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-10-01T16:45:10.109Z,1759337110.109 [Default:CheckIn] Stopped
2025-10-01T16:45:10.109Z,1759337110.109 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-10-01T16:45:10.109Z,1759337110.109 [Default:CheckIn](INFO): Running loop #3
2025-10-01T16:45:10.109Z,1759337110.109 [Default:CheckIn] Running Loop=3
2025-10-01T16:45:10.109Z,1759337110.109 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-10-01T16:45:10.110Z,1759337110.110 [Default:CheckIn:Read_GPS] Running Loop=1
2025-10-01T16:45:12.135Z,1759337112.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164511.00,A,3648.17650,N,12147.28445,W,0.039,0.00,011025,,,A*75
2025-10-01T16:45:12.137Z,1759337112.137 [NAL9602](INFO): GPS fix at 20251001T164511: (36.802942, -121.788074)
2025-10-01T16:45:12.148Z,1759337112.148 [Default:CheckIn:Read_GPS] Stopped
2025-10-01T16:45:12.148Z,1759337112.148 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-10-01T16:45:14.134Z,1759337114.134 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:45:33.157Z,1759337133.157 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=64823, MT Status=1, MTMSN=3935
2025-10-01T16:45:33.214Z,1759337133.214 [NAL9602](INFO): Sent 72 bytes from file Logs/20251001T162844/Courier0013.lzma
2025-10-01T16:45:33.214Z,1759337133.214 [NAL9602](INFO): Packets left to send: 0
2025-10-01T16:45:33.815Z,1759337133.815 [NAL9602](INFO): Received command: load Transport/keepstation.tl;set keepstation.Depth 0.01 m;set keepstation.ApproachDepth 0.01 m;set keepstation.Speed 0 m/s;set keepstation.MinOffshore 0.0001 km;run
2025-10-01T16:45:33.931Z,1759337133.931 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-10-01T16:45:33.931Z,1759337133.931 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-10-01T16:45:33.931Z,1759337133.931 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-10-01T16:45:33.936Z,1759337133.936 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-10-01T16:45:35.275Z,1759337135.275 [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-10-01T16:45:35.284Z,1759337135.284 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-10-01T16:45:35.287Z,1759337135.287 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-10-01T16:45:35.290Z,1759337135.290 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-10-01T16:45:35.293Z,1759337135.293 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-10-01T16:45:35.296Z,1759337135.296 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-10-01T16:45:35.299Z,1759337135.299 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-10-01T16:45:35.302Z,1759337135.302 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-10-01T16:45:35.305Z,1759337135.305 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-10-01T16:45:35.308Z,1759337135.308 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-10-01T16:45:35.331Z,1759337135.331 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-10-01T16:45:35.338Z,1759337135.338 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-10-01T16:45:35.344Z,1759337135.344 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-10-01T16:45:35.348Z,1759337135.348 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-10-01T16:45:35.351Z,1759337135.351 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-10-01T16:45:35.369Z,1759337135.369 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-10-01T16:45:35.376Z,1759337135.376 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-10-01T16:45:35.395Z,1759337135.395 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-10-01T16:45:35.402Z,1759337135.402 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-10-01T16:45:35.405Z,1759337135.405 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-10-01T16:45:35.412Z,1759337135.412 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-10-01T16:45:35.413Z,1759337135.413 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-10-01T16:45:35.413Z,1759337135.413 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-10-01T16:45:35.415Z,1759337135.415 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-10-01T16:45:35.798Z,1759337135.798 [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-10-01T16:45:35.808Z,1759337135.808 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-10-01T16:45:35.811Z,1759337135.811 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-10-01T16:45:35.814Z,1759337135.814 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-10-01T16:45:35.817Z,1759337135.817 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-10-01T16:45:35.820Z,1759337135.820 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-10-01T16:45:35.823Z,1759337135.823 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-10-01T16:45:35.826Z,1759337135.826 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-10-01T16:45:35.829Z,1759337135.829 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-10-01T16:45:35.836Z,1759337135.836 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-10-01T16:45:35.881Z,1759337135.881 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-10-01T16:45:35.881Z,1759337135.881 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-10-01T16:45:35.887Z,1759337135.887 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-10-01T16:45:36.061Z,1759337136.061 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2025-10-01T16:45:36.073Z,1759337136.073 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-10-01T16:45:36.081Z,1759337136.081 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-10-01T16:45:36.084Z,1759337136.084 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-10-01T16:45:36.095Z,1759337136.095 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-10-01T16:45:36.097Z,1759337136.097 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-10-01T16:45:36.113Z,1759337136.113 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-10-01T16:45:36.135Z,1759337136.135 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-10-01T16:45:36.153Z,1759337136.153 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-10-01T16:45:36.153Z,1759337136.153 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-10-01T16:45:36.158Z,1759337136.158 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-10-01T16:45:36.200Z,1759337136.200 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-10-01T16:45:36.204Z,1759337136.204 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-10-01T16:45:36.624Z,1759337136.624 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-10-01T16:45:36.632Z,1759337136.632 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-10-01T16:45:36.632Z,1759337136.632 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-10-01T16:45:36.633Z,1759337136.633 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-10-01T16:45:36.749Z,1759337136.749 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-10-01T16:45:36.753Z,1759337136.753 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-10-01T16:45:36.756Z,1759337136.756 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-10-01T16:45:36.759Z,1759337136.759 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-10-01T16:45:36.762Z,1759337136.762 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-10-01T16:45:36.765Z,1759337136.765 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2025-10-01T16:45:36.772Z,1759337136.772 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-10-01T16:45:36.818Z,1759337136.818 [keepstation:H.Pitch](DEBUG): Construct.
2025-10-01T16:45:36.924Z,1759337136.924 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-10-01T16:45:36.933Z,1759337136.933 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-10-01T16:45:36.938Z,1759337136.938 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-10-01T16:45:36.979Z,1759337136.979 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-10-01T16:45:36.984Z,1759337136.984 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-10-01T16:45:36.998Z,1759337136.998 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-10-01T16:45:37.012Z,1759337137.012 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-10-01T16:45:37.027Z,1759337137.027 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-10-01T16:45:37.046Z,1759337137.046 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-10-01T16:45:37.077Z,1759337137.077 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-10-01T16:45:37.140Z,1759337137.140 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission keepstation {
"""
Vehicle transits to desired waypoint (or stays put if none is commanded)
and stays within the specified radius.
"""
arguments {
MissionTimeout = 4 hour
"""
Maximum duration of mission
"""
NeedCommsTime = 45 minute
"""
How often to surface for communications
"""
Latitude = NaN degree
"""
Latitude of waypoint to hold. If NaN, hold the latitude at start of
mission.
"""
Longitude = NaN degree
"""
Longitude of waypoint to hold. If NaN, hold the longitude at start of
mission.
"""
Depth = 30 meter
"""
Depth held during drift mode
"""
ApproachDepth = 15 meter
"""
Depth for initial approach to station.
"""
DepthDeadband = 4 meter
"""
How much vertical drift from the specified depth is allowed in drift
mode
"""
Speed = 0.75 meter_per_second
"""
Vehicle transit speed.
"""
Radius = 200 meter
"""
Radius of circle around waypoint to hold.
"""
SinkOffSurface = false
"""
If true, use buoyancy only to decend to SinkDepth before driving to first target depth
"""
SinkDepth = 10 meter
"""
Initial depth to decend using only buoyancy before driving
"""
SinkDepthTimeout = 10 minute
"""
Max wait time for the vehicle to reach the target depth
"""
MaxDepth = 45 meter
"""
Maximum allowable depth during the mission.
"""
MinOffshore = 2 kilometer
"""
Minimum distance from the shoreline to maintain
"""
}
output {
DiveMode = 0 count
"""
Mission variable (don't change). The mission sets this variable to
switch between dive modes. Initialized to DoingComms.
"""
DoingComms = 0 count
"""
Mission variable (don't change). The mission will run in this mode
when doing surface comms.
"""
SurfaceSink = 1 count
"""
Mission variable (don't change). The mission will run in this mode
when sinking from the surface using only buoyancy.
"""
TransitToStation = 2 count
"""
Mission variable (don't change). The mission will run in this mode
when performing the transit to station
"""
StationKeep = 3 count
"""
Mission variable (don't change). The mission will run in this mode
when keeping station
"""
OnStation = false
"""
Mission variable (don't change). Determines last mode (Transit or StationKeep)
to resume after needcomms
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Pitch {
run in parallel
set massPosition = Control:VerticalControl.massDefault
}
aggregate SurfaceComms {
run when (
called
or ( elapsed ( Universal:time_fix ) > NeedCommsTime )
)
assign in sequence DiveMode = DoingComms
call priorityHere=false refId="NeedComms"
aggregate HandleNan {
run in sequence
break if (
not isNaN ( Latitude )
and not isNaN ( Longitude )
)
# If both lat and lon are nan, we are implicitly on station and should skip initial transit, so force OnStation to true
aggregate SetOnStation {
run in sequence
break if (
not isNaN ( Latitude )
or not isNaN ( Longitude )
)
assign in sequence OnStation = true
}
# The "assigns" here mean we should only ever run these aggregates once
# An initial NaN lat/lon should be set here during the initial surface NeedComms call
# Use the most recent fix -- if there isn't one, the keepstation behavior will use Universal:lat/lon
aggregate SetLat {
run in sequence
break if ( not isNaN ( Latitude ) )
assign in sequence Latitude = Universal:latitude_fix
}
aggregate SetLon {
run in sequence
break if ( not isNaN ( Longitude ) )
assign in sequence Longitude = Universal:longitude_fix
}
}
aggregate setTransit {
run in sequence
break if (
SinkOffSurface
or OnStation
)
assign in sequence DiveMode = TransitToStation
}
aggregate setStation {
run in sequence
break if (
SinkOffSurface
or not OnStation
)
assign in sequence DiveMode = StationKeep
}
aggregate setSink {
run in sequence
break if ( not SinkOffSurface )
assign in sequence DiveMode = SurfaceSink
}
}
call id="StartingMission" priorityHere=false refId="SurfaceComms"
aggregate DiveCmd {
run when ( called )
aggregate SurfaceSink {
run while ( DiveMode == SurfaceSink )
aggregate sink {
run in sequence
behavior Guidance:Buoyancy id="BuoyancyHold" {
run in parallel
set position = NaN cc
}
behavior Guidance:SetSpeed {
run in parallel
set speed = 0 m/s
}
syslog info "Sinking to " + SinkDepth~meter
behavior Guidance:Pitch {
run in sequence
timeout duration=SinkDepthTimeout {
syslog important "Timed out while trying to reach target sink depth at current depth of "
+ Universal:depth~meter
}
set depth = SinkDepth
}
# Once we've sunk, either resume transit or station keeping depths and speeds
aggregate sinkToTransit {
run in sequence
break if ( OnStation )
assign in sequence DiveMode = TransitToStation
}
aggregate sinkToStation {
run in sequence
break if ( not OnStation )
assign in sequence DiveMode = StationKeep
}
}
}
aggregate TransitToStation {
run while ( DiveMode == TransitToStation )
aggregate dive {
run in sequence
behavior Guidance:Buoyancy {
run in parallel
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch {
run in parallel
set depth = ApproachDepth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
}
}
aggregate StationKeep {
run while ( DiveMode == StationKeep )
assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:KeepStation {
run in parallel
set latitude = Latitude
set longitude = Longitude
set radius = Radius
set speed = Speed
}
}
}
aggregate TransitToStation {
"""
Need a separate aggregate for transit if we want to specify a
different depth for the approach.
"""
run in sequence
aggregate Dive {
run while ( not ( DiveMode == DoingComms ) )
call priorityHere=false refId="DiveCmd"
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
}
assign in sequence DiveMode = StationKeep
assign in sequence OnStation = true
}
call id="OnStation" priorityHere=false refId="NeedComms"
aggregate KeepStation {
run in sequence
aggregate Dive {
run while ( not ( DiveMode == DoingComms ) )
call priorityHere=false refId="DiveCmd"
}
behavior Guidance:Wait {
"""
Due to the way the KeepStation behavior is currently
implemented, we must run it in parallel and use a Wait to keep
from completing the mission before the timeout expires. However,
this new version of the mission uses a top-level timeout so that
the entire mission will not run for longer then MissionTimeout.
"""
run in sequence
set duration = MissionTimeout
}
}
}
2025-10-01T16:45:37.140Z,1759337137.140 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-10-01T16:45:45.258Z,1759337145.258 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:45:47.736Z,1759337147.736 [CommandExec](IMPORTANT): got command set keepstation.Depth 0.01 meter
2025-10-01T16:45:47.737Z,1759337147.737 [CommandExec](IMPORTANT): got command set keepstation.ApproachDepth 0.01 meter
2025-10-01T16:45:47.740Z,1759337147.740 [CommandExec](IMPORTANT): got command set keepstation.Speed 0 meter_per_second
2025-10-01T16:45:47.741Z,1759337147.741 [CommandExec](IMPORTANT): got command set keepstation.MinOffshore 0.0001 kilometer
2025-10-01T16:45:47.741Z,1759337147.741 [CommandExec](IMPORTANT): got command run
2025-10-01T16:45:47.744Z,1759337147.744 [CommandExec](IMPORTANT): Running
2025-10-01T16:45:47.821Z,1759337147.821 [Default] Stopped
2025-10-01T16:45:47.821Z,1759337147.821 [Default](DEBUG): Aggregate::uninitialize Default
2025-10-01T16:45:47.822Z,1759337147.822 [Default:B.GoToSurface] Stopped
2025-10-01T16:45:47.822Z,1759337147.822 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-10-01T16:45:47.822Z,1759337147.822 [Default:CheckIn] Stopped
2025-10-01T16:45:47.826Z,1759337147.826 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-10-01T16:45:47.826Z,1759337147.826 [Default:CheckIn:Read_Iridium] Stopped
2025-10-01T16:45:47.826Z,1759337147.826 [MissionManager](IMPORTANT): Started mission keepstation
2025-10-01T16:45:47.827Z,1759337147.827 [keepstation] Running Loop=1
2025-10-01T16:45:47.827Z,1759337147.827 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-10-01T16:45:47.827Z,1759337147.827 [keepstation:StandardEnvelopes] Running Loop=1
2025-10-01T16:45:47.827Z,1759337147.827 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-10-01T16:45:47.827Z,1759337147.827 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-10-01T16:45:47.827Z,1759337147.827 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-10-01T16:45:47.827Z,1759337147.827 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-10-01T16:45:47.827Z,1759337147.827 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-10-01T16:45:47.828Z,1759337147.828 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-10-01T16:45:47.828Z,1759337147.828 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-10-01T16:45:47.828Z,1759337147.828 [keepstation:BackseatDriver] Running Loop=1
2025-10-01T16:45:47.828Z,1759337147.828 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-10-01T16:45:47.828Z,1759337147.828 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-10-01T16:45:47.828Z,1759337147.828 [keepstation:PowerOnly] Running Loop=1
2025-10-01T16:45:47.828Z,1759337147.828 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-10-01T16:45:47.828Z,1759337147.828 [keepstation:PowerOnly:A] Running Loop=1
2025-10-01T16:45:47.828Z,1759337147.828 [keepstation:PowerOnly:B] Running Loop=1
2025-10-01T16:45:47.828Z,1759337147.828 [keepstation:PowerOnly:C] Running Loop=1
2025-10-01T16:45:47.828Z,1759337147.828 [keepstation:PowerOnly:D] Running Loop=1
2025-10-01T16:45:47.829Z,1759337147.829 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-10-01T16:45:47.829Z,1759337147.829 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-10-01T16:45:47.829Z,1759337147.829 [keepstation:H.Pitch] Running Loop=1
2025-10-01T16:45:47.829Z,1759337147.829 [keepstation:H.Pitch](DEBUG): Initialize.
2025-10-01T16:45:47.829Z,1759337147.829 [keepstation:B] Running Loop=1
2025-10-01T16:45:47.834Z,1759337147.834 [keepstation:H.Pitch] Running Loop=1
2025-10-01T16:45:47.834Z,1759337147.834 [keepstation:PowerOnly] Running Loop=1
2025-10-01T16:45:47.835Z,1759337147.835 [keepstation:PowerOnly:D] Stopped
2025-10-01T16:45:47.835Z,1759337147.835 [keepstation:PowerOnly:C] Stopped
2025-10-01T16:45:47.835Z,1759337147.835 [keepstation:PowerOnly:B] Stopped
2025-10-01T16:45:47.835Z,1759337147.835 [keepstation:PowerOnly:A] Stopped
2025-10-01T16:45:47.835Z,1759337147.835 [keepstation:BackseatDriver] Running Loop=1
2025-10-01T16:45:47.835Z,1759337147.835 [keepstation:StandardEnvelopes] Running Loop=1
2025-10-01T16:45:47.835Z,1759337147.835 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-10-01T16:45:47.836Z,1759337147.836 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE
2025-10-01T16:45:47.852Z,1759337147.852 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-10-01T16:45:47.853Z,1759337147.853 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-10-01T16:45:47.854Z,1759337147.854 [keepstation:B] Stopped
2025-10-01T16:45:47.858Z,1759337147.858 [keepstation:D] Running Loop=1
2025-10-01T16:45:47.866Z,1759337147.866 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-10-01T16:45:47.867Z,1759337147.867 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-10-01T16:45:47.867Z,1759337147.867 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-10-01T16:45:48.213Z,1759337148.213 [keepstation:D] Stopped
2025-10-01T16:45:48.213Z,1759337148.213 [keepstation:E] Running Loop=1
2025-10-01T16:45:48.618Z,1759337148.618 [keepstation:E] Stopped
2025-10-01T16:45:48.618Z,1759337148.618 [keepstation:StartingMission] Running Loop=1
2025-10-01T16:45:48.618Z,1759337148.618 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-10-01T16:45:49.030Z,1759337149.030 [keepstation:SurfaceComms] Running Loop=1
2025-10-01T16:45:49.030Z,1759337149.030 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-10-01T16:45:49.031Z,1759337149.031 [keepstation:SurfaceComms:A] Running Loop=1
2025-10-01T16:45:49.031Z,1759337149.031 [keepstation:SurfaceComms:A] Stopped
2025-10-01T16:45:49.031Z,1759337149.031 [keepstation:SurfaceComms:B] Running Loop=1
2025-10-01T16:45:49.031Z,1759337149.031 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-10-01T16:45:49.437Z,1759337149.437 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-10-01T16:45:49.437Z,1759337149.437 [keepstation:NeedComms] Running Loop=1
2025-10-01T16:45:49.438Z,1759337149.438 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-10-01T16:45:49.438Z,1759337149.438 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-10-01T16:45:49.438Z,1759337149.438 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-10-01T16:45:49.438Z,1759337149.438 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-10-01T16:45:49.439Z,1759337149.439 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-10-01T16:45:49.439Z,1759337149.439 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-10-01T16:45:49.439Z,1759337149.439 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-10-01T16:45:49.440Z,1759337149.440 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-10-01T16:45:49.440Z,1759337149.440 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-10-01T16:45:49.440Z,1759337149.440 [keepstation:NeedComms:A] Running Loop=1
2025-10-01T16:45:49.441Z,1759337149.441 [keepstation:NeedComms:A](INFO): last time_fix was: 1759337111.000000 second since 1970/01/01T00:00:00Z
2025-10-01T16:45:49.442Z,1759337149.442 [keepstation:NeedComms:A] Stopped
2025-10-01T16:45:49.830Z,1759337149.830 [keepstation:NeedComms:C] Running Loop=1
2025-10-01T16:45:50.221Z,1759337150.221 [NAL9602](INFO): SBD MO Status=1, MOMSN=64824, MT Status=0, MTMSN=0
2025-10-01T16:45:50.274Z,1759337150.274 [NAL9602](INFO): Sent 151 bytes from file Logs/20251001T162844/Express0014.lzma
2025-10-01T16:45:50.274Z,1759337150.274 [NAL9602](INFO): Packets left to send: 0
2025-10-01T16:45:50.480Z,1759337150.480 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-10-01T16:45:51.431Z,1759337151.431 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164550.00,A,3648.17657,N,12147.28408,W,0.039,0.00,011025,,,A*7E
2025-10-01T16:45:51.433Z,1759337151.433 [NAL9602](INFO): GPS fix at 20251001T164550: (36.802943, -121.788068)
2025-10-01T16:45:51.446Z,1759337151.446 [keepstation:NeedComms:C] Stopped
2025-10-01T16:45:51.447Z,1759337151.447 [keepstation:NeedComms:D] Running Loop=1
2025-10-01T16:45:51.864Z,1759337151.864 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-10-01T16:46:08.845Z,1759337168.845 [NAL9602](INFO): SBD MO Status=1, MOMSN=64825, MT Status=0, MTMSN=0
2025-10-01T16:46:08.894Z,1759337168.894 [NAL9602](INFO): Sent 39 bytes from file Logs/20251001T162844/Courier0016.lzma
2025-10-01T16:46:08.895Z,1759337168.895 [NAL9602](INFO): Packets left to send: 0
2025-10-01T16:46:16.168Z,1759337176.168 [NAL9602](INFO): SBD MO Status=1, MOMSN=64826, MT Status=0, MTMSN=0
2025-10-01T16:46:16.218Z,1759337176.218 [NAL9602](INFO): Sent 63 bytes from file Logs/20251001T162844/Courier0019.lzma
2025-10-01T16:46:16.219Z,1759337176.219 [NAL9602](INFO): Packets left to send: 0
2025-10-01T16:46:16.382Z,1759337176.382 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:46:26.737Z,1759337186.737 [NAL9602](INFO): SBD MO Status=1, MOMSN=64827, MT Status=0, MTMSN=0
2025-10-01T16:46:26.794Z,1759337186.794 [NAL9602](INFO): Sent 332 bytes from file Logs/20251001T162844/Express0017.lzma
2025-10-01T16:46:26.794Z,1759337186.794 [NAL9602](INFO): Packets left to send: 1
2025-10-01T16:46:46.992Z,1759337206.992 [NAL9602](INFO): SBD MO Status=1, MOMSN=64828, MT Status=0, MTMSN=0
2025-10-01T16:46:47.042Z,1759337207.042 [NAL9602](INFO): Sent 37 bytes from file Logs/20251001T162844/Express0017.lzma
2025-10-01T16:46:47.042Z,1759337207.042 [NAL9602](INFO): Packets left to send: 0
2025-10-01T16:46:47.507Z,1759337207.507 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:46:57.947Z,1759337217.947 [NAL9602](INFO): SBD MO Status=1, MOMSN=64829, MT Status=0, MTMSN=0
2025-10-01T16:46:58.001Z,1759337218.001 [NAL9602](INFO): Sent 83 bytes from file Logs/20251001T162844/Express0020.lzma
2025-10-01T16:46:58.001Z,1759337218.001 [NAL9602](INFO): Packets left to send: 0
2025-10-01T16:47:18.645Z,1759337238.645 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:47:18.957Z,1759337238.957 [NAL9602](INFO): SBD MO Status=0, MOMSN=64830, MT Status=0, MTMSN=0
2025-10-01T16:47:19.051Z,1759337239.051 [keepstation:NeedComms:D] Stopped
2025-10-01T16:47:19.051Z,1759337239.051 [keepstation:NeedComms:E] Running Loop=1
2025-10-01T16:47:19.368Z,1759337239.368 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-10-01T16:47:21.378Z,1759337241.378 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164720.00,A,3648.17603,N,12147.28521,W,0.039,0.00,011025,,,A*70
2025-10-01T16:47:21.380Z,1759337241.380 [NAL9602](INFO): GPS fix at 20251001T164720: (36.802934, -121.788087)
2025-10-01T16:47:21.394Z,1759337241.394 [keepstation:NeedComms:E] Stopped
2025-10-01T16:47:21.395Z,1759337241.395 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-10-01T16:47:21.395Z,1759337241.395 [keepstation:NeedComms] Stopped
2025-10-01T16:47:21.395Z,1759337241.395 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-10-01T16:47:21.395Z,1759337241.395 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-10-01T16:47:21.395Z,1759337241.395 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-10-01T16:47:21.808Z,1759337241.808 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-10-01T16:47:21.808Z,1759337241.808 [keepstation:SurfaceComms:B] Stopped
2025-10-01T16:47:21.808Z,1759337241.808 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-10-01T16:47:21.808Z,1759337241.808 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-10-01T16:47:21.808Z,1759337241.808 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-10-01T16:47:21.809Z,1759337241.809 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-10-01T16:47:21.809Z,1759337241.809 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-10-01T16:47:21.809Z,1759337241.809 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-10-01T16:47:22.231Z,1759337242.231 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-10-01T16:47:22.231Z,1759337242.231 [keepstation:SurfaceComms:HandleNan:SetOnStation](INFO): Completed keepstation:SurfaceComms:HandleNan:SetOnStation
2025-10-01T16:47:22.231Z,1759337242.231 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-10-01T16:47:22.231Z,1759337242.231 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-10-01T16:47:22.231Z,1759337242.231 [keepstation:SurfaceComms:HandleNan:SetLat] Running Loop=1
2025-10-01T16:47:22.231Z,1759337242.231 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLat
2025-10-01T16:47:22.231Z,1759337242.231 [keepstation:SurfaceComms:HandleNan:SetLat:A] Running Loop=1
2025-10-01T16:47:22.612Z,1759337242.612 [keepstation:SurfaceComms:HandleNan:SetLat:A] Stopped
2025-10-01T16:47:22.612Z,1759337242.612 [keepstation:SurfaceComms:HandleNan:SetLat](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLat
2025-10-01T16:47:22.612Z,1759337242.612 [keepstation:SurfaceComms:HandleNan:SetLat] Stopped
2025-10-01T16:47:22.612Z,1759337242.612 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLat
2025-10-01T16:47:22.612Z,1759337242.612 [keepstation:SurfaceComms:HandleNan:SetLon] Running Loop=1
2025-10-01T16:47:22.612Z,1759337242.612 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLon
2025-10-01T16:47:22.612Z,1759337242.612 [keepstation:SurfaceComms:HandleNan:SetLon:A] Running Loop=1
2025-10-01T16:47:23.005Z,1759337243.005 [keepstation:SurfaceComms:HandleNan:SetLon:A] Stopped
2025-10-01T16:47:23.005Z,1759337243.005 [keepstation:SurfaceComms:HandleNan:SetLon](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLon
2025-10-01T16:47:23.005Z,1759337243.005 [keepstation:SurfaceComms:HandleNan:SetLon] Stopped
2025-10-01T16:47:23.005Z,1759337243.005 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLon
2025-10-01T16:47:23.005Z,1759337243.005 [keepstation:SurfaceComms:HandleNan](INFO): Completed keepstation:SurfaceComms:HandleNan
2025-10-01T16:47:23.005Z,1759337243.005 [keepstation:SurfaceComms:HandleNan] Stopped
2025-10-01T16:47:23.006Z,1759337243.006 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-10-01T16:47:23.006Z,1759337243.006 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-10-01T16:47:23.006Z,1759337243.006 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-10-01T16:47:23.006Z,1759337243.006 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-10-01T16:47:23.418Z,1759337243.418 [keepstation:SurfaceComms:setTransit] Stopped
2025-10-01T16:47:23.419Z,1759337243.419 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-10-01T16:47:23.419Z,1759337243.419 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-10-01T16:47:23.419Z,1759337243.419 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-10-01T16:47:23.419Z,1759337243.419 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-10-01T16:47:23.419Z,1759337243.419 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-10-01T16:47:23.814Z,1759337243.814 [keepstation:SurfaceComms:setStation:A] Stopped
2025-10-01T16:47:23.815Z,1759337243.815 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-10-01T16:47:23.815Z,1759337243.815 [keepstation:SurfaceComms:setStation] Stopped
2025-10-01T16:47:23.815Z,1759337243.815 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-10-01T16:47:23.815Z,1759337243.815 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-10-01T16:47:23.815Z,1759337243.815 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-10-01T16:47:23.815Z,1759337243.815 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-10-01T16:47:24.214Z,1759337244.214 [keepstation:SurfaceComms:setSink] Stopped
2025-10-01T16:47:24.214Z,1759337244.214 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-10-01T16:47:24.214Z,1759337244.214 [keepstation:SurfaceComms:setSink:A] Stopped
2025-10-01T16:47:24.215Z,1759337244.215 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-10-01T16:47:24.215Z,1759337244.215 [keepstation:SurfaceComms] Stopped
2025-10-01T16:47:24.215Z,1759337244.215 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-10-01T16:47:24.622Z,1759337244.622 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-10-01T16:47:24.622Z,1759337244.622 [keepstation:StartingMission] Stopped
2025-10-01T16:47:24.622Z,1759337244.622 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-10-01T16:47:24.622Z,1759337244.622 [keepstation:TransitToStation] Running Loop=1
2025-10-01T16:47:24.622Z,1759337244.622 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-10-01T16:47:24.622Z,1759337244.622 [keepstation:TransitToStation:Dive] Running Loop=1
2025-10-01T16:47:24.623Z,1759337244.623 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-10-01T16:47:24.623Z,1759337244.623 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-10-01T16:47:24.623Z,1759337244.623 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-10-01T16:47:24.623Z,1759337244.623 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-10-01T16:47:24.623Z,1759337244.623 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-10-01T16:47:24.624Z,1759337244.624 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.802934,-121.788087
2025-10-01T16:47:25.031Z,1759337245.031 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.802934,-121.788087
2025-10-01T16:47:25.032Z,1759337245.032 [keepstation:TransitToStation:Dive] Running Loop=1
2025-10-01T16:47:25.032Z,1759337245.032 [keepstation:DiveCmd] Running Loop=1
2025-10-01T16:47:25.032Z,1759337245.032 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-10-01T16:47:25.032Z,1759337245.032 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-10-01T16:47:25.032Z,1759337245.032 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-10-01T16:47:25.033Z,1759337245.033 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-10-01T16:47:25.033Z,1759337245.033 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-10-01T16:47:25.033Z,1759337245.033 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-10-01T16:47:25.033Z,1759337245.033 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-10-01T16:47:25.033Z,1759337245.033 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-10-01T16:47:25.033Z,1759337245.033 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-10-01T16:47:25.033Z,1759337245.033 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-10-01T16:47:25.033Z,1759337245.033 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-10-01T16:47:25.033Z,1759337245.033 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-10-01T16:47:25.034Z,1759337245.034 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-10-01T16:47:25.034Z,1759337245.034 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-10-01T16:47:25.034Z,1759337245.034 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-10-01T16:47:25.058Z,1759337245.058 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-10-01T16:47:25.058Z,1759337245.058 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-10-01T16:47:25.058Z,1759337245.058 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-10-01T16:47:25.058Z,1759337245.058 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-10-01T16:47:25.059Z,1759337245.059 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-10-01T16:47:25.059Z,1759337245.059 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-10-01T16:47:25.059Z,1759337245.059 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-10-01T16:47:25.059Z,1759337245.059 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-10-01T16:47:25.059Z,1759337245.059 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-10-01T16:47:25.059Z,1759337245.059 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-10-01T16:47:25.059Z,1759337245.059 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-10-01T16:47:25.059Z,1759337245.059 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-10-01T16:47:25.060Z,1759337245.060 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-10-01T16:47:25.060Z,1759337245.060 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-10-01T16:47:25.061Z,1759337245.061 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-10-01T16:47:25.061Z,1759337245.061 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-10-01T16:47:25.061Z,1759337245.061 [keepstation:DiveCmd:TransitToStation] Stopped
2025-10-01T16:47:25.061Z,1759337245.061 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-10-01T16:47:25.062Z,1759337245.062 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-10-01T16:47:25.062Z,1759337245.062 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-10-01T16:47:25.066Z,1759337245.066 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-10-01T16:47:25.066Z,1759337245.066 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-10-01T16:47:25.066Z,1759337245.066 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-10-01T16:47:25.066Z,1759337245.066 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-10-01T16:47:25.066Z,1759337245.066 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-10-01T16:47:25.066Z,1759337245.066 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-10-01T16:47:25.066Z,1759337245.066 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-10-01T16:47:25.067Z,1759337245.067 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-10-01T16:47:25.067Z,1759337245.067 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-10-01T16:47:25.067Z,1759337245.067 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-10-01T16:47:25.067Z,1759337245.067 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-10-01T16:47:25.067Z,1759337245.067 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-10-01T16:47:25.067Z,1759337245.067 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-10-01T16:47:25.067Z,1759337245.067 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-10-01T16:47:49.766Z,1759337269.766 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:47:53.291Z,1759337273.291 [NAL9602](INFO): Not Powering down - fast GPS
2025-10-01T16:48:20.902Z,1759337300.902 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:48:52.035Z,1759337332.035 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:49:23.159Z,1759337363.159 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:49:54.282Z,1759337394.282 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:50:25.411Z,1759337425.411 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:50:56.534Z,1759337456.534 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:51:27.674Z,1759337487.674 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:51:58.798Z,1759337518.798 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:52:29.926Z,1759337549.926 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:53:01.054Z,1759337581.054 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:53:32.182Z,1759337612.182 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:54:03.306Z,1759337643.306 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:54:34.431Z,1759337674.431 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:55:05.555Z,1759337705.555 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:55:36.686Z,1759337736.686 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:56:07.810Z,1759337767.810 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:56:38.934Z,1759337798.934 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:57:10.075Z,1759337830.075 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:57:41.198Z,1759337861.198 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:58:12.323Z,1759337892.323 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:58:43.470Z,1759337923.470 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:59:14.598Z,1759337954.598 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T16:59:45.722Z,1759337985.722 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:00:16.846Z,1759338016.846 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:00:47.970Z,1759338047.970 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:01:19.094Z,1759338079.094 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:01:50.234Z,1759338110.234 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:02:21.358Z,1759338141.358 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:02:52.482Z,1759338172.482 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:03:23.606Z,1759338203.606 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:03:54.731Z,1759338234.731 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:04:25.854Z,1759338265.854 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:04:56.978Z,1759338296.978 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:05:28.118Z,1759338328.118 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:05:59.254Z,1759338359.254 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:06:30.390Z,1759338390.390 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:07:01.514Z,1759338421.514 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:07:32.647Z,1759338452.647 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:08:03.772Z,1759338483.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:08:34.895Z,1759338514.895 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:09:06.018Z,1759338546.018 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:09:37.146Z,1759338577.146 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:10:08.270Z,1759338608.270 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:10:39.394Z,1759338639.394 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:11:10.538Z,1759338670.538 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:11:41.671Z,1759338701.671 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:12:12.794Z,1759338732.794 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:12:43.918Z,1759338763.918 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:13:15.043Z,1759338795.043 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:13:46.186Z,1759338826.186 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:14:17.310Z,1759338857.310 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:14:48.438Z,1759338888.438 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:15:19.570Z,1759338919.570 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:15:50.694Z,1759338950.694 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:16:21.818Z,1759338981.818 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:16:52.942Z,1759339012.942 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:17:24.066Z,1759339044.066 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:17:55.190Z,1759339075.190 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:18:26.330Z,1759339106.330 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:18:57.454Z,1759339137.454 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:19:28.578Z,1759339168.578 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:19:59.702Z,1759339199.702 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:20:30.826Z,1759339230.826 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:21:01.990Z,1759339261.990 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:21:33.114Z,1759339293.114 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:22:04.242Z,1759339324.242 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:22:35.368Z,1759339355.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:23:06.490Z,1759339386.490 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:23:37.615Z,1759339417.615 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:24:08.738Z,1759339448.738 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:24:39.862Z,1759339479.862 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:25:10.990Z,1759339510.990 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:25:42.114Z,1759339542.114 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:26:13.246Z,1759339573.246 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:26:44.370Z,1759339604.370 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:27:15.494Z,1759339635.494 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:27:46.618Z,1759339666.618 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:28:17.743Z,1759339697.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:28:48.882Z,1759339728.882 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:29:20.011Z,1759339760.011 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:29:51.150Z,1759339791.150 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:30:22.275Z,1759339822.275 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:30:53.398Z,1759339853.398 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:31:24.534Z,1759339884.534 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:31:55.662Z,1759339915.662 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:32:21.761Z,1759339941.761 [keepstation:SurfaceComms] Running Loop=1
2025-10-01T17:32:21.761Z,1759339941.761 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-10-01T17:32:21.761Z,1759339941.761 [keepstation:SurfaceComms:A] Running Loop=1
2025-10-01T17:32:21.761Z,1759339941.761 [keepstation:SurfaceComms:A] Stopped
2025-10-01T17:32:21.761Z,1759339941.761 [keepstation:SurfaceComms:B] Running Loop=1
2025-10-01T17:32:21.762Z,1759339941.762 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-10-01T17:32:22.166Z,1759339942.166 [keepstation:TransitToStation:Dive] Stopped
2025-10-01T17:32:22.166Z,1759339942.166 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-10-01T17:32:22.166Z,1759339942.166 [keepstation:TransitToStation:Dive:A] Stopped
2025-10-01T17:32:22.166Z,1759339942.166 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-10-01T17:32:22.167Z,1759339942.167 [keepstation:DiveCmd:StationKeep] Stopped
2025-10-01T17:32:22.167Z,1759339942.167 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-10-01T17:32:22.167Z,1759339942.167 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-10-01T17:32:22.167Z,1759339942.167 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-10-01T17:32:22.167Z,1759339942.167 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-10-01T17:32:22.167Z,1759339942.167 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-10-01T17:32:22.169Z,1759339942.169 [keepstation:NeedComms] Running Loop=1
2025-10-01T17:32:22.169Z,1759339942.169 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-10-01T17:32:22.169Z,1759339942.169 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-10-01T17:32:22.169Z,1759339942.169 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-10-01T17:32:22.169Z,1759339942.169 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-10-01T17:32:22.170Z,1759339942.170 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-10-01T17:32:22.170Z,1759339942.170 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-10-01T17:32:22.171Z,1759339942.171 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-10-01T17:32:22.171Z,1759339942.171 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-10-01T17:32:22.171Z,1759339942.171 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-10-01T17:32:22.172Z,1759339942.172 [keepstation:NeedComms:A] Running Loop=1
2025-10-01T17:32:22.173Z,1759339942.173 [keepstation:NeedComms:A](INFO): last time_fix was: 1759337240.000000 second since 1970/01/01T00:00:00Z
2025-10-01T17:32:22.173Z,1759339942.173 [keepstation:NeedComms:A] Stopped
2025-10-01T17:32:22.581Z,1759339942.581 [keepstation:NeedComms:C] Running Loop=1
2025-10-01T17:32:24.588Z,1759339944.588 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173223.00,A,3648.17615,N,12147.28431,W,0.078,0.00,011025,,,A*72
2025-10-01T17:32:24.591Z,1759339944.591 [NAL9602](INFO): GPS fix at 20251001T173223: (36.802936, -121.788072)
2025-10-01T17:32:24.608Z,1759339944.608 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.802934,-121.788087
2025-10-01T17:32:24.611Z,1759339944.611 [keepstation:NeedComms:C] Stopped
2025-10-01T17:32:24.611Z,1759339944.611 [keepstation:NeedComms:D] Running Loop=1
2025-10-01T17:32:26.794Z,1759339946.794 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:32:35.165Z,1759339955.165 [NAL9602](INFO): SBD MO Status=1, MOMSN=64831, MT Status=0, MTMSN=0
2025-10-01T17:32:35.222Z,1759339955.222 [NAL9602](INFO): Sent 73 bytes from file Logs/20251001T162844/Courier0022.lzma
2025-10-01T17:32:35.222Z,1759339955.222 [NAL9602](INFO): Packets left to send: 0
2025-10-01T17:32:47.520Z,1759339967.520 [NAL9602](INFO): SBD MO Status=1, MOMSN=64832, MT Status=0, MTMSN=0
2025-10-01T17:32:47.590Z,1759339967.590 [NAL9602](INFO): Sent 218 bytes from file Logs/20251001T162844/Express0023.lzma
2025-10-01T17:32:47.590Z,1759339967.590 [NAL9602](INFO): Packets left to send: 0
2025-10-01T17:32:55.208Z,1759339975.208 [NAL9602](INFO): SBD MO Status=0, MOMSN=64833, MT Status=0, MTMSN=0
2025-10-01T17:32:55.290Z,1759339975.290 [keepstation:NeedComms:D] Stopped
2025-10-01T17:32:55.290Z,1759339975.290 [keepstation:NeedComms:E] Running Loop=1
2025-10-01T17:32:57.621Z,1759339977.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173256.00,A,3648.17668,N,12147.28366,W,0.039,0.00,011025,,,A*7A
2025-10-01T17:32:57.624Z,1759339977.624 [NAL9602](INFO): GPS fix at 20251001T173256: (36.802945, -121.788061)
2025-10-01T17:32:57.637Z,1759339977.637 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.802934,-121.788087
2025-10-01T17:32:57.640Z,1759339977.640 [keepstation:NeedComms:E] Stopped
2025-10-01T17:32:57.641Z,1759339977.641 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-10-01T17:32:57.641Z,1759339977.641 [keepstation:NeedComms] Stopped
2025-10-01T17:32:57.641Z,1759339977.641 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-10-01T17:32:57.641Z,1759339977.641 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-10-01T17:32:57.641Z,1759339977.641 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-10-01T17:32:57.926Z,1759339977.926 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:32:58.056Z,1759339978.056 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-10-01T17:32:58.056Z,1759339978.056 [keepstation:SurfaceComms:B] Stopped
2025-10-01T17:32:58.057Z,1759339978.057 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-10-01T17:32:58.057Z,1759339978.057 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-10-01T17:32:58.057Z,1759339978.057 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-10-01T17:32:58.057Z,1759339978.057 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-10-01T17:32:58.057Z,1759339978.057 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-10-01T17:32:58.057Z,1759339978.057 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-10-01T17:32:58.500Z,1759339978.500 [keepstation:SurfaceComms:HandleNan] Stopped
2025-10-01T17:32:58.500Z,1759339978.500 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-10-01T17:32:58.500Z,1759339978.500 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-10-01T17:32:58.500Z,1759339978.500 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-10-01T17:32:58.501Z,1759339978.501 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-10-01T17:32:58.501Z,1759339978.501 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-10-01T17:32:58.501Z,1759339978.501 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-10-01T17:32:58.501Z,1759339978.501 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-10-01T17:32:58.941Z,1759339978.941 [keepstation:SurfaceComms:setTransit] Stopped
2025-10-01T17:32:58.941Z,1759339978.941 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-10-01T17:32:58.941Z,1759339978.941 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-10-01T17:32:58.942Z,1759339978.942 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-10-01T17:32:58.942Z,1759339978.942 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-10-01T17:32:58.950Z,1759339978.950 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-10-01T17:32:59.253Z,1759339979.253 [keepstation:SurfaceComms:setStation:A] Stopped
2025-10-01T17:32:59.253Z,1759339979.253 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-10-01T17:32:59.253Z,1759339979.253 [keepstation:SurfaceComms:setStation] Stopped
2025-10-01T17:32:59.253Z,1759339979.253 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-10-01T17:32:59.253Z,1759339979.253 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-10-01T17:32:59.254Z,1759339979.254 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-10-01T17:32:59.254Z,1759339979.254 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-10-01T17:32:59.662Z,1759339979.662 [keepstation:TransitToStation:Dive] Running Loop=1
2025-10-01T17:32:59.662Z,1759339979.662 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-10-01T17:32:59.662Z,1759339979.662 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-10-01T17:32:59.663Z,1759339979.663 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-10-01T17:32:59.663Z,1759339979.663 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-10-01T17:32:59.663Z,1759339979.663 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-10-01T17:32:59.663Z,1759339979.663 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-10-01T17:32:59.663Z,1759339979.663 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-10-01T17:32:59.663Z,1759339979.663 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-10-01T17:32:59.664Z,1759339979.664 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-10-01T17:32:59.664Z,1759339979.664 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-10-01T17:32:59.665Z,1759339979.665 [keepstation:SurfaceComms:setSink] Stopped
2025-10-01T17:32:59.665Z,1759339979.665 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-10-01T17:32:59.665Z,1759339979.665 [keepstation:SurfaceComms:setSink:A] Stopped
2025-10-01T17:32:59.666Z,1759339979.666 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-10-01T17:32:59.666Z,1759339979.666 [keepstation:SurfaceComms] Stopped
2025-10-01T17:32:59.666Z,1759339979.666 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-10-01T17:33:29.054Z,1759340009.054 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:33:30.341Z,1759340010.341 [NAL9602](INFO): Not Powering down - fast GPS
2025-10-01T17:34:00.180Z,1759340040.180 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:34:31.310Z,1759340071.310 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:35:02.434Z,1759340102.434 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:35:33.558Z,1759340133.558 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:36:04.682Z,1759340164.682 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:36:35.806Z,1759340195.806 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:37:06.930Z,1759340226.930 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:37:27.890Z,1759340247.890 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T17:37:38.054Z,1759340258.054 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:37:40.022Z,1759340260.022 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T17:37:52.129Z,1759340272.129 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T17:38:04.251Z,1759340284.251 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T17:38:09.178Z,1759340289.178 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:38:15.562Z,1759340295.562 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T17:38:27.278Z,1759340307.278 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T17:38:38.993Z,1759340318.993 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T17:38:40.302Z,1759340320.302 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:38:51.126Z,1759340331.126 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T17:39:11.442Z,1759340351.442 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:39:42.574Z,1759340382.574 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:40:13.714Z,1759340413.714 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:40:44.838Z,1759340444.838 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:41:15.963Z,1759340475.963 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:41:24.636Z,1759340484.636 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T17:41:37.564Z,1759340497.564 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T17:41:47.095Z,1759340507.095 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:41:50.894Z,1759340510.894 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T17:42:03.015Z,1759340523.015 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T17:42:15.943Z,1759340535.943 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T17:42:18.222Z,1759340538.222 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:42:28.871Z,1759340548.871 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T17:42:41.800Z,1759340561.800 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T17:42:49.346Z,1759340569.346 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:42:54.726Z,1759340574.726 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T17:43:08.060Z,1759340588.060 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T17:43:20.490Z,1759340600.490 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:43:20.589Z,1759340600.589 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T17:43:33.106Z,1759340613.106 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T17:43:46.041Z,1759340626.041 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5.
2025-10-01T17:43:46.044Z,1759340626.044 [BPC1](INFO): Received data from all battery sticks.
2025-10-01T17:43:51.622Z,1759340631.622 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:44:22.762Z,1759340662.762 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:44:53.894Z,1759340693.894 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:45:25.018Z,1759340725.018 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:45:56.142Z,1759340756.142 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:46:27.266Z,1759340787.266 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:46:58.390Z,1759340818.390 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:47:29.514Z,1759340849.514 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:48:00.638Z,1759340880.638 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:48:31.774Z,1759340911.774 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:49:02.898Z,1759340942.898 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:49:34.030Z,1759340974.030 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:50:05.154Z,1759341005.154 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:50:36.302Z,1759341036.302 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:51:07.427Z,1759341067.427 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:51:38.566Z,1759341098.566 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:52:09.692Z,1759341129.692 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:52:40.814Z,1759341160.814 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:53:11.946Z,1759341191.946 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:53:43.078Z,1759341223.078 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:54:14.202Z,1759341254.202 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:54:45.327Z,1759341285.327 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:55:16.451Z,1759341316.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:55:47.574Z,1759341347.574 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:56:18.698Z,1759341378.698 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:56:49.834Z,1759341409.834 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:57:20.958Z,1759341440.958 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:57:52.098Z,1759341472.098 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:58:23.222Z,1759341503.222 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:58:54.351Z,1759341534.351 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:59:25.474Z,1759341565.474 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T17:59:56.618Z,1759341596.618 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:00:27.743Z,1759341627.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:00:58.866Z,1759341658.866 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:01:29.990Z,1759341689.990 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:02:01.114Z,1759341721.114 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:02:32.250Z,1759341752.250 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:03:03.394Z,1759341783.394 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:03:34.526Z,1759341814.526 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:04:05.666Z,1759341845.666 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:04:36.791Z,1759341876.791 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:05:07.926Z,1759341907.926 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:05:39.050Z,1759341939.050 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:06:10.174Z,1759341970.174 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:06:41.298Z,1759342001.298 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:07:12.426Z,1759342032.426 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:07:43.550Z,1759342063.550 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:08:14.690Z,1759342094.690 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:08:45.815Z,1759342125.815 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:09:03.885Z,1759342143.885 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-10-01T18:09:16.966Z,1759342156.966 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:09:48.090Z,1759342188.090 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:10:19.214Z,1759342219.214 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:10:50.338Z,1759342250.338 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:11:21.462Z,1759342281.462 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:11:52.587Z,1759342312.587 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:12:23.726Z,1759342343.726 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:12:54.850Z,1759342374.850 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:13:25.978Z,1759342405.978 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:13:57.102Z,1759342437.102 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:14:28.230Z,1759342468.230 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:14:59.366Z,1759342499.366 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:15:30.498Z,1759342530.498 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:16:01.623Z,1759342561.623 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:16:32.746Z,1759342592.746 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:17:03.870Z,1759342623.870 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:17:34.994Z,1759342654.994 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:17:57.990Z,1759342677.990 [keepstation:SurfaceComms] Running Loop=1
2025-10-01T18:17:58.007Z,1759342678.007 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-10-01T18:17:58.007Z,1759342678.007 [keepstation:SurfaceComms:A] Running Loop=1
2025-10-01T18:17:58.007Z,1759342678.007 [keepstation:SurfaceComms:A] Stopped
2025-10-01T18:17:58.007Z,1759342678.007 [keepstation:SurfaceComms:B] Running Loop=1
2025-10-01T18:17:58.007Z,1759342678.007 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-10-01T18:17:58.394Z,1759342678.394 [keepstation:TransitToStation:Dive] Stopped
2025-10-01T18:17:58.394Z,1759342678.394 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-10-01T18:17:58.394Z,1759342678.394 [keepstation:TransitToStation:Dive:A] Stopped
2025-10-01T18:17:58.394Z,1759342678.394 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-10-01T18:17:58.395Z,1759342678.395 [keepstation:DiveCmd:StationKeep] Stopped
2025-10-01T18:17:58.395Z,1759342678.395 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-10-01T18:17:58.395Z,1759342678.395 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-10-01T18:17:58.395Z,1759342678.395 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-10-01T18:17:58.395Z,1759342678.395 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-10-01T18:17:58.395Z,1759342678.395 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-10-01T18:17:58.397Z,1759342678.397 [keepstation:NeedComms] Running Loop=1
2025-10-01T18:17:58.397Z,1759342678.397 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-10-01T18:17:58.397Z,1759342678.397 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-10-01T18:17:58.397Z,1759342678.397 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-10-01T18:17:58.397Z,1759342678.397 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-10-01T18:17:58.398Z,1759342678.398 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-10-01T18:17:58.398Z,1759342678.398 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-10-01T18:17:58.399Z,1759342678.399 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-10-01T18:17:58.399Z,1759342678.399 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-10-01T18:17:58.399Z,1759342678.399 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-10-01T18:17:58.400Z,1759342678.400 [keepstation:NeedComms:A] Running Loop=1
2025-10-01T18:17:58.401Z,1759342678.401 [keepstation:NeedComms:A](INFO): last time_fix was: 1759339976.000000 second since 1970/01/01T00:00:00Z
2025-10-01T18:17:58.401Z,1759342678.401 [keepstation:NeedComms:A] Stopped
2025-10-01T18:17:58.803Z,1759342678.803 [keepstation:NeedComms:C] Running Loop=1
2025-10-01T18:18:00.805Z,1759342680.805 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181800.00,A,3648.17581,N,12147.28413,W,0.019,0.00,011025,,,A*7D
2025-10-01T18:18:00.807Z,1759342680.807 [NAL9602](INFO): GPS fix at 20251001T181800: (36.802930, -121.788069)
2025-10-01T18:18:00.840Z,1759342680.840 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.802934,-121.788087
2025-10-01T18:18:00.847Z,1759342680.847 [keepstation:NeedComms:C] Stopped
2025-10-01T18:18:00.847Z,1759342680.847 [keepstation:NeedComms:D] Running Loop=1
2025-10-01T18:18:06.119Z,1759342686.119 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:18:16.672Z,1759342696.672 [NAL9602](INFO): SBD MO Status=1, MOMSN=64834, MT Status=0, MTMSN=0
2025-10-01T18:18:16.730Z,1759342696.730 [NAL9602](INFO): Sent 84 bytes from file Logs/20251001T162844/Courier0025.lzma
2025-10-01T18:18:16.730Z,1759342696.730 [NAL9602](INFO): Packets left to send: 0
2025-10-01T18:18:26.049Z,1759342706.049 [NAL9602](INFO): SBD MO Status=1, MOMSN=64835, MT Status=0, MTMSN=0
2025-10-01T18:18:26.102Z,1759342706.102 [NAL9602](INFO): Sent 114 bytes from file Logs/20251001T162844/Express0026.lzma
2025-10-01T18:18:26.102Z,1759342706.102 [NAL9602](INFO): Packets left to send: 0
2025-10-01T18:18:34.129Z,1759342714.129 [NAL9602](INFO): SBD MO Status=0, MOMSN=64836, MT Status=0, MTMSN=0
2025-10-01T18:18:34.209Z,1759342714.209 [keepstation:NeedComms:D] Stopped
2025-10-01T18:18:34.210Z,1759342714.210 [keepstation:NeedComms:E] Running Loop=1
2025-10-01T18:18:36.555Z,1759342716.555 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181835.00,A,3648.17611,N,12147.28451,W,0.019,0.00,011025,,,A*77
2025-10-01T18:18:36.557Z,1759342716.557 [NAL9602](INFO): GPS fix at 20251001T181835: (36.802935, -121.788075)
2025-10-01T18:18:36.569Z,1759342716.569 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.802934,-121.788087
2025-10-01T18:18:36.572Z,1759342716.572 [keepstation:NeedComms:E] Stopped
2025-10-01T18:18:36.572Z,1759342716.572 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-10-01T18:18:36.573Z,1759342716.573 [keepstation:NeedComms] Stopped
2025-10-01T18:18:36.573Z,1759342716.573 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-10-01T18:18:36.573Z,1759342716.573 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-10-01T18:18:36.573Z,1759342716.573 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-10-01T18:18:36.998Z,1759342716.998 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-10-01T18:18:36.998Z,1759342716.998 [keepstation:SurfaceComms:B] Stopped
2025-10-01T18:18:36.998Z,1759342716.998 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-10-01T18:18:36.998Z,1759342716.998 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-10-01T18:18:36.998Z,1759342716.998 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-10-01T18:18:36.998Z,1759342716.998 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-10-01T18:18:36.999Z,1759342716.999 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-10-01T18:18:36.999Z,1759342716.999 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-10-01T18:18:37.242Z,1759342717.242 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:18:37.384Z,1759342717.384 [keepstation:SurfaceComms:HandleNan] Stopped
2025-10-01T18:18:37.384Z,1759342717.384 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-10-01T18:18:37.384Z,1759342717.384 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-10-01T18:18:37.384Z,1759342717.384 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-10-01T18:18:37.384Z,1759342717.384 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-10-01T18:18:37.384Z,1759342717.384 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-10-01T18:18:37.384Z,1759342717.384 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-10-01T18:18:37.385Z,1759342717.385 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-10-01T18:18:37.820Z,1759342717.820 [keepstation:SurfaceComms:setTransit] Stopped
2025-10-01T18:18:37.820Z,1759342717.820 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-10-01T18:18:37.820Z,1759342717.820 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-10-01T18:18:37.820Z,1759342717.820 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-10-01T18:18:37.820Z,1759342717.820 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-10-01T18:18:37.820Z,1759342717.820 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-10-01T18:18:38.180Z,1759342718.180 [keepstation:SurfaceComms:setStation:A] Stopped
2025-10-01T18:18:38.180Z,1759342718.180 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-10-01T18:18:38.180Z,1759342718.180 [keepstation:SurfaceComms:setStation] Stopped
2025-10-01T18:18:38.180Z,1759342718.180 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-10-01T18:18:38.181Z,1759342718.181 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-10-01T18:18:38.181Z,1759342718.181 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-10-01T18:18:38.181Z,1759342718.181 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-10-01T18:18:38.587Z,1759342718.587 [keepstation:TransitToStation:Dive] Running Loop=1
2025-10-01T18:18:38.587Z,1759342718.587 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-10-01T18:18:38.587Z,1759342718.587 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-10-01T18:18:38.587Z,1759342718.587 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-10-01T18:18:38.588Z,1759342718.588 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-10-01T18:18:38.588Z,1759342718.588 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-10-01T18:18:38.588Z,1759342718.588 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-10-01T18:18:38.588Z,1759342718.588 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-10-01T18:18:38.588Z,1759342718.588 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-10-01T18:18:38.588Z,1759342718.588 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-10-01T18:18:38.588Z,1759342718.588 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-10-01T18:18:38.590Z,1759342718.590 [keepstation:SurfaceComms:setSink] Stopped
2025-10-01T18:18:38.590Z,1759342718.590 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-10-01T18:18:38.590Z,1759342718.590 [keepstation:SurfaceComms:setSink:A] Stopped
2025-10-01T18:18:38.591Z,1759342718.591 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-10-01T18:18:38.591Z,1759342718.591 [keepstation:SurfaceComms] Stopped
2025-10-01T18:18:38.591Z,1759342718.591 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-10-01T18:19:08.374Z,1759342748.374 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:19:08.468Z,1759342748.467 [NAL9602](INFO): Not Powering down - fast GPS
2025-10-01T18:19:39.514Z,1759342779.514 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:20:10.638Z,1759342810.638 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:20:41.762Z,1759342841.762 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:21:12.886Z,1759342872.886 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:21:44.011Z,1759342904.011 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:22:15.143Z,1759342935.143 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:22:46.266Z,1759342966.266 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:23:17.390Z,1759342997.390 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:23:48.526Z,1759343028.526 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:24:19.650Z,1759343059.650 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:24:50.790Z,1759343090.790 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:25:21.914Z,1759343121.914 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:25:53.050Z,1759343153.050 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:26:24.174Z,1759343184.174 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:26:55.298Z,1759343215.298 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:27:26.442Z,1759343246.442 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:27:57.586Z,1759343277.586 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:28:28.710Z,1759343308.710 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:28:59.834Z,1759343339.834 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:29:30.970Z,1759343370.970 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:30:02.102Z,1759343402.102 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:30:33.226Z,1759343433.226 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:31:04.350Z,1759343464.350 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:31:35.478Z,1759343495.478 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:32:06.602Z,1759343526.602 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:32:37.734Z,1759343557.734 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:33:08.871Z,1759343588.871 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:33:40.006Z,1759343620.006 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:34:11.139Z,1759343651.139 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:34:42.262Z,1759343682.262 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:35:13.395Z,1759343713.395 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:35:44.518Z,1759343744.518 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:36:15.646Z,1759343775.646 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:36:46.770Z,1759343806.770 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:37:17.903Z,1759343837.903 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:37:49.030Z,1759343869.030 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:38:20.156Z,1759343900.156 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:38:51.286Z,1759343931.286 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:39:22.418Z,1759343962.418 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:39:53.550Z,1759343993.550 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:40:24.682Z,1759344024.682 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:40:55.809Z,1759344055.809 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:41:26.930Z,1759344086.930 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:41:58.054Z,1759344118.054 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:42:29.178Z,1759344149.178 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:43:00.310Z,1759344180.310 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:43:31.450Z,1759344211.450 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:44:02.583Z,1759344242.583 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:44:33.706Z,1759344273.706 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:44:52.158Z,1759344292.158 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T18:45:04.279Z,1759344304.279 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T18:45:04.830Z,1759344304.830 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:45:16.399Z,1759344316.399 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T18:45:28.519Z,1759344328.519 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T18:45:35.966Z,1759344335.966 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:45:40.639Z,1759344340.639 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T18:45:52.758Z,1759344352.758 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T18:46:04.476Z,1759344364.476 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T18:46:07.106Z,1759344367.106 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:46:15.786Z,1759344375.786 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T18:46:38.230Z,1759344398.230 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:47:09.366Z,1759344429.366 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:47:40.490Z,1759344460.490 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:47:49.925Z,1759344469.925 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-10-01T18:48:11.630Z,1759344491.630 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:48:42.754Z,1759344522.754 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:48:56.581Z,1759344536.581 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T18:49:11.538Z,1759344551.538 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T18:49:13.882Z,1759344553.882 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:49:26.475Z,1759344566.475 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T18:49:41.826Z,1759344581.826 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T18:49:45.006Z,1759344585.006 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:49:57.180Z,1759344597.180 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T18:50:12.127Z,1759344612.127 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T18:50:16.134Z,1759344616.134 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:50:27.479Z,1759344627.479 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T18:50:42.426Z,1759344642.426 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T18:50:47.262Z,1759344647.262 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:50:57.375Z,1759344657.375 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T18:51:11.531Z,1759344671.531 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5.
2025-10-01T18:51:11.533Z,1759344671.533 [BPC1](INFO): Received data from all battery sticks.
2025-10-01T18:51:18.386Z,1759344678.386 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:51:49.522Z,1759344709.522 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:52:20.647Z,1759344740.647 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:52:51.770Z,1759344771.770 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:53:22.894Z,1759344802.894 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:53:54.024Z,1759344834.024 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:54:25.166Z,1759344865.166 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:54:56.302Z,1759344896.302 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:55:27.442Z,1759344927.442 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:55:58.574Z,1759344958.574 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:56:29.698Z,1759344989.698 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:57:00.830Z,1759345020.830 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:57:31.954Z,1759345051.954 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:58:03.086Z,1759345083.086 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:58:34.210Z,1759345114.210 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:59:05.334Z,1759345145.334 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T18:59:36.458Z,1759345176.458 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:00:07.598Z,1759345207.598 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:00:38.723Z,1759345238.723 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:01:09.846Z,1759345269.846 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:01:40.974Z,1759345300.974 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:02:12.106Z,1759345332.106 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:02:43.230Z,1759345363.230 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:03:14.354Z,1759345394.354 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:03:36.917Z,1759345416.917 [keepstation:SurfaceComms] Running Loop=1
2025-10-01T19:03:36.917Z,1759345416.917 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-10-01T19:03:36.917Z,1759345416.917 [keepstation:SurfaceComms:A] Running Loop=1
2025-10-01T19:03:36.918Z,1759345416.918 [keepstation:SurfaceComms:A] Stopped
2025-10-01T19:03:36.918Z,1759345416.918 [keepstation:SurfaceComms:B] Running Loop=1
2025-10-01T19:03:36.918Z,1759345416.918 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-10-01T19:03:37.331Z,1759345417.331 [keepstation:TransitToStation:Dive] Stopped
2025-10-01T19:03:37.332Z,1759345417.332 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-10-01T19:03:37.332Z,1759345417.332 [keepstation:TransitToStation:Dive:A] Stopped
2025-10-01T19:03:37.332Z,1759345417.332 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-10-01T19:03:37.332Z,1759345417.332 [keepstation:DiveCmd:StationKeep] Stopped
2025-10-01T19:03:37.332Z,1759345417.332 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-10-01T19:03:37.332Z,1759345417.332 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-10-01T19:03:37.332Z,1759345417.332 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-10-01T19:03:37.333Z,1759345417.333 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-10-01T19:03:37.333Z,1759345417.333 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-10-01T19:03:37.338Z,1759345417.338 [keepstation:NeedComms] Running Loop=1
2025-10-01T19:03:37.339Z,1759345417.339 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-10-01T19:03:37.339Z,1759345417.339 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-10-01T19:03:37.339Z,1759345417.339 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-10-01T19:03:37.339Z,1759345417.339 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-10-01T19:03:37.339Z,1759345417.339 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-10-01T19:03:37.340Z,1759345417.340 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-10-01T19:03:37.340Z,1759345417.340 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-10-01T19:03:37.340Z,1759345417.340 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-10-01T19:03:37.341Z,1759345417.341 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-10-01T19:03:37.341Z,1759345417.341 [keepstation:NeedComms:A] Running Loop=1
2025-10-01T19:03:37.345Z,1759345417.345 [keepstation:NeedComms:A](INFO): last time_fix was: 1759342715.000000 second since 1970/01/01T00:00:00Z
2025-10-01T19:03:37.345Z,1759345417.345 [keepstation:NeedComms:A] Stopped
2025-10-01T19:03:37.721Z,1759345417.721 [keepstation:NeedComms:C] Running Loop=1
2025-10-01T19:03:39.727Z,1759345419.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190339.00,A,3648.17723,N,12147.28536,W,0.078,0.00,011025,,,A*77
2025-10-01T19:03:39.730Z,1759345419.730 [NAL9602](INFO): GPS fix at 20251001T190339: (36.802954, -121.788089)
2025-10-01T19:03:39.741Z,1759345419.741 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.802934,-121.788087
2025-10-01T19:03:39.741Z,1759345419.741 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-10-01T19:03:39.741Z,1759345419.741 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-10-01T19:03:39.742Z,1759345419.742 [keepstation:TransitToStation:C] Running Loop=1
2025-10-01T19:03:39.744Z,1759345419.744 [keepstation:NeedComms:C] Stopped
2025-10-01T19:03:39.744Z,1759345419.744 [keepstation:NeedComms:D] Running Loop=1
2025-10-01T19:03:40.159Z,1759345420.159 [keepstation:TransitToStation:C] Stopped
2025-10-01T19:03:40.160Z,1759345420.160 [keepstation:TransitToStation:D] Running Loop=1
2025-10-01T19:03:40.160Z,1759345420.160 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-10-01T19:03:40.160Z,1759345420.160 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-10-01T19:03:40.160Z,1759345420.160 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-10-01T19:03:40.160Z,1759345420.160 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-10-01T19:03:40.160Z,1759345420.160 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-10-01T19:03:40.160Z,1759345420.160 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-10-01T19:03:40.161Z,1759345420.161 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-10-01T19:03:40.568Z,1759345420.568 [keepstation:TransitToStation:D] Stopped
2025-10-01T19:03:40.568Z,1759345420.568 [keepstation:TransitToStation:Dive] Running Loop=1
2025-10-01T19:03:40.568Z,1759345420.568 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-10-01T19:03:40.568Z,1759345420.568 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-10-01T19:03:40.568Z,1759345420.568 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-10-01T19:03:40.568Z,1759345420.568 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-10-01T19:03:40.568Z,1759345420.568 [keepstation:TransitToStation] Stopped
2025-10-01T19:03:40.568Z,1759345420.568 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-10-01T19:03:40.568Z,1759345420.568 [keepstation:TransitToStation:Dive] Stopped
2025-10-01T19:03:40.569Z,1759345420.569 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-10-01T19:03:40.569Z,1759345420.569 [keepstation:TransitToStation:Dive:A] Stopped
2025-10-01T19:03:40.569Z,1759345420.569 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-10-01T19:03:40.569Z,1759345420.569 [keepstation:OnStation] Running Loop=1
2025-10-01T19:03:40.569Z,1759345420.569 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-10-01T19:03:45.478Z,1759345425.478 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:04:14.567Z,1759345454.567 [NAL9602](INFO): SBD MO Status=1, MOMSN=64837, MT Status=0, MTMSN=0
2025-10-01T19:04:14.615Z,1759345454.615 [NAL9602](INFO): Sent 84 bytes from file Logs/20251001T162844/Courier0028.lzma
2025-10-01T19:04:14.615Z,1759345454.615 [NAL9602](INFO): Packets left to send: 0
2025-10-01T19:04:16.602Z,1759345456.602 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:04:30.393Z,1759345470.393 [NAL9602](INFO): SBD MO Status=2, MOMSN=64838, MT Status=2, MTMSN=0
2025-10-01T19:04:30.393Z,1759345470.393 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-10-01T19:04:45.393Z,1759345485.393 [NAL9602](INFO): SBD MO Status=2, MOMSN=64838, MT Status=2, MTMSN=0
2025-10-01T19:04:45.394Z,1759345485.394 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-10-01T19:04:47.730Z,1759345487.730 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:05:12.110Z,1759345512.110 [NAL9602](INFO): SBD MO Status=1, MOMSN=64838, MT Status=0, MTMSN=0
2025-10-01T19:05:12.170Z,1759345512.170 [NAL9602](INFO): Sent 157 bytes from file Logs/20251001T162844/Express0029.lzma
2025-10-01T19:05:12.171Z,1759345512.171 [NAL9602](INFO): Packets left to send: 0
2025-10-01T19:05:18.886Z,1759345518.886 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:05:27.053Z,1759345527.053 [NAL9602](INFO): SBD MO Status=0, MOMSN=64839, MT Status=0, MTMSN=0
2025-10-01T19:05:27.152Z,1759345527.152 [keepstation:NeedComms:D] Stopped
2025-10-01T19:05:27.157Z,1759345527.157 [keepstation:NeedComms:E] Running Loop=1
2025-10-01T19:05:29.475Z,1759345529.475 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190528.00,A,3648.17551,N,12147.28437,W,0.156,0.00,011025,,,A*7B
2025-10-01T19:05:29.477Z,1759345529.477 [NAL9602](INFO): GPS fix at 20251001T190528: (36.802925, -121.788073)
2025-10-01T19:05:29.492Z,1759345529.492 [keepstation:NeedComms:E] Stopped
2025-10-01T19:05:29.492Z,1759345529.492 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-10-01T19:05:29.493Z,1759345529.493 [keepstation:NeedComms] Stopped
2025-10-01T19:05:29.493Z,1759345529.493 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-10-01T19:05:29.493Z,1759345529.493 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-10-01T19:05:29.493Z,1759345529.493 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-10-01T19:05:29.915Z,1759345529.915 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-10-01T19:05:29.915Z,1759345529.915 [keepstation:OnStation] Stopped
2025-10-01T19:05:29.915Z,1759345529.915 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-10-01T19:05:29.916Z,1759345529.916 [keepstation:KeepStation] Running Loop=1
2025-10-01T19:05:29.916Z,1759345529.916 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-10-01T19:05:29.916Z,1759345529.916 [keepstation:KeepStation:Dive] Running Loop=1
2025-10-01T19:05:29.916Z,1759345529.916 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-10-01T19:05:29.916Z,1759345529.916 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-10-01T19:05:29.916Z,1759345529.916 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-10-01T19:05:29.916Z,1759345529.916 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-10-01T19:05:29.916Z,1759345529.916 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-10-01T19:05:29.918Z,1759345529.918 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-10-01T19:05:29.918Z,1759345529.918 [keepstation:SurfaceComms:B] Stopped
2025-10-01T19:05:29.919Z,1759345529.919 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-10-01T19:05:29.919Z,1759345529.919 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-10-01T19:05:29.919Z,1759345529.919 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-10-01T19:05:29.919Z,1759345529.919 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-10-01T19:05:29.919Z,1759345529.919 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-10-01T19:05:29.919Z,1759345529.919 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-10-01T19:05:30.312Z,1759345530.312 [keepstation:KeepStation:Dive] Running Loop=1
2025-10-01T19:05:30.314Z,1759345530.314 [keepstation:SurfaceComms:HandleNan] Stopped
2025-10-01T19:05:30.314Z,1759345530.314 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-10-01T19:05:30.314Z,1759345530.314 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-10-01T19:05:30.314Z,1759345530.314 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-10-01T19:05:30.314Z,1759345530.314 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-10-01T19:05:30.315Z,1759345530.315 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-10-01T19:05:30.315Z,1759345530.315 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-10-01T19:05:30.315Z,1759345530.315 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-10-01T19:05:30.710Z,1759345530.710 [keepstation:SurfaceComms:setTransit] Stopped
2025-10-01T19:05:30.710Z,1759345530.710 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-10-01T19:05:30.710Z,1759345530.710 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-10-01T19:05:30.711Z,1759345530.711 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-10-01T19:05:30.711Z,1759345530.711 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-10-01T19:05:30.711Z,1759345530.711 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-10-01T19:05:31.117Z,1759345531.117 [keepstation:SurfaceComms:setStation:A] Stopped
2025-10-01T19:05:31.117Z,1759345531.117 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-10-01T19:05:31.117Z,1759345531.117 [keepstation:SurfaceComms:setStation] Stopped
2025-10-01T19:05:31.117Z,1759345531.117 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-10-01T19:05:31.117Z,1759345531.117 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-10-01T19:05:31.117Z,1759345531.117 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-10-01T19:05:31.117Z,1759345531.117 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-10-01T19:05:31.508Z,1759345531.508 [keepstation:SurfaceComms:setSink] Stopped
2025-10-01T19:05:31.508Z,1759345531.508 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-10-01T19:05:31.509Z,1759345531.509 [keepstation:SurfaceComms:setSink:A] Stopped
2025-10-01T19:05:31.509Z,1759345531.509 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-10-01T19:05:31.509Z,1759345531.509 [keepstation:SurfaceComms] Stopped
2025-10-01T19:05:31.509Z,1759345531.509 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-10-01T19:05:50.026Z,1759345550.026 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:06:01.395Z,1759345561.395 [NAL9602](INFO): Not Powering down - fast GPS
2025-10-01T19:06:21.150Z,1759345581.150 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:06:52.274Z,1759345612.274 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:07:23.406Z,1759345643.406 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:07:54.542Z,1759345674.542 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:08:25.666Z,1759345705.666 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:08:56.790Z,1759345736.790 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:09:27.930Z,1759345767.930 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:09:59.063Z,1759345799.063 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:10:30.186Z,1759345830.186 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:11:01.326Z,1759345861.326 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:11:32.467Z,1759345892.467 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:12:03.590Z,1759345923.590 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:12:34.714Z,1759345954.714 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:13:05.838Z,1759345985.838 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:13:36.970Z,1759346016.970 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:14:08.094Z,1759346048.094 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:14:39.218Z,1759346079.218 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:15:10.342Z,1759346110.342 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:15:41.478Z,1759346141.478 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:16:12.602Z,1759346172.602 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:16:43.726Z,1759346203.726 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:17:14.850Z,1759346234.850 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:17:45.974Z,1759346265.974 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:18:17.098Z,1759346297.098 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:18:48.222Z,1759346328.222 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:19:19.351Z,1759346359.351 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:19:50.474Z,1759346390.474 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:20:21.606Z,1759346421.606 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:20:52.755Z,1759346452.755 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:21:23.898Z,1759346483.898 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:21:55.046Z,1759346515.046 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:22:26.170Z,1759346546.170 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:22:57.294Z,1759346577.294 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:23:28.418Z,1759346608.418 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:23:59.542Z,1759346639.542 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:24:30.678Z,1759346670.678 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:25:01.802Z,1759346701.802 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:25:32.926Z,1759346732.926 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:26:04.066Z,1759346764.066 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:26:35.203Z,1759346795.203 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:27:06.326Z,1759346826.326 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:27:37.450Z,1759346857.450 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:28:08.574Z,1759346888.574 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:28:39.706Z,1759346919.706 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:29:10.842Z,1759346950.842 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:29:41.966Z,1759346981.966 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:30:13.122Z,1759347013.122 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:30:44.246Z,1759347044.246 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:31:15.382Z,1759347075.382 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:31:46.507Z,1759347106.507 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:32:17.630Z,1759347137.630 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:32:48.754Z,1759347168.754 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:33:19.886Z,1759347199.886 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:33:51.026Z,1759347231.026 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:34:22.158Z,1759347262.158 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:34:53.282Z,1759347293.282 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:35:24.410Z,1759347324.410 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:35:55.535Z,1759347355.535 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:36:26.678Z,1759347386.678 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:36:57.802Z,1759347417.802 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:37:28.926Z,1759347448.926 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:38:00.050Z,1759347480.050 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:38:31.174Z,1759347511.174 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:39:02.298Z,1759347542.298 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:39:33.422Z,1759347573.422 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:40:04.550Z,1759347604.550 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:40:35.698Z,1759347635.698 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:41:06.826Z,1759347666.826 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:41:37.974Z,1759347697.974 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:42:09.098Z,1759347729.098 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:42:40.242Z,1759347760.242 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:43:11.366Z,1759347791.366 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:43:42.496Z,1759347822.496 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:44:13.618Z,1759347853.618 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:44:44.742Z,1759347884.742 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:45:15.866Z,1759347915.866 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:45:46.990Z,1759347946.990 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:46:18.130Z,1759347978.130 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:46:49.254Z,1759348009.254 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:47:20.378Z,1759348040.378 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:47:51.502Z,1759348071.502 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:48:22.642Z,1759348102.642 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:48:53.802Z,1759348133.802 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:49:24.926Z,1759348164.926 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:49:56.051Z,1759348196.051 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:50:27.174Z,1759348227.174 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:50:29.827Z,1759348229.827 [keepstation:SurfaceComms] Running Loop=1
2025-10-01T19:50:29.827Z,1759348229.827 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-10-01T19:50:29.827Z,1759348229.827 [keepstation:SurfaceComms:A] Running Loop=1
2025-10-01T19:50:29.828Z,1759348229.828 [keepstation:SurfaceComms:A] Stopped
2025-10-01T19:50:29.828Z,1759348229.828 [keepstation:SurfaceComms:B] Running Loop=1
2025-10-01T19:50:29.828Z,1759348229.828 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-10-01T19:50:30.234Z,1759348230.234 [keepstation:KeepStation:Dive] Stopped
2025-10-01T19:50:30.234Z,1759348230.234 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-10-01T19:50:30.234Z,1759348230.234 [keepstation:KeepStation:Dive:A] Stopped
2025-10-01T19:50:30.234Z,1759348230.234 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-10-01T19:50:30.235Z,1759348230.235 [keepstation:DiveCmd:StationKeep] Stopped
2025-10-01T19:50:30.235Z,1759348230.235 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-10-01T19:50:30.235Z,1759348230.235 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-10-01T19:50:30.235Z,1759348230.235 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-10-01T19:50:30.235Z,1759348230.235 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-10-01T19:50:30.235Z,1759348230.235 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-10-01T19:50:30.237Z,1759348230.237 [keepstation:NeedComms] Running Loop=1
2025-10-01T19:50:30.237Z,1759348230.237 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-10-01T19:50:30.237Z,1759348230.237 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-10-01T19:50:30.237Z,1759348230.237 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-10-01T19:50:30.237Z,1759348230.237 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-10-01T19:50:30.238Z,1759348230.238 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-10-01T19:50:30.238Z,1759348230.238 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-10-01T19:50:30.239Z,1759348230.239 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-10-01T19:50:30.239Z,1759348230.239 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-10-01T19:50:30.239Z,1759348230.239 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-10-01T19:50:30.239Z,1759348230.239 [keepstation:NeedComms:A] Running Loop=1
2025-10-01T19:50:30.241Z,1759348230.241 [keepstation:NeedComms:A](INFO): last time_fix was: 1759345528.000000 second since 1970/01/01T00:00:00Z
2025-10-01T19:50:30.241Z,1759348230.241 [keepstation:NeedComms:A] Stopped
2025-10-01T19:50:30.649Z,1759348230.649 [keepstation:NeedComms:C] Running Loop=1
2025-10-01T19:50:32.645Z,1759348232.645 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195031.00,A,3648.17593,N,12147.28392,W,0.019,0.00,011025,,,A*7F
2025-10-01T19:50:32.647Z,1759348232.647 [NAL9602](INFO): GPS fix at 20251001T195031: (36.802932, -121.788065)
2025-10-01T19:50:32.660Z,1759348232.660 [keepstation:NeedComms:C] Stopped
2025-10-01T19:50:32.660Z,1759348232.660 [keepstation:NeedComms:D] Running Loop=1
2025-10-01T19:50:54.527Z,1759348254.527 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=64840, MT Status=1, MTMSN=3936
2025-10-01T19:50:54.582Z,1759348254.582 [NAL9602](INFO): Sent 73 bytes from file Logs/20251001T162844/Courier0031.lzma
2025-10-01T19:50:54.583Z,1759348254.583 [NAL9602](INFO): Packets left to send: 0
2025-10-01T19:50:55.035Z,1759348255.035 [NAL9602](INFO): Received command: ! echo 1 > /dev/loadB6
2025-10-01T19:50:55.100Z,1759348255.100 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB6
2025-10-01T19:50:58.318Z,1759348258.318 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:51:12.392Z,1759348272.392 [NAL9602](INFO): SBD MO Status=1, MOMSN=64841, MT Status=0, MTMSN=0
2025-10-01T19:51:12.442Z,1759348272.442 [NAL9602](INFO): Sent 111 bytes from file Logs/20251001T162844/Express0032.lzma
2025-10-01T19:51:12.442Z,1759348272.442 [NAL9602](INFO): Packets left to send: 0
2025-10-01T19:51:29.442Z,1759348289.442 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:51:33.401Z,1759348293.401 [NAL9602](INFO): SBD MO Status=0, MOMSN=64842, MT Status=0, MTMSN=0
2025-10-01T19:51:44.350Z,1759348304.350 [NAL9602](INFO): SBD MO Status=1, MOMSN=64843, MT Status=0, MTMSN=0
2025-10-01T19:51:44.402Z,1759348304.402 [NAL9602](INFO): Sent 39 bytes from file Logs/20251001T162844/Courier0034.lzma
2025-10-01T19:51:44.402Z,1759348304.402 [NAL9602](INFO): Packets left to send: 0
2025-10-01T19:51:55.719Z,1759348315.719 [NAL9602](INFO): SBD MO Status=1, MOMSN=64844, MT Status=0, MTMSN=0
2025-10-01T19:51:55.769Z,1759348315.769 [NAL9602](INFO): Sent 159 bytes from file Logs/20251001T162844/Express0035.lzma
2025-10-01T19:51:55.769Z,1759348315.769 [NAL9602](INFO): Packets left to send: 0
2025-10-01T19:52:00.566Z,1759348320.566 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:52:07.427Z,1759348327.427 [NAL9602](INFO): SBD MO Status=0, MOMSN=64845, MT Status=0, MTMSN=0
2025-10-01T19:52:07.511Z,1759348327.511 [keepstation:NeedComms:D] Stopped
2025-10-01T19:52:07.512Z,1759348327.512 [keepstation:NeedComms:E] Running Loop=1
2025-10-01T19:52:09.850Z,1759348329.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195209.00,A,3648.17623,N,12147.28431,W,0.019,0.00,011025,,,A*70
2025-10-01T19:52:09.852Z,1759348329.852 [NAL9602](INFO): GPS fix at 20251001T195209: (36.802937, -121.788072)
2025-10-01T19:52:09.866Z,1759348329.866 [keepstation:NeedComms:E] Stopped
2025-10-01T19:52:09.867Z,1759348329.867 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-10-01T19:52:09.867Z,1759348329.867 [keepstation:NeedComms] Stopped
2025-10-01T19:52:09.867Z,1759348329.867 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-10-01T19:52:09.867Z,1759348329.867 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-10-01T19:52:09.867Z,1759348329.867 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-10-01T19:52:10.292Z,1759348330.292 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-10-01T19:52:10.292Z,1759348330.292 [keepstation:SurfaceComms:B] Stopped
2025-10-01T19:52:10.292Z,1759348330.292 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-10-01T19:52:10.292Z,1759348330.292 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-10-01T19:52:10.292Z,1759348330.292 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-10-01T19:52:10.293Z,1759348330.293 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-10-01T19:52:10.293Z,1759348330.293 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-10-01T19:52:10.293Z,1759348330.293 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-10-01T19:52:10.687Z,1759348330.687 [keepstation:SurfaceComms:HandleNan] Stopped
2025-10-01T19:52:10.687Z,1759348330.687 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-10-01T19:52:10.687Z,1759348330.687 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-10-01T19:52:10.688Z,1759348330.688 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-10-01T19:52:10.688Z,1759348330.688 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-10-01T19:52:10.688Z,1759348330.688 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-10-01T19:52:10.688Z,1759348330.688 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-10-01T19:52:10.688Z,1759348330.688 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-10-01T19:52:11.081Z,1759348331.081 [keepstation:SurfaceComms:setTransit] Stopped
2025-10-01T19:52:11.081Z,1759348331.081 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-10-01T19:52:11.081Z,1759348331.081 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-10-01T19:52:11.081Z,1759348331.081 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-10-01T19:52:11.081Z,1759348331.081 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-10-01T19:52:11.081Z,1759348331.081 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-10-01T19:52:11.511Z,1759348331.511 [keepstation:SurfaceComms:setStation:A] Stopped
2025-10-01T19:52:11.511Z,1759348331.511 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2025-10-01T19:52:11.511Z,1759348331.511 [keepstation:SurfaceComms:setStation] Stopped
2025-10-01T19:52:11.511Z,1759348331.511 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-10-01T19:52:11.512Z,1759348331.512 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-10-01T19:52:11.512Z,1759348331.512 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-10-01T19:52:11.512Z,1759348331.512 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-10-01T19:52:11.900Z,1759348331.900 [keepstation:KeepStation:Dive] Running Loop=1
2025-10-01T19:52:11.900Z,1759348331.900 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-10-01T19:52:11.900Z,1759348331.900 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-10-01T19:52:11.900Z,1759348331.900 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-10-01T19:52:11.901Z,1759348331.901 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-10-01T19:52:11.901Z,1759348331.901 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-10-01T19:52:11.901Z,1759348331.901 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-10-01T19:52:11.901Z,1759348331.901 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-10-01T19:52:11.901Z,1759348331.901 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-10-01T19:52:11.902Z,1759348331.902 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-10-01T19:52:11.902Z,1759348331.902 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-10-01T19:52:11.903Z,1759348331.903 [keepstation:SurfaceComms:setSink] Stopped
2025-10-01T19:52:11.904Z,1759348331.904 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-10-01T19:52:11.904Z,1759348331.904 [keepstation:SurfaceComms:setSink:A] Stopped
2025-10-01T19:52:11.904Z,1759348331.904 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-10-01T19:52:11.904Z,1759348331.904 [keepstation:SurfaceComms] Stopped
2025-10-01T19:52:11.904Z,1759348331.904 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-10-01T19:52:17.522Z,1759348337.522 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T19:52:29.642Z,1759348349.642 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T19:52:31.690Z,1759348351.690 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:52:40.954Z,1759348360.954 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T19:52:42.572Z,1759348362.572 [NAL9602](INFO): Not Powering down - fast GPS
2025-10-01T19:52:52.671Z,1759348372.671 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T19:53:02.815Z,1759348382.815 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:53:04.388Z,1759348384.388 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T19:53:16.102Z,1759348396.102 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-10-01T19:53:33.946Z,1759348413.946 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:54:05.070Z,1759348445.070 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-10-01T19:54:34.670Z,1759348474.670 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002872
2025-10-01T19:56:10.647Z,1759348570.647 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:56:23.575Z,1759348583.575 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:56:36.909Z,1759348596.909 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:56:49.423Z,1759348609.423 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:57:01.538Z,1759348621.538 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:57:14.063Z,1759348634.063 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:57:26.586Z,1759348646.586 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:57:39.110Z,1759348659.110 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:57:52.039Z,1759348672.039 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:58:05.372Z,1759348685.372 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:58:17.911Z,1759348697.911 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:58:30.850Z,1759348710.850 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-10-01T19:58:30.857Z,1759348710.857 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5.
2025-10-01T19:58:30.876Z,1759348710.876 [BPC1](INFO): Received data from all battery sticks.
2025-10-01T20:01:29.723Z,1759348889.723 [CommandExec](IMPORTANT): got command quit
2025-10-01T20:01:30.726Z,1759348890.726 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2025-10-01T20:01:30.726Z,1759348890.726 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:01:30.727Z,1759348890.727 [CommandExec](INFO): Uninitializing the command executive.
2025-10-01T20:01:30.727Z,1759348890.727 [CommandExec](INFO): Uninitializing the command scheduler.
2025-10-01T20:01:30.727Z,1759348890.727 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:30.898Z,1759348890.898 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-10-01T20:01:30.898Z,1759348890.898 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-10-01T20:01:30.898Z,1759348890.898 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:30.899Z,1759348890.899 [NavChartDb](INFO): Join timeout helper Thread ID is 8105
2025-10-01T20:01:31.091Z,1759348891.091 [MissionManager](INFO): MissionManager is completed.
2025-10-01T20:01:31.091Z,1759348891.091 [MissionManager](INFO): Uninitializing Mission keepstation
2025-10-01T20:01:31.091Z,1759348891.091 [keepstation] Stopped
2025-10-01T20:01:31.091Z,1759348891.091 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-10-01T20:01:31.091Z,1759348891.091 [keepstation:StandardEnvelopes] Stopped
2025-10-01T20:01:31.091Z,1759348891.091 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-10-01T20:01:31.091Z,1759348891.091 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-10-01T20:01:31.091Z,1759348891.091 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-10-01T20:01:31.091Z,1759348891.091 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-10-01T20:01:31.092Z,1759348891.092 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-10-01T20:01:31.092Z,1759348891.092 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-10-01T20:01:31.092Z,1759348891.092 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-10-01T20:01:31.092Z,1759348891.092 [keepstation:BackseatDriver] Stopped
2025-10-01T20:01:31.092Z,1759348891.092 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-10-01T20:01:31.092Z,1759348891.092 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-10-01T20:01:31.092Z,1759348891.092 [keepstation:PowerOnly] Stopped
2025-10-01T20:01:31.092Z,1759348891.092 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-10-01T20:01:31.092Z,1759348891.092 [keepstation:PowerOnly:E.Wait] Stopped
2025-10-01T20:01:31.092Z,1759348891.092 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:H.Pitch] Stopped
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:DiveCmd] Stopped
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:DiveCmd:StationKeep] Stopped
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:KeepStation] Stopped
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-10-01T20:01:31.093Z,1759348891.093 [keepstation:KeepStation:Dive] Stopped
2025-10-01T20:01:31.094Z,1759348891.094 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-10-01T20:01:31.094Z,1759348891.094 [keepstation:KeepStation:Dive:A] Stopped
2025-10-01T20:01:31.094Z,1759348891.094 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-10-01T20:01:31.094Z,1759348891.094 [keepstation:KeepStation:B.Wait] Stopped
2025-10-01T20:01:31.094Z,1759348891.094 [keepstation:KeepStation:B.Wait](DEBUG): Uninitialize Wait Component.
2025-10-01T20:01:31.222Z,1759348891.222 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:01:31.223Z,1759348891.223 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:31.230Z,1759348891.230 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-10-01T20:01:31.230Z,1759348891.230 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:31.231Z,1759348891.231 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8106
2025-10-01T20:01:31.462Z,1759348891.462 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:01:31.462Z,1759348891.462 [WetLabsBB2FL](INFO): Powering down
2025-10-01T20:01:31.463Z,1759348891.463 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:31.482Z,1759348891.482 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-10-01T20:01:31.482Z,1759348891.482 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:31.482Z,1759348891.482 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8107
2025-10-01T20:01:31.521Z,1759348891.521 [MissionManager](IMPORTANT): Started mission Default
2025-10-01T20:01:31.521Z,1759348891.521 [Default] Running Loop=1
2025-10-01T20:01:31.521Z,1759348891.522 [Default](DEBUG): Aggregate::initialize Default
2025-10-01T20:01:31.522Z,1759348891.522 [Default:B.GoToSurface] Running Loop=1
2025-10-01T20:01:31.522Z,1759348891.522 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-10-01T20:01:31.522Z,1759348891.522 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-10-01T20:01:31.522Z,1759348891.522 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-10-01T20:01:31.523Z,1759348891.523 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-10-01T20:01:31.523Z,1759348891.523 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-10-01T20:01:31.523Z,1759348891.523 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-10-01T20:01:31.524Z,1759348891.524 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-10-01T20:01:31.524Z,1759348891.524 [Default:A.Wait] Running Loop=1
2025-10-01T20:01:31.524Z,1759348891.524 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-10-01T20:01:31.542Z,1759348891.542 [CTD_Seabird](INFO): Powering down
2025-10-01T20:01:31.554Z,1759348891.554 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:01:31.554Z,1759348891.554 [CTD_Seabird](INFO): Powering down
2025-10-01T20:01:31.566Z,1759348891.566 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:31.570Z,1759348891.570 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-10-01T20:01:31.570Z,1759348891.570 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:31.571Z,1759348891.571 [Radio_Surface](INFO): Join timeout helper Thread ID is 8108
2025-10-01T20:01:31.742Z,1759348891.742 [Radio_Surface](INFO): Powering down
2025-10-01T20:01:31.743Z,1759348891.743 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:01:31.744Z,1759348891.744 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:31.750Z,1759348891.750 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-10-01T20:01:31.750Z,1759348891.750 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:31.751Z,1759348891.751 [Onboard](INFO): Join timeout helper Thread ID is 8109
2025-10-01T20:01:31.999Z,1759348891.999 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:01:31.999Z,1759348891.999 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:32.019Z,1759348892.019 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-10-01T20:01:32.019Z,1759348892.019 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:32.019Z,1759348892.019 [DataOverHttps](INFO): Join timeout helper Thread ID is 8111
2025-10-01T20:01:32.074Z,1759348892.074 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:01:32.075Z,1759348892.075 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:32.087Z,1759348892.087 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-10-01T20:01:32.087Z,1759348892.087 [DAT ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:32.087Z,1759348892.087 [DAT](INFO): Join timeout helper Thread ID is 8112
2025-10-01T20:01:32.290Z,1759348892.290 [DAT](INFO): Powering down
2025-10-01T20:01:32.359Z,1759348892.359 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:01:32.359Z,1759348892.359 [DAT](INFO): Powering down
2025-10-01T20:01:32.360Z,1759348892.360 [DAT ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:32.363Z,1759348892.363 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-10-01T20:01:32.363Z,1759348892.363 [logger ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:32.363Z,1759348892.363 [logger](INFO): Join timeout helper Thread ID is 8113
2025-10-01T20:01:32.364Z,1759348892.364 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:01:32.364Z,1759348892.364 [logger ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:32.365Z,1759348892.365 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-10-01T20:01:32.365Z,1759348892.365 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:32.366Z,1759348892.366 [CommandLine](INFO): Join timeout helper Thread ID is 8114
2025-10-01T20:01:32.406Z,1759348892.406 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:01:32.407Z,1759348892.407 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:32.427Z,1759348892.427 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-10-01T20:01:32.427Z,1759348892.427 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:32.427Z,1759348892.427 [CommandExec](INFO): Join timeout helper Thread ID is 8115
2025-10-01T20:01:32.428Z,1759348892.428 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-10-01T20:01:32.428Z,1759348892.428 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:32.429Z,1759348892.429 [controlThread](INFO): Join timeout helper Thread ID is 8116
2025-10-01T20:01:32.670Z,1759348892.670 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:01:32.670Z,1759348892.670 [controlThread](DEBUG): Uninitializing ControlThread
2025-10-01T20:01:32.671Z,1759348892.671 [AHRS_M2](INFO): Powering down
2025-10-01T20:01:32.750Z,1759348892.750 [NAL9602](INFO): Powering down
2025-10-01T20:01:32.752Z,1759348892.752 [Sonardyne_Nano](INFO): Powering down
2025-10-01T20:01:32.954Z,1759348892.954 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-10-01T20:01:32.955Z,1759348892.955 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-10-01T20:01:32.956Z,1759348892.956 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-10-01T20:01:32.956Z,1759348892.956 [MissionManager](INFO): Uninitializing Mission Default
2025-10-01T20:01:32.956Z,1759348892.956 [Default] Stopped
2025-10-01T20:01:32.956Z,1759348892.956 [Default](DEBUG): Aggregate::uninitialize Default
2025-10-01T20:01:32.956Z,1759348892.956 [Default:A.Wait] Stopped
2025-10-01T20:01:32.956Z,1759348892.956 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-10-01T20:01:32.956Z,1759348892.956 [Default:B.GoToSurface] Stopped
2025-10-01T20:01:32.957Z,1759348892.957 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-10-01T20:01:32.960Z,1759348892.960 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-10-01T20:01:32.960Z,1759348892.960 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-10-01T20:01:32.960Z,1759348892.960 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-10-01T20:01:32.961Z,1759348892.961 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-10-01T20:01:32.961Z,1759348892.961 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-10-01T20:01:32.961Z,1759348892.961 [BuoyancyServo](INFO): Powering down
2025-10-01T20:01:33.094Z,1759348893.094 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-10-01T20:01:33.094Z,1759348893.094 [ElevatorServo](INFO): Powering down
2025-10-01T20:01:33.095Z,1759348893.095 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-10-01T20:01:33.095Z,1759348893.095 [MassServo](INFO): Powering down
2025-10-01T20:01:33.096Z,1759348893.096 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-10-01T20:01:33.096Z,1759348893.096 [RudderServo](INFO): Powering down
2025-10-01T20:01:33.097Z,1759348893.097 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-10-01T20:01:33.097Z,1759348893.097 [ThrusterHE](INFO): Powering down
2025-10-01T20:01:33.098Z,1759348893.098 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-10-01T20:01:33.099Z,1759348893.099 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-10-01T20:01:33.099Z,1759348893.099 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-10-01T20:01:33.099Z,1759348893.099 [CBIT](DEBUG): Powering off loads.
2025-10-01T20:01:33.110Z,1759348893.110 [CBIT](DEBUG): Disabling WDT.
2025-10-01T20:01:33.122Z,1759348893.122 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-10-01T20:01:33.122Z,1759348893.122 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-10-01T20:01:33.123Z,1759348893.123 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:33.173Z,1759348893.173 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:33.176Z,1759348893.176 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:33.182Z,1759348893.182 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:33.189Z,1759348893.189 [DAT ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:33.263Z,1759348893.263 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:33.267Z,1759348893.267 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:33.293Z,1759348893.293 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-10-01T20:01:33.293Z,1759348893.293 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-10-01T20:01:33.363Z,1759348893.363 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:01:33.440Z,1759348893.440 [logger ThreadHandler](INFO): Thread cancelled.