2026-03-12T15:48:50.046Z,1773330530.046 [Supervisor](DEBUG): Initializing supervisor.
2026-03-12T15:48:50.050Z,1773330530.050 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2026-03-12T15:48:50.051Z,1773330530.051 [SyncHandler](INFO): Protected caller Thread ID is 9772
2026-03-12T15:48:50.051Z,1773330530.051 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2026-03-12T15:48:50.052Z,1773330530.052 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2026-03-12T15:48:50.053Z,1773330530.053 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9773
2026-03-12T15:48:50.057Z,1773330530.057 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2026-03-12T15:48:50.076Z,1773330530.076 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2026-03-12T15:48:50.077Z,1773330530.077 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2026-03-12T15:48:50.077Z,1773330530.077 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9774
2026-03-12T15:48:50.081Z,1773330530.081 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2026-03-12T15:48:50.082Z,1773330530.082 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2026-03-12T15:48:50.082Z,1773330530.082 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9775
2026-03-12T15:48:50.085Z,1773330530.085 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2026-03-12T15:48:50.085Z,1773330530.085 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2026-03-12T15:48:50.086Z,1773330530.086 [logger ThreadHandler](INFO): Protected caller Thread ID is 9776
2026-03-12T15:48:50.089Z,1773330530.089 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2026-03-12T15:48:50.090Z,1773330530.090 [Supervisor](INFO): Looking for Config files in directory: Config/
2026-03-12T15:48:50.092Z,1773330530.092 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2026-03-12T15:48:50.633Z,1773330530.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2026-03-12T15:48:50.634Z,1773330530.634 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2026-03-12T15:48:50.850Z,1773330530.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2026-03-12T15:48:50.851Z,1773330530.851 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2026-03-12T15:48:50.949Z,1773330530.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2026-03-12T15:48:50.951Z,1773330530.951 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2026-03-12T15:48:51.189Z,1773330531.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2026-03-12T15:48:51.190Z,1773330531.190 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2026-03-12T15:48:51.278Z,1773330531.278 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2026-03-12T15:48:51.382Z,1773330531.382 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2026-03-12T15:48:51.382Z,1773330531.382 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2026-03-12T15:48:51.738Z,1773330531.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2026-03-12T15:48:51.740Z,1773330531.740 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2026-03-12T15:48:51.882Z,1773330531.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2026-03-12T15:48:51.883Z,1773330531.883 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2026-03-12T15:48:52.450Z,1773330532.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2026-03-12T15:48:52.451Z,1773330532.451 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2026-03-12T15:48:53.013Z,1773330533.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2026-03-12T15:48:53.013Z,1773330533.013 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2026-03-12T15:48:53.695Z,1773330533.695 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2026-03-12T15:48:53.696Z,1773330533.696 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2026-03-12T15:48:53.990Z,1773330533.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2026-03-12T15:48:53.991Z,1773330533.991 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2026-03-12T15:48:54.109Z,1773330534.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2026-03-12T15:48:54.110Z,1773330534.110 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2026-03-12T15:48:54.946Z,1773330534.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2026-03-12T15:48:54.947Z,1773330534.947 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2026-03-12T15:48:55.294Z,1773330535.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2026-03-12T15:48:55.296Z,1773330535.296 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2026-03-12T15:48:55.543Z,1773330535.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2026-03-12T15:48:55.545Z,1773330535.545 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2026-03-12T15:48:55.547Z,1773330535.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2026-03-12T15:48:55.673Z,1773330535.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2026-03-12T15:48:55.766Z,1773330535.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2026-03-12T15:48:55.910Z,1773330535.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2026-03-12T15:48:56.004Z,1773330536.004 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2026-03-12T15:48:56.277Z,1773330536.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2026-03-12T15:48:56.279Z,1773330536.279 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2026-03-12T15:48:56.391Z,1773330536.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2026-03-12T15:48:56.507Z,1773330536.507 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2026-03-12T15:48:56.614Z,1773330536.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2026-03-12T15:48:56.775Z,1773330536.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2026-03-12T15:48:56.881Z,1773330536.881 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2026-03-12T15:48:56.976Z,1773330536.976 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2026-03-12T15:48:56.986Z,1773330536.986 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2026-03-12T15:48:56.997Z,1773330536.997 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2026-03-12T15:48:56.997Z,1773330536.997 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2026-03-12T15:48:57.136Z,1773330537.136 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2026-03-12T15:48:57.136Z,1773330537.136 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2026-03-12T15:48:57.192Z,1773330537.192 [VerticalControl](DEBUG): Construct VerticalControl.
2026-03-12T15:48:57.253Z,1773330537.253 [VerticalControl] Loaded
2026-03-12T15:48:57.253Z,1773330537.253 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2026-03-12T15:48:57.256Z,1773330537.256 [HorizontalControl](DEBUG): Construct HorizontalControl.
2026-03-12T15:48:57.303Z,1773330537.303 [HorizontalControl] Loaded
2026-03-12T15:48:57.303Z,1773330537.303 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2026-03-12T15:48:57.306Z,1773330537.306 [SpeedControl](DEBUG): Construct SpeedControl.
2026-03-12T15:48:57.309Z,1773330537.309 [SpeedControl] Loaded
2026-03-12T15:48:57.309Z,1773330537.309 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2026-03-12T15:48:57.312Z,1773330537.312 [LoopControl](DEBUG): Construct LoopControl.
2026-03-12T15:48:57.312Z,1773330537.312 [LoopControl] Loaded
2026-03-12T15:48:57.313Z,1773330537.313 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2026-03-12T15:48:57.313Z,1773330537.313 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2026-03-12T15:48:57.313Z,1773330537.313 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2026-03-12T15:48:57.475Z,1773330537.475 [BuoyancyServo] Loaded
2026-03-12T15:48:57.476Z,1773330537.476 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2026-03-12T15:48:57.544Z,1773330537.544 [ElevatorServo] Loaded
2026-03-12T15:48:57.544Z,1773330537.544 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2026-03-12T15:48:57.566Z,1773330537.566 [MassServo] Loaded
2026-03-12T15:48:57.566Z,1773330537.566 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2026-03-12T15:48:57.587Z,1773330537.587 [RudderServo] Loaded
2026-03-12T15:48:57.587Z,1773330537.587 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2026-03-12T15:48:57.665Z,1773330537.665 [ThrusterHE] Loaded
2026-03-12T15:48:57.665Z,1773330537.665 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2026-03-12T15:48:57.691Z,1773330537.691 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2026-03-12T15:48:57.692Z,1773330537.692 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2026-03-12T15:48:57.847Z,1773330537.847 [DepthRateCalculator] Loaded
2026-03-12T15:48:57.847Z,1773330537.847 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2026-03-12T15:48:57.860Z,1773330537.860 [PitchRateCalculator] Loaded
2026-03-12T15:48:57.860Z,1773330537.860 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2026-03-12T15:48:57.878Z,1773330537.878 [SpeedCalculator] Loaded
2026-03-12T15:48:57.878Z,1773330537.878 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2026-03-12T15:48:57.903Z,1773330537.903 [YawRateCalculator] Loaded
2026-03-12T15:48:57.903Z,1773330537.903 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2026-03-12T15:48:57.941Z,1773330537.941 [ElevatorOffsetCalculator] Loaded
2026-03-12T15:48:57.941Z,1773330537.941 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2026-03-12T15:48:57.942Z,1773330537.942 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2026-03-12T15:48:57.942Z,1773330537.942 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2026-03-12T15:48:58.216Z,1773330538.216 [DeadReckonUsingMultipleVelocitySources] Loaded
2026-03-12T15:48:58.217Z,1773330538.217 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2026-03-12T15:48:58.230Z,1773330538.230 [NavChart] Loaded
2026-03-12T15:48:58.230Z,1773330538.230 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2026-03-12T15:48:58.236Z,1773330538.236 [UniversalFixResidualReporter] Loaded
2026-03-12T15:48:58.236Z,1773330538.236 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2026-03-12T15:48:58.276Z,1773330538.276 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2026-03-12T15:48:58.276Z,1773330538.276 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2026-03-12T15:48:58.851Z,1773330538.851 [AHRS_M2] Loaded
2026-03-12T15:48:58.852Z,1773330538.852 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2026-03-12T15:48:58.890Z,1773330538.890 [BackseatComponent] Loaded
2026-03-12T15:48:58.891Z,1773330538.891 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2026-03-12T15:48:58.892Z,1773330538.892 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409454E0
2026-03-12T15:48:58.892Z,1773330538.892 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9858
2026-03-12T15:48:58.895Z,1773330538.895 [LcmPublisher] Loaded
2026-03-12T15:48:58.895Z,1773330538.895 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2026-03-12T15:48:58.902Z,1773330538.902 [BioacousticsDataBridge] Loaded
2026-03-12T15:48:58.902Z,1773330538.902 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2026-03-12T15:48:59.788Z,1773330539.788 [BPC1] Loaded
2026-03-12T15:48:59.788Z,1773330539.788 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2026-03-12T15:48:59.878Z,1773330539.878 [DATMMP] Loaded
2026-03-12T15:48:59.879Z,1773330539.879 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2026-03-12T15:48:59.880Z,1773330539.880 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409764E0
2026-03-12T15:48:59.880Z,1773330539.880 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 9859
2026-03-12T15:48:59.951Z,1773330539.951 [DataOverHttps] Loaded
2026-03-12T15:48:59.952Z,1773330539.952 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2026-03-12T15:48:59.953Z,1773330539.953 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409A64E0
2026-03-12T15:48:59.953Z,1773330539.953 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9860
2026-03-12T15:48:59.976Z,1773330539.976 [Depth_Keller] Loaded
2026-03-12T15:48:59.976Z,1773330539.976 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2026-03-12T15:48:59.981Z,1773330539.981 [DropWeight] Loaded
2026-03-12T15:48:59.982Z,1773330539.982 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2026-03-12T15:49:00.046Z,1773330540.046 [NAL9602] Loaded
2026-03-12T15:49:00.046Z,1773330540.046 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2026-03-12T15:49:00.076Z,1773330540.076 [Onboard] Loaded
2026-03-12T15:49:00.076Z,1773330540.076 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2026-03-12T15:49:00.077Z,1773330540.077 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409D64E0
2026-03-12T15:49:00.077Z,1773330540.077 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9861
2026-03-12T15:49:00.092Z,1773330540.092 [Power24vConverter] Loaded
2026-03-12T15:49:00.092Z,1773330540.092 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2026-03-12T15:49:00.109Z,1773330540.109 [Radio_Surface] Loaded
2026-03-12T15:49:00.109Z,1773330540.109 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2026-03-12T15:49:00.110Z,1773330540.110 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A064E0
2026-03-12T15:49:00.111Z,1773330540.111 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9862
2026-03-12T15:49:00.124Z,1773330540.124 [Sonardyne_Nano] Loaded
2026-03-12T15:49:00.124Z,1773330540.124 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2026-03-12T15:49:00.148Z,1773330540.148 [Waterlinked] Loaded
2026-03-12T15:49:00.148Z,1773330540.148 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2026-03-12T15:49:00.149Z,1773330540.149 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2026-03-12T15:49:00.149Z,1773330540.149 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2026-03-12T15:49:00.226Z,1773330540.226 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2026-03-12T15:49:00.226Z,1773330540.226 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2026-03-12T15:49:00.410Z,1773330540.410 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2026-03-12T15:49:00.411Z,1773330540.411 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2026-03-12T15:49:00.441Z,1773330540.441 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2026-03-12T15:49:00.441Z,1773330540.441 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2026-03-12T15:49:00.616Z,1773330540.616 [CTD_Seabird] Loaded
2026-03-12T15:49:00.617Z,1773330540.617 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2026-03-12T15:49:00.618Z,1773330540.618 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B524E0
2026-03-12T15:49:00.618Z,1773330540.618 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9863
2026-03-12T15:49:00.654Z,1773330540.654 [ESPComponent] Loaded
2026-03-12T15:49:00.654Z,1773330540.654 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2026-03-12T15:49:00.676Z,1773330540.676 [PAR_Licor] Loaded
2026-03-12T15:49:00.677Z,1773330540.677 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2026-03-12T15:49:00.722Z,1773330540.722 [WetLabsBB2FL] Loaded
2026-03-12T15:49:00.723Z,1773330540.723 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2026-03-12T15:49:00.724Z,1773330540.724 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B824E0
2026-03-12T15:49:00.724Z,1773330540.724 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9864
2026-03-12T15:49:00.725Z,1773330540.725 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2026-03-12T15:49:00.725Z,1773330540.725 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2026-03-12T15:49:01.081Z,1773330541.081 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2026-03-12T15:49:01.081Z,1773330541.081 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2026-03-12T15:49:01.248Z,1773330541.248 [SBIT](DEBUG): Construct Startup Built In Test.
2026-03-12T15:49:01.258Z,1773330541.258 [SBIT] Loaded
2026-03-12T15:49:01.258Z,1773330541.258 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2026-03-12T15:49:01.261Z,1773330541.261 [IBIT](DEBUG): Construct Initiated Built In Test.
2026-03-12T15:49:01.274Z,1773330541.274 [IBIT] Loaded
2026-03-12T15:49:01.274Z,1773330541.274 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2026-03-12T15:49:01.279Z,1773330541.279 [CBIT](DEBUG): Construct Continuous Built In Test.
2026-03-12T15:49:01.360Z,1773330541.360 [CBIT] Loaded
2026-03-12T15:49:01.361Z,1773330541.361 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2026-03-12T15:49:01.393Z,1773330541.393 [GFScanner] Loaded
2026-03-12T15:49:01.393Z,1773330541.393 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2026-03-12T15:49:01.394Z,1773330541.394 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2026-03-12T15:49:01.400Z,1773330541.400 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2026-03-12T15:49:01.403Z,1773330541.403 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2026-03-12T15:49:01.414Z,1773330541.414 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2026-03-12T15:49:01.415Z,1773330541.415 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C8B4E0
2026-03-12T15:49:01.415Z,1773330541.415 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9865
2026-03-12T15:49:01.419Z,1773330541.419 [Supervisor](INFO): Main Thread ID is 829
2026-03-12T15:49:01.420Z,1773330541.420 [Supervisor](DEBUG): Running supervisor.
2026-03-12T15:49:01.420Z,1773330541.420 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9866
2026-03-12T15:49:01.421Z,1773330541.421 [CommandExec](INFO): Initializing the command executive.
2026-03-12T15:49:01.422Z,1773330541.422 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9867
2026-03-12T15:49:01.425Z,1773330541.425 [controlThread ThreadHandler](INFO): Handler Thread ID is 9868
2026-03-12T15:49:01.425Z,1773330541.425 [controlThread](DEBUG): Initializing ControlThread
2026-03-12T15:49:01.426Z,1773330541.426 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2026-03-12T15:49:01.428Z,1773330541.428 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2026-03-12T15:49:01.428Z,1773330541.428 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2026-03-12T15:49:01.429Z,1773330541.429 [LoopControl](DEBUG): Initialize LoopControlComponent.
2026-03-12T15:49:01.430Z,1773330541.430 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2026-03-12T15:49:01.430Z,1773330541.430 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2026-03-12T15:49:01.431Z,1773330541.431 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2026-03-12T15:49:01.431Z,1773330541.431 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2026-03-12T15:49:01.431Z,1773330541.431 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2026-03-12T15:49:01.433Z,1773330541.433 [NavChart](DEBUG): Initialize NavChart Navigation.
2026-03-12T15:49:01.433Z,1773330541.433 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2026-03-12T15:49:01.440Z,1773330541.440 [SBIT](INFO): Initialize SBIT Component.
2026-03-12T15:49:01.440Z,1773330541.440 [SBIT](IMPORTANT): git: 2026-03-03
2026-03-12T15:49:01.440Z,1773330541.440 [SBIT](INFO): git hash: f7a2e94ac3e2178d8a146c43cd471ee86a10fe19
2026-03-12T15:49:01.441Z,1773330541.441 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2026-03-12T15:49:01.442Z,1773330541.442 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2026-03-12T15:49:01.443Z,1773330541.443 [SBIT](INFO): Beginning SBIT in 29.000000 seconds.
2026-03-12T15:49:01.443Z,1773330541.443 [IBIT](INFO): Initialize IBIT Component.
2026-03-12T15:49:01.444Z,1773330541.444 [CBIT](DEBUG): Initialize CBIT Component.
2026-03-12T15:49:01.445Z,1773330541.445 [logger ThreadHandler](INFO): Handler Thread ID is 9869
2026-03-12T15:49:01.456Z,1773330541.456 [CBIT](DEBUG): Initialized mux pins.
2026-03-12T15:49:01.456Z,1773330541.456 [CBIT](DEBUG): Initializing the watchdog timer.
2026-03-12T15:49:01.464Z,1773330541.464 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9870
2026-03-12T15:49:01.476Z,1773330541.476 [DATMMP ThreadHandler](INFO): Handler Thread ID is 9871
2026-03-12T15:49:01.477Z,1773330541.477 [DATMMP](INFO): Start
2026-03-12T15:49:01.477Z,1773330541.477 [DATMMP](INFO): Powering up
2026-03-12T15:49:01.477Z,1773330541.477 [DATMMP](DEBUG): Initializing DATMMP.
2026-03-12T15:49:01.480Z,1773330541.480 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2026-03-12T15:49:01.480Z,1773330541.480 [CBIT](DEBUG): Initializing heartbeat.
2026-03-12T15:49:01.482Z,1773330541.482 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9873
2026-03-12T15:49:01.483Z,1773330541.483 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2026-03-12T15:49:01.492Z,1773330541.492 [Onboard ThreadHandler](INFO): Handler Thread ID is 9874
2026-03-12T15:49:01.522Z,1773330541.522 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9875
2026-03-12T15:49:01.536Z,1773330541.536 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9876
2026-03-12T15:49:01.536Z,1773330541.536 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-12T15:49:01.541Z,1773330541.541 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9878
2026-03-12T15:49:01.544Z,1773330541.544 [WetLabsBB2FL](INFO): Powering up
2026-03-12T15:49:01.545Z,1773330541.545 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9880
2026-03-12T15:49:01.552Z,1773330541.552 [CBIT](DEBUG): Deactivating emergency mode.
2026-03-12T15:49:01.554Z,1773330541.554 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2026-03-12T15:49:01.555Z,1773330541.555 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2026-03-12T15:49:01.555Z,1773330541.555 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000
2026-03-12T15:49:01.555Z,1773330541.555 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000
2026-03-12T15:49:01.555Z,1773330541.555 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000
2026-03-12T15:49:01.555Z,1773330541.555 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000
2026-03-12T15:49:01.555Z,1773330541.555 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000
2026-03-12T15:49:01.556Z,1773330541.556 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000
2026-03-12T15:49:01.556Z,1773330541.556 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000
2026-03-12T15:49:01.556Z,1773330541.556 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000
2026-03-12T15:49:01.556Z,1773330541.556 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000
2026-03-12T15:49:01.556Z,1773330541.556 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000
2026-03-12T15:49:01.556Z,1773330541.556 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000
2026-03-12T15:49:01.557Z,1773330541.557 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000
2026-03-12T15:49:01.557Z,1773330541.557 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000
2026-03-12T15:49:01.557Z,1773330541.557 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000
2026-03-12T15:49:01.557Z,1773330541.557 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000
2026-03-12T15:49:01.557Z,1773330541.557 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000
2026-03-12T15:49:01.557Z,1773330541.557 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000
2026-03-12T15:49:01.558Z,1773330541.558 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000
2026-03-12T15:49:01.591Z,1773330541.591 [CBIT](DEBUG): Backplane powered.
2026-03-12T15:49:01.592Z,1773330541.592 [GFScanner](DEBUG): Initializing GFScanner
2026-03-12T15:49:01.592Z,1773330541.592 [GFScanner](DEBUG): Deactivating GF circuits.
2026-03-12T15:49:01.597Z,1773330541.597 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2026-03-12T15:49:01.597Z,1773330541.597 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2026-03-12T15:49:01.598Z,1773330541.598 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2026-03-12T15:49:01.601Z,1773330541.601 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2026-03-12T15:49:01.656Z,1773330541.656 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2026-03-12T15:49:01.669Z,1773330541.669 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-12T15:49:01.718Z,1773330541.718 [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
}
}
}
2026-03-12T15:49:01.719Z,1773330541.719 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2026-03-12T15:49:01.719Z,1773330541.719 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2026-03-12T15:49:01.728Z,1773330541.728 [DATMMP](INFO): Starting
2026-03-12T15:49:01.734Z,1773330541.734 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2026-03-12T15:49:01.924Z,1773330541.924 [Radio_Surface](INFO): Powering up
2026-03-12T15:49:01.980Z,1773330541.980 [DATMMP](INFO): Starting
2026-03-12T15:49:02.017Z,1773330542.017 [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
2026-03-12T15:49:02.039Z,1773330542.039 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2026-03-12T15:49:02.040Z,1773330542.040 [Default:A.Wait](DEBUG): Construct Wait.
2026-03-12T15:49:02.042Z,1773330542.042 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-12T15:49:02.100Z,1773330542.100 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2026-03-12T15:49:02.103Z,1773330542.103 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2026-03-12T15:49:02.125Z,1773330542.125 [Default:E.Execute](DEBUG): Construct Execute.
2026-03-12T15:49:02.136Z,1773330542.136 [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"
}
}
2026-03-12T15:49:02.141Z,1773330542.141 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter,
2026-03-12T15:49:02.160Z,1773330542.160 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2026-03-12T15:49:02.232Z,1773330542.232 [DATMMP](INFO): Starting
2026-03-12T15:49:02.233Z,1773330542.233 [Depth_Keller](INFO): Initializing.
2026-03-12T15:49:02.234Z,1773330542.234 [Power24vConverter](INFO): Powering up.
2026-03-12T15:49:02.235Z,1773330542.235 [Sonardyne_Nano](INFO): Initializing.
2026-03-12T15:49:02.268Z,1773330542.268 [DepthRateCalculator](ERROR): Depth measurement is not active
2026-03-12T15:49:02.299Z,1773330542.299 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2026-03-12T15:49:02.308Z,1773330542.308 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2026-03-12T15:49:02.308Z,1773330542.308 [ElevatorServo](DEBUG): Initializing EZServoServo.
2026-03-12T15:49:02.316Z,1773330542.316 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2026-03-12T15:49:02.317Z,1773330542.317 [MassServo](DEBUG): Initializing EZServoServo.
2026-03-12T15:49:02.324Z,1773330542.324 [MassServo](DEBUG): Initializing MassServo.
2026-03-12T15:49:02.325Z,1773330542.325 [RudderServo](DEBUG): Initializing EZServoServo.
2026-03-12T15:49:02.332Z,1773330542.332 [RudderServo](DEBUG): Initializing RudderServo.
2026-03-12T15:49:02.333Z,1773330542.333 [ThrusterHE](DEBUG): Initializing EZServoServo.
2026-03-12T15:49:02.340Z,1773330542.340 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2026-03-12T15:49:02.485Z,1773330542.485 [DATMMP](INFO): Starting
2026-03-12T15:49:02.736Z,1773330542.736 [DATMMP](INFO): Starting
2026-03-12T15:49:02.988Z,1773330542.988 [DATMMP](INFO): Starting
2026-03-12T15:49:03.240Z,1773330543.240 [DATMMP](INFO): Starting
2026-03-12T15:49:03.492Z,1773330543.492 [DATMMP](INFO): Starting
2026-03-12T15:49:03.744Z,1773330543.744 [DATMMP](INFO): Starting
2026-03-12T15:49:03.996Z,1773330543.996 [DATMMP](INFO): Starting
2026-03-12T15:49:04.156Z,1773330544.156 [WetLabsBB2FL](INFO): Powering down
2026-03-12T15:49:04.170Z,1773330544.170 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2026-03-12T15:49:04.248Z,1773330544.248 [DATMMP](INFO): Starting
2026-03-12T15:49:04.500Z,1773330544.500 [DATMMP](INFO): Starting
2026-03-12T15:49:04.752Z,1773330544.752 [DATMMP](INFO): Starting
2026-03-12T15:49:05.004Z,1773330545.004 [DATMMP](INFO): Starting
2026-03-12T15:49:05.256Z,1773330545.256 [DATMMP](INFO): Starting
2026-03-12T15:49:05.468Z,1773330545.468 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2026-03-12T15:49:05.508Z,1773330545.508 [DATMMP](INFO): Starting
2026-03-12T15:49:05.760Z,1773330545.760 [DATMMP](INFO): Starting
2026-03-12T15:49:06.012Z,1773330546.012 [DATMMP](INFO): Starting
2026-03-12T15:49:06.264Z,1773330546.264 [DATMMP](INFO): Starting
2026-03-12T15:49:06.517Z,1773330546.517 [DATMMP](INFO): Starting
2026-03-12T15:49:06.768Z,1773330546.768 [DATMMP](INFO): Starting
2026-03-12T15:49:07.020Z,1773330547.020 [DATMMP](INFO): Starting
2026-03-12T15:49:07.272Z,1773330547.272 [DATMMP](INFO): Starting
2026-03-12T15:49:07.524Z,1773330547.524 [DATMMP](INFO): Starting
2026-03-12T15:49:07.776Z,1773330547.776 [DATMMP](INFO): Starting
2026-03-12T15:49:08.028Z,1773330548.028 [DATMMP](INFO): Starting
2026-03-12T15:49:08.033Z,1773330548.033 [ThrusterHE](ERROR): Zero Speed Commanded.
2026-03-12T15:49:08.280Z,1773330548.280 [DATMMP](INFO): Starting
2026-03-12T15:49:08.532Z,1773330548.532 [DATMMP](INFO): Starting
2026-03-12T15:49:08.784Z,1773330548.784 [DATMMP](INFO): Starting
2026-03-12T15:49:09.036Z,1773330549.036 [DATMMP](INFO): Starting
2026-03-12T15:49:09.288Z,1773330549.288 [DATMMP](INFO): Starting
2026-03-12T15:49:09.540Z,1773330549.540 [DATMMP](INFO): Starting
2026-03-12T15:49:09.792Z,1773330549.792 [DATMMP](INFO): Starting
2026-03-12T15:49:10.045Z,1773330550.045 [DATMMP](INFO): Starting
2026-03-12T15:49:10.296Z,1773330550.296 [DATMMP](INFO): Starting
2026-03-12T15:49:10.548Z,1773330550.548 [DATMMP](INFO): Starting
2026-03-12T15:49:10.800Z,1773330550.800 [DATMMP](INFO): Starting
2026-03-12T15:49:11.052Z,1773330551.052 [DATMMP](INFO): Starting
2026-03-12T15:49:11.304Z,1773330551.304 [DATMMP](INFO): Starting
2026-03-12T15:49:11.556Z,1773330551.556 [DATMMP](INFO): Starting
2026-03-12T15:49:11.808Z,1773330551.808 [DATMMP](INFO): Starting
2026-03-12T15:49:12.060Z,1773330552.060 [DATMMP](INFO): Starting
2026-03-12T15:49:12.312Z,1773330552.312 [DATMMP](INFO): Starting
2026-03-12T15:49:12.564Z,1773330552.564 [DATMMP](INFO): Starting
2026-03-12T15:49:12.816Z,1773330552.816 [DATMMP](INFO): Starting
2026-03-12T15:49:13.068Z,1773330553.068 [DATMMP](INFO): Starting
2026-03-12T15:49:13.320Z,1773330553.320 [DATMMP](INFO): Starting
2026-03-12T15:49:13.320Z,1773330553.320 [DATMMP](INFO): DAT read:
2026-03-12T15:49:13.321Z,1773330553.321 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2026-03-12T15:49:13.572Z,1773330553.572 [DATMMP](INFO): Starting
2026-03-12T15:49:13.824Z,1773330553.824 [DATMMP](INFO): Starting
2026-03-12T15:49:14.076Z,1773330554.076 [DATMMP](INFO): Starting
2026-03-12T15:49:14.328Z,1773330554.328 [DATMMP](INFO): Starting
2026-03-12T15:49:14.580Z,1773330554.580 [DATMMP](INFO): Starting
2026-03-12T15:49:14.832Z,1773330554.832 [DATMMP](INFO): Starting
2026-03-12T15:49:15.084Z,1773330555.084 [DATMMP](INFO): Starting
2026-03-12T15:49:15.085Z,1773330555.085 [DATMMP](INFO): DAT read: MF Frequency Band 5120 bandwidth
2026-03-12T15:49:15.086Z,1773330555.086 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.19.1
2026-03-12T15:49:15.086Z,1773330555.086 [DATMMP](INFO): DAT read: Mar 12 2026 15:49:09
2026-03-12T15:49:15.336Z,1773330555.336 [DATMMP](INFO): Starting
2026-03-12T15:49:15.588Z,1773330555.588 [DATMMP](INFO): Starting
2026-03-12T15:49:15.840Z,1773330555.840 [DATMMP](INFO): Starting
2026-03-12T15:49:15.840Z,1773330555.840 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2026-03-12T15:49:15.842Z,1773330555.842 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2026-03-12T15:49:15.842Z,1773330555.842 [DATMMP](INFO): commRate: 800
2026-03-12T15:49:16.092Z,1773330556.092 [DATMMP](INFO): Starting
2026-03-12T15:49:16.344Z,1773330556.344 [DATMMP](INFO): Starting
2026-03-12T15:49:16.596Z,1773330556.596 [DATMMP](INFO): Starting
2026-03-12T15:49:16.848Z,1773330556.848 [DATMMP](INFO): Starting
2026-03-12T15:49:17.100Z,1773330557.100 [DATMMP](INFO): Starting
2026-03-12T15:49:17.352Z,1773330557.352 [DATMMP](INFO): Starting
2026-03-12T15:49:17.604Z,1773330557.604 [DATMMP](INFO): Starting
2026-03-12T15:49:17.856Z,1773330557.856 [DATMMP](INFO): Starting
2026-03-12T15:49:17.908Z,1773330557.908 [DATMMP](INFO): entering command mode
2026-03-12T15:49:18.108Z,1773330558.108 [DATMMP](INFO): Starting
2026-03-12T15:49:18.108Z,1773330558.108 [DATMMP](INFO): DAT read:
2026-03-12T15:49:18.108Z,1773330558.108 [DATMMP](INFO): DAT read: user:1>
2026-03-12T15:49:18.109Z,1773330558.109 [DATMMP](DEBUG): read user prompt 1: user:1>
2026-03-12T15:49:18.109Z,1773330558.109 [DATMMP](INFO): entering MMP mode
2026-03-12T15:49:18.360Z,1773330558.360 [DATMMP](INFO): Starting
2026-03-12T15:49:18.360Z,1773330558.360 [DATMMP](INFO): GSXN notify for xid: 0
2026-03-12T15:49:18.362Z,1773330558.362 [DATMMP](INFO): status rx: x1
2026-03-12T15:49:18.362Z,1773330558.362 [DATMMP](INFO): MMP status: 1: started
2026-03-12T15:49:18.363Z,1773330558.363 [DATMMP](INFO): Received message type: status
2026-03-12T15:49:18.363Z,1773330558.363 [DATMMP](INFO): Handled
2026-03-12T15:49:18.612Z,1773330558.612 [DATMMP](INFO): Starting
2026-03-12T15:49:18.613Z,1773330558.613 [DATMMP](INFO): Sent Tx Power Config 8
2026-03-12T15:49:18.613Z,1773330558.613 [DATMMP](INFO): Setting local address: 12
2026-03-12T15:49:18.616Z,1773330558.616 [DATMMP](INFO): Setting time to: 15:49:18 And date to:3/12/2026
2026-03-12T15:49:18.864Z,1773330558.864 [DATMMP](INFO): Starting
2026-03-12T15:49:18.864Z,1773330558.864 [DATMMP](INFO): GSXN notify for xid: 0
2026-03-12T15:49:18.865Z,1773330558.865 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2026-03-12T15:49:18.865Z,1773330558.865 [DATMMP](INFO): Received message type: mdmpower
2026-03-12T15:49:18.865Z,1773330558.865 [DATMMP](INFO): Handled
2026-03-12T15:49:19.116Z,1773330559.116 [DATMMP](INFO): Starting
2026-03-12T15:49:19.116Z,1773330559.116 [DATMMP](INFO): GSXN notify for xid: 10
2026-03-12T15:49:19.117Z,1773330559.117 [DATMMP](INFO): sreg_txpower rx: x8
2026-03-12T15:49:19.117Z,1773330559.117 [DATMMP](INFO): Received message type: sreg_txpower
2026-03-12T15:49:19.117Z,1773330559.117 [DATMMP](INFO): Match for : sreg_txpower
2026-03-12T15:49:19.117Z,1773330559.117 [DATMMP](INFO): ACK
2026-03-12T15:49:19.368Z,1773330559.368 [DATMMP](INFO): Starting
2026-03-12T15:49:19.368Z,1773330559.368 [DATMMP](INFO): GSXN notify for xid: 0
2026-03-12T15:49:19.369Z,1773330559.369 [DATMMP](INFO): mdmlocal rx: xC x0 x0 x0
2026-03-12T15:49:19.369Z,1773330559.369 [DATMMP](INFO): Received message type: mdmlocal
2026-03-12T15:49:19.369Z,1773330559.369 [DATMMP](INFO): Handled
2026-03-12T15:49:19.620Z,1773330559.620 [DATMMP](INFO): Starting
2026-03-12T15:49:19.620Z,1773330559.620 [DATMMP](INFO): GSXN notify for xid: 11
2026-03-12T15:49:19.621Z,1773330559.621 [DATMMP](INFO): sreg_locaddr rx: xC
2026-03-12T15:49:19.621Z,1773330559.621 [DATMMP](INFO): Received message type: sreg_locaddr
2026-03-12T15:49:19.621Z,1773330559.621 [DATMMP](INFO): Match for : sreg_locaddr
2026-03-12T15:49:19.621Z,1773330559.621 [DATMMP](INFO): ACK
2026-03-12T15:49:19.872Z,1773330559.872 [DATMMP](INFO): Starting
2026-03-12T15:49:19.872Z,1773330559.872 [DATMMP](INFO): GSXN notify for xid: 12
2026-03-12T15:49:19.873Z,1773330559.873 [DATMMP](INFO): timedate rx: x0 xF x31 x12 x2 xC x7E x0
2026-03-12T15:49:19.873Z,1773330559.873 [DATMMP](INFO): Received message type: timedate
2026-03-12T15:49:19.874Z,1773330559.874 [DATMMP](INFO): Match for : timedate
2026-03-12T15:49:19.874Z,1773330559.874 [DATMMP](INFO): ACK
2026-03-12T15:49:20.124Z,1773330560.124 [DATMMP](INFO): Starting
2026-03-12T15:49:20.124Z,1773330560.124 [DATMMP](INFO): GSXN notify for xid: 0
2026-03-12T15:49:20.125Z,1773330560.125 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2026-03-12T15:49:20.125Z,1773330560.125 [DATMMP](INFO): Received message type: sys_verb
2026-03-12T15:49:20.125Z,1773330560.125 [DATMMP](INFO): Handled
2026-03-12T15:49:20.390Z,1773330560.390 [DATMMP](INFO): Starting
2026-03-12T15:49:20.390Z,1773330560.390 [DATMMP](INFO): GSXN notify for xid: 13
2026-03-12T15:49:20.391Z,1773330560.391 [DATMMP](INFO): sreg_verbosity rx: x0
2026-03-12T15:49:20.391Z,1773330560.391 [DATMMP](INFO): Received message type: sreg_verbosity
2026-03-12T15:49:20.391Z,1773330560.391 [DATMMP](INFO): Match for : sreg_verbosity
2026-03-12T15:49:20.391Z,1773330560.391 [DATMMP](INFO): ACK
2026-03-12T15:49:20.644Z,1773330560.644 [DATMMP](INFO): Starting
2026-03-12T15:49:20.644Z,1773330560.644 [DATMMP](INFO): GSXN notify for xid: 14
2026-03-12T15:49:20.645Z,1773330560.645 [DATMMP](INFO): version rx: x2 x5
2026-03-12T15:49:20.645Z,1773330560.645 [DATMMP](INFO): DatMMPVersion: 2.5
2026-03-12T15:49:20.645Z,1773330560.645 [DATMMP](INFO): Received message type: version
2026-03-12T15:49:20.646Z,1773330560.646 [DATMMP](INFO): Match for : version
2026-03-12T15:49:20.646Z,1773330560.646 [DATMMP](INFO): ACK
2026-03-12T15:49:20.646Z,1773330560.646 [DATMMP](INFO): in MMP mode: config complete going to runnable
2026-03-12T15:49:30.438Z,1773330570.438 [NAL9602](INFO): Powering up NAL9602
2026-03-12T15:49:31.013Z,1773330571.013 [SBIT](IMPORTANT): Beginning Startup BIT
2026-03-12T15:49:31.037Z,1773330571.037 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-12T15:49:35.020Z,1773330575.020 [DATMMP](INFO): GSXN notify for xid: 0
2026-03-12T15:49:35.021Z,1773330575.021 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2026-03-12T15:49:35.022Z,1773330575.022 [DATMMP](INFO): Received message type: timesync
2026-03-12T15:49:35.022Z,1773330575.022 [DATMMP](INFO): Handled
2026-03-12T15:49:35.540Z,1773330575.540 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.043807
CHAN A1 (24V): 0.156017
CHAN A2 (12V): 0.031680
CHAN A3 (5V): 0.012187
CHAN B0 (3.3V): 0.008950
CHAN B1 (3.15aV): -0.048948
CHAN B2 (3.15bV): 0.009524
CHAN B3 (GND): 0.000179
OPEN: 0.001942
Full Scale: +/- 1 mA
2026-03-12T15:49:39.584Z,1773330579.584 [Waterlinked](INFO): Powering down
2026-03-12T15:49:41.489Z,1773330581.489 [NAL9602](INFO): NAL9602 initialized
2026-03-12T15:49:45.100Z,1773330585.100 [DATMMP](INFO): GSXN notify for xid: 0
2026-03-12T15:49:45.101Z,1773330585.101 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2026-03-12T15:49:45.101Z,1773330585.101 [DATMMP](INFO): Received message type: timesync
2026-03-12T15:49:45.101Z,1773330585.101 [DATMMP](INFO): Handled
2026-03-12T15:50:25.199Z,1773330625.199 [SBIT](IMPORTANT): SBIT PASSED
2026-03-12T15:50:25.200Z,1773330625.200 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2026-03-12T15:50:25.200Z,1773330625.200 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2026-03-12T15:50:25.200Z,1773330625.200 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2026-03-12T15:50:25.200Z,1773330625.200 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2026-03-12T15:50:25.201Z,1773330625.201 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2026-03-12T15:50:25.201Z,1773330625.201 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2026-03-12T15:50:25.201Z,1773330625.201 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter;
2026-03-12T15:50:25.201Z,1773330625.201 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 15.000000 meter;
2026-03-12T15:50:25.201Z,1773330625.201 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=178.758892 cubic_centimeter;
2026-03-12T15:50:25.201Z,1773330625.201 [SBIT](IMPORTANT): VerticalControl.massDefault=-2.424096 millimeter;
2026-03-12T15:50:25.587Z,1773330625.587 [NAL9602](INFO): SBD MO Status=2, MOMSN=13179, MT Status=2, MTMSN=0
2026-03-12T15:50:25.587Z,1773330625.587 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T15:50:25.602Z,1773330625.602 [MissionManager](IMPORTANT): Started mission Startup
2026-03-12T15:50:25.602Z,1773330625.602 [Startup] Running Loop=1
2026-03-12T15:50:25.602Z,1773330625.602 [Startup](DEBUG): Aggregate::initialize Startup
2026-03-12T15:50:25.602Z,1773330625.602 [Startup:A.GoToSurface] Running Loop=1
2026-03-12T15:50:25.602Z,1773330625.602 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T15:50:25.603Z,1773330625.603 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-03-12T15:50:25.603Z,1773330625.603 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-03-12T15:50:25.604Z,1773330625.604 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-03-12T15:50:25.604Z,1773330625.604 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-03-12T15:50:25.604Z,1773330625.604 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T15:50:25.605Z,1773330625.605 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T15:50:25.606Z,1773330625.606 [Startup:StartupSatComms] Running Loop=1
2026-03-12T15:50:25.606Z,1773330625.606 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2026-03-12T15:50:25.606Z,1773330625.606 [Startup:StartupSatComms:A] Running Loop=1
2026-03-12T15:50:26.025Z,1773330626.025 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2026-03-12T15:50:48.219Z,1773330648.219 [NAL9602](INFO): SBD MO Status=0, MOMSN=13179, MT Status=0, MTMSN=0
2026-03-12T15:50:48.219Z,1773330648.219 [NAL9602](INFO): No messages in MT queue
2026-03-12T15:50:49.436Z,1773330649.436 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155043.00,A,3647.78590,N,12150.91282,W,0.836,312.88,120326,,,A*71
2026-03-12T15:50:49.440Z,1773330649.440 [NAL9602](INFO): GPS fix at 20260312T155043: (36.796432, -121.848547)
2026-03-12T15:50:49.452Z,1773330649.452 [Startup:StartupSatComms:A] Stopped
2026-03-12T15:50:49.452Z,1773330649.452 [Startup:StartupSatComms:B] Running Loop=1
2026-03-12T15:50:49.950Z,1773330649.950 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-03-12T15:51:06.463Z,1773330666.463 [NAL9602](INFO): SBD MO Status=2, MOMSN=13180, MT Status=2, MTMSN=0
2026-03-12T15:51:06.463Z,1773330666.463 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T15:51:31.574Z,1773330691.574 [NAL9602](INFO): SBD MO Status=2, MOMSN=13180, MT Status=2, MTMSN=0
2026-03-12T15:51:31.574Z,1773330691.574 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T15:51:49.789Z,1773330709.789 [Startup:StartupSatComms:B](INFO): Timed out from 2026-03-12T15:50:49.5Z
2026-03-12T15:51:49.789Z,1773330709.789 [Startup:StartupSatComms:B] Stopped
2026-03-12T15:51:49.789Z,1773330709.789 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2026-03-12T15:51:49.789Z,1773330709.789 [Startup:StartupSatComms] Stopped
2026-03-12T15:51:49.789Z,1773330709.789 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2026-03-12T15:51:49.790Z,1773330709.790 [Startup](INFO): Completed Startup
2026-03-12T15:51:49.790Z,1773330709.790 [MissionManager](INFO): Startup is completed.
2026-03-12T15:51:49.790Z,1773330709.790 [MissionManager](INFO): Uninitializing Mission Startup
2026-03-12T15:51:49.790Z,1773330709.790 [Startup] Stopped
2026-03-12T15:51:49.790Z,1773330709.790 [Startup](DEBUG): Aggregate::uninitialize Startup
2026-03-12T15:51:49.790Z,1773330709.790 [Startup:A.GoToSurface] Stopped
2026-03-12T15:51:49.790Z,1773330709.790 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T15:51:50.203Z,1773330710.203 [MissionManager](IMPORTANT): Started mission Default
2026-03-12T15:51:50.204Z,1773330710.204 [Default] Running Loop=1
2026-03-12T15:51:50.204Z,1773330710.204 [Default](DEBUG): Aggregate::initialize Default
2026-03-12T15:51:50.204Z,1773330710.204 [Default:B.GoToSurface] Running Loop=1
2026-03-12T15:51:50.204Z,1773330710.204 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T15:51:50.204Z,1773330710.204 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-03-12T15:51:50.204Z,1773330710.204 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-03-12T15:51:50.205Z,1773330710.205 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-03-12T15:51:50.205Z,1773330710.205 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-03-12T15:51:50.205Z,1773330710.205 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T15:51:50.206Z,1773330710.206 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T15:51:50.206Z,1773330710.206 [Default:A.Wait] Running Loop=1
2026-03-12T15:51:50.206Z,1773330710.206 [Default:A.Wait](DEBUG): Initialize Wait Component.
2026-03-12T15:51:57.474Z,1773330717.474 [NAL9602](INFO): SBD MO Status=2, MOMSN=13180, MT Status=2, MTMSN=0
2026-03-12T15:51:57.474Z,1773330717.474 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T15:52:03.528Z,1773330723.528 [Default:A.Wait](INFO): Done Waiting.
2026-03-12T15:52:03.528Z,1773330723.528 [Default:A.Wait] Stopped
2026-03-12T15:52:03.529Z,1773330723.529 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2026-03-12T15:52:03.939Z,1773330723.939 [Default:CheckIn] Running Loop=1
2026-03-12T15:52:03.939Z,1773330723.939 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-12T15:52:03.939Z,1773330723.939 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-12T15:52:04.331Z,1773330724.331 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2026-03-12T15:52:05.939Z,1773330725.939 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155159.00,A,3647.78828,N,12150.92187,W,1.263,311.21,120326,,,A*7B
2026-03-12T15:52:05.941Z,1773330725.941 [NAL9602](INFO): GPS fix at 20260312T155159: (36.796471, -121.848698)
2026-03-12T15:52:05.967Z,1773330725.967 [Default:CheckIn:Read_GPS] Stopped
2026-03-12T15:52:05.967Z,1773330725.967 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-12T15:52:06.383Z,1773330726.383 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-03-12T15:52:24.781Z,1773330744.781 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003986
2026-03-12T15:52:32.143Z,1773330752.143 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20260312T054726/Courier0045.lzma
2026-03-12T15:52:33.146Z,1773330753.146 [DataOverHttps](INFO): Moved sent file to Logs/20260312T054726/Courier0045.lzma.bak
2026-03-12T15:52:33.146Z,1773330753.146 [DataOverHttps](INFO): SBD MOMSN=26722110
2026-03-12T15:52:33.460Z,1773330753.460 [NAL9602](INFO): SBD MO Status=2, MOMSN=13180, MT Status=2, MTMSN=0
2026-03-12T15:52:33.461Z,1773330753.461 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T15:52:51.264Z,1773330771.264 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20260312T154850/Courier0000.lzma
2026-03-12T15:52:52.265Z,1773330772.265 [DataOverHttps](INFO): Moved sent file to Logs/20260312T154850/Courier0000.lzma.bak
2026-03-12T15:52:52.265Z,1773330772.265 [DataOverHttps](INFO): SBD MOMSN=26722112
2026-03-12T15:53:04.288Z,1773330784.288 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T15:53:08.123Z,1773330788.123 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20260312T154850/Courier0004.lzma
2026-03-12T15:53:09.125Z,1773330789.125 [DataOverHttps](INFO): Moved sent file to Logs/20260312T154850/Courier0004.lzma.bak
2026-03-12T15:53:09.125Z,1773330789.125 [DataOverHttps](INFO): SBD MOMSN=26722116
2026-03-12T15:53:24.771Z,1773330804.771 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20260312T054726/Express0046.lzma
2026-03-12T15:53:25.773Z,1773330805.773 [DataOverHttps](INFO): Moved sent file to Logs/20260312T054726/Express0046.lzma.bak
2026-03-12T15:53:25.774Z,1773330805.774 [DataOverHttps](INFO): SBD MOMSN=26722119
2026-03-12T15:53:41.641Z,1773330821.641 [DataOverHttps](INFO): Sending 978 bytes from file Logs/20260312T154850/Express0001.lzma
2026-03-12T15:53:42.641Z,1773330822.641 [DataOverHttps](INFO): Moved sent file to Logs/20260312T154850/Express0001.lzma.bak
2026-03-12T15:53:42.642Z,1773330822.642 [DataOverHttps](INFO): SBD MOMSN=26722123
2026-03-12T15:53:58.310Z,1773330838.310 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-12T15:53:58.356Z,1773330838.356 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20260312T154850/Express0005.lzma
2026-03-12T15:53:59.345Z,1773330839.345 [DataOverHttps](INFO): Moved sent file to Logs/20260312T154850/Express0005.lzma.bak
2026-03-12T15:53:59.345Z,1773330839.345 [DataOverHttps](INFO): SBD MOMSN=26722149
2026-03-12T15:54:00.734Z,1773330840.734 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-12T15:54:00.777Z,1773330840.777 [Default:CheckIn:Read_Iridium] Stopped
2026-03-12T15:54:00.777Z,1773330840.777 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-12T15:54:00.778Z,1773330840.778 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-12T15:54:05.179Z,1773330845.179 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-12T15:54:07.604Z,1773330847.604 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-12T15:54:20.536Z,1773330860.536 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-12T15:54:22.550Z,1773330862.550 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-12T15:54:35.882Z,1773330875.882 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-12T15:54:37.498Z,1773330877.498 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-12T15:54:50.832Z,1773330890.832 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-12T15:54:52.448Z,1773330892.448 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-12T15:55:05.778Z,1773330905.778 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-12T15:55:08.202Z,1773330908.202 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-12T15:55:20.763Z,1773330920.763 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-12T15:55:23.994Z,1773330923.994 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-12T15:55:36.114Z,1773330936.114 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-12T15:55:39.346Z,1773330939.346 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-12T15:55:51.065Z,1773330951.065 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-12T15:55:54.296Z,1773330954.296 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-12T15:56:06.414Z,1773330966.414 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-12T15:56:09.243Z,1773330969.243 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-12T15:56:21.370Z,1773330981.370 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5.
2026-03-12T15:56:21.373Z,1773330981.373 [BPC1](INFO): Received data from all battery sticks.
2026-03-12T15:56:30.581Z,1773330990.581 [DataOverHttps](IMPORTANT): SBD MTMSN=20260312T155625
2026-03-12T15:56:38.436Z,1773330998.436 [DataOverHttps](INFO): Received command: schedule resume
2026-03-12T15:56:38.494Z,1773330998.494 [CommandExec](IMPORTANT): got command schedule resume
2026-03-12T15:57:50.429Z,1773331070.429 [DataOverHttps](IMPORTANT): SBD MTMSN=20260312T155745
2026-03-12T15:57:57.699Z,1773331077.699 [DataOverHttps](INFO): Received command: load Transport/keepstation.tl;set keepstation.NeedCommsTime 10 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;run
2026-03-12T15:57:57.740Z,1773331077.740 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2026-03-12T15:57:57.741Z,1773331077.741 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2026-03-12T15:57:57.741Z,1773331077.741 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2026-03-12T15:57:57.745Z,1773331077.745 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2026-03-12T15:57:58.951Z,1773331078.951 [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
2026-03-12T15:57:58.961Z,1773331078.961 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2026-03-12T15:57:58.964Z,1773331078.964 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2026-03-12T15:57:58.967Z,1773331078.967 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2026-03-12T15:57:58.970Z,1773331078.970 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2026-03-12T15:57:58.973Z,1773331078.973 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2026-03-12T15:57:58.976Z,1773331078.976 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2026-03-12T15:57:58.979Z,1773331078.979 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2026-03-12T15:57:58.982Z,1773331078.982 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2026-03-12T15:57:58.985Z,1773331078.985 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2026-03-12T15:57:58.988Z,1773331078.988 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2026-03-12T15:57:58.991Z,1773331078.991 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2026-03-12T15:57:58.994Z,1773331078.994 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2026-03-12T15:57:58.997Z,1773331078.997 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2026-03-12T15:57:59.020Z,1773331079.020 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2026-03-12T15:57:59.023Z,1773331079.023 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2026-03-12T15:57:59.030Z,1773331079.030 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2026-03-12T15:57:59.035Z,1773331079.035 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2026-03-12T15:57:59.046Z,1773331079.046 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2026-03-12T15:57:59.053Z,1773331079.053 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2026-03-12T15:57:59.058Z,1773331079.058 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2026-03-12T15:57:59.058Z,1773331079.058 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2026-03-12T15:57:59.058Z,1773331079.058 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2026-03-12T15:57:59.071Z,1773331079.071 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2026-03-12T15:57:59.497Z,1773331079.497 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1500
7
30
0
0
0
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2026-03-12T15:57:59.503Z,1773331079.503 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2026-03-12T15:57:59.506Z,1773331079.506 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2026-03-12T15:57:59.509Z,1773331079.509 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2026-03-12T15:57:59.512Z,1773331079.512 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2026-03-12T15:57:59.515Z,1773331079.515 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2026-03-12T15:57:59.517Z,1773331079.517 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1500.000000 s
2026-03-12T15:57:59.520Z,1773331079.520 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2026-03-12T15:57:59.523Z,1773331079.523 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2026-03-12T15:57:59.570Z,1773331079.570 [MissionManager](INFO): DefineArg keepstation:NeedComms.DoingCommsMode = 0.000000 count
2026-03-12T15:57:59.578Z,1773331079.578 [MissionManager](INFO): DefineArg keepstation:NeedComms.RestoreMode = 0.000000 count
2026-03-12T15:57:59.593Z,1773331079.593 [MissionManager](INFO): DefineOutput keepstation:NeedComms.IsActive = 0 bool
2026-03-12T15:57:59.605Z,1773331079.605 [MissionManager](INFO): DefineOutput keepstation:NeedComms.RequiredDiveMode = 0.000000 count
2026-03-12T15:57:59.646Z,1773331079.646 [keepstation:NeedComms:C.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-12T15:57:59.716Z,1773331079.716 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2026-03-12T15:57:59.716Z,1773331079.716 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2026-03-12T15:57:59.717Z,1773331079.717 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2026-03-12T15:57:59.846Z,1773331079.846 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2026-03-12T15:57:59.855Z,1773331079.855 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2026-03-12T15:57:59.862Z,1773331079.862 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2026-03-12T15:57:59.865Z,1773331079.865 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2026-03-12T15:57:59.876Z,1773331079.876 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2026-03-12T15:57:59.877Z,1773331079.877 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2026-03-12T15:57:59.887Z,1773331079.887 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2026-03-12T15:57:59.909Z,1773331079.909 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2026-03-12T15:57:59.925Z,1773331079.925 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2026-03-12T15:57:59.925Z,1773331079.925 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2026-03-12T15:57:59.926Z,1773331079.926 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2026-03-12T15:58:00.026Z,1773331080.026 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2026-03-12T15:58:00.034Z,1773331080.034 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2026-03-12T15:58:00.041Z,1773331080.041 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2026-03-12T15:58:00.045Z,1773331080.045 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2026-03-12T15:58:00.045Z,1773331080.045 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2026-03-12T15:58:00.046Z,1773331080.046 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2026-03-12T15:58:00.234Z,1773331080.234 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2026-03-12T15:58:00.243Z,1773331080.243 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2026-03-12T15:58:00.254Z,1773331080.254 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2026-03-12T15:58:00.265Z,1773331080.265 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2026-03-12T15:58:00.272Z,1773331080.272 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2026-03-12T15:58:00.280Z,1773331080.280 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2026-03-12T15:58:00.291Z,1773331080.291 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2026-03-12T15:58:00.297Z,1773331080.297 [keepstation:H.Pitch](DEBUG): Construct.
2026-03-12T15:58:00.434Z,1773331080.434 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2026-03-12T15:58:00.437Z,1773331080.437 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2026-03-12T15:58:00.441Z,1773331080.441 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2026-03-12T15:58:00.484Z,1773331080.484 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2026-03-12T15:58:00.488Z,1773331080.488 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2026-03-12T15:58:00.493Z,1773331080.493 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2026-03-12T15:58:00.511Z,1773331080.511 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2026-03-12T15:58:00.516Z,1773331080.516 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2026-03-12T15:58:00.532Z,1773331080.532 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2026-03-12T15:58:00.565Z,1773331080.565 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2026-03-12T15:58:00.595Z,1773331080.595 [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
}
}
}
2026-03-12T15:58:00.596Z,1773331080.596 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2026-03-12T15:58:08.366Z,1773331088.366 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 10 minute
2026-03-12T15:58:08.367Z,1773331088.367 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree
2026-03-12T15:58:08.374Z,1773331088.374 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree
2026-03-12T15:58:08.375Z,1773331088.375 [CommandExec](IMPORTANT): got command run
2026-03-12T15:58:08.377Z,1773331088.377 [CommandExec](IMPORTANT): Running
2026-03-12T15:58:08.467Z,1773331088.467 [Default] Stopped
2026-03-12T15:58:08.468Z,1773331088.468 [Default](DEBUG): Aggregate::uninitialize Default
2026-03-12T15:58:08.468Z,1773331088.468 [Default:B.GoToSurface] Stopped
2026-03-12T15:58:08.468Z,1773331088.468 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T15:58:08.468Z,1773331088.468 [Default:CheckIn] Stopped
2026-03-12T15:58:08.468Z,1773331088.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-12T15:58:08.468Z,1773331088.468 [Default:CheckIn:C.Wait] Stopped
2026-03-12T15:58:08.468Z,1773331088.468 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-12T15:58:08.468Z,1773331088.468 [MissionManager](IMPORTANT): Started mission keepstation
2026-03-12T15:58:08.469Z,1773331088.469 [keepstation] Running Loop=1
2026-03-12T15:58:08.469Z,1773331088.469 [keepstation](DEBUG): Aggregate::initialize keepstation
2026-03-12T15:58:08.469Z,1773331088.469 [keepstation:StandardEnvelopes] Running Loop=1
2026-03-12T15:58:08.469Z,1773331088.469 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2026-03-12T15:58:08.469Z,1773331088.469 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2026-03-12T15:58:08.469Z,1773331088.469 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2026-03-12T15:58:08.469Z,1773331088.469 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2026-03-12T15:58:08.469Z,1773331088.469 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2026-03-12T15:58:08.470Z,1773331088.470 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2026-03-12T15:58:08.470Z,1773331088.470 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2026-03-12T15:58:08.470Z,1773331088.470 [keepstation:BackseatDriver] Running Loop=1
2026-03-12T15:58:08.470Z,1773331088.470 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2026-03-12T15:58:08.470Z,1773331088.470 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2026-03-12T15:58:08.470Z,1773331088.470 [keepstation:PowerOnly] Running Loop=1
2026-03-12T15:58:08.470Z,1773331088.470 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2026-03-12T15:58:08.470Z,1773331088.470 [keepstation:PowerOnly:A] Running Loop=1
2026-03-12T15:58:08.470Z,1773331088.470 [keepstation:PowerOnly:B] Running Loop=1
2026-03-12T15:58:08.470Z,1773331088.470 [keepstation:PowerOnly:C] Running Loop=1
2026-03-12T15:58:08.470Z,1773331088.470 [keepstation:PowerOnly:D] Running Loop=1
2026-03-12T15:58:08.471Z,1773331088.471 [keepstation:PowerOnly:E.Wait] Running Loop=1
2026-03-12T15:58:08.471Z,1773331088.471 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2026-03-12T15:58:08.471Z,1773331088.471 [keepstation:H.Pitch] Running Loop=1
2026-03-12T15:58:08.471Z,1773331088.471 [keepstation:H.Pitch](DEBUG): Initialize.
2026-03-12T15:58:08.471Z,1773331088.471 [keepstation:B] Running Loop=1
2026-03-12T15:58:08.472Z,1773331088.472 [keepstation:H.Pitch] Running Loop=1
2026-03-12T15:58:08.472Z,1773331088.472 [keepstation:PowerOnly] Running Loop=1
2026-03-12T15:58:08.473Z,1773331088.473 [keepstation:PowerOnly:D] Stopped
2026-03-12T15:58:08.473Z,1773331088.473 [keepstation:PowerOnly:C] Stopped
2026-03-12T15:58:08.473Z,1773331088.473 [keepstation:PowerOnly:B] Stopped
2026-03-12T15:58:08.473Z,1773331088.473 [keepstation:PowerOnly:A] Stopped
2026-03-12T15:58:08.473Z,1773331088.473 [keepstation:BackseatDriver] Running Loop=1
2026-03-12T15:58:08.473Z,1773331088.473 [keepstation:StandardEnvelopes] Running Loop=1
2026-03-12T15:58:08.474Z,1773331088.474 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2026-03-12T15:58:08.474Z,1773331088.474 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2026-03-12T15:58:08.475Z,1773331088.475 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2026-03-12T15:58:08.475Z,1773331088.475 [keepstation:B] Stopped
2026-03-12T15:58:08.488Z,1773331088.488 [keepstation:D] Running Loop=1
2026-03-12T15:58:08.859Z,1773331088.859 [keepstation:D] Stopped
2026-03-12T15:58:08.860Z,1773331088.860 [keepstation:E] Running Loop=1
2026-03-12T15:58:09.247Z,1773331089.247 [keepstation:E] Stopped
2026-03-12T15:58:09.247Z,1773331089.247 [keepstation:StartingMission] Running Loop=1
2026-03-12T15:58:09.248Z,1773331089.248 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2026-03-12T15:58:09.659Z,1773331089.659 [keepstation:SurfaceComms] Running Loop=1
2026-03-12T15:58:09.659Z,1773331089.659 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2026-03-12T15:58:09.659Z,1773331089.659 [keepstation:SurfaceComms:A] Running Loop=1
2026-03-12T15:58:09.660Z,1773331089.660 [keepstation:SurfaceComms:A] Stopped
2026-03-12T15:58:09.660Z,1773331089.660 [keepstation:SurfaceComms:B] Running Loop=1
2026-03-12T15:58:09.660Z,1773331089.660 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2026-03-12T15:58:10.063Z,1773331090.063 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2026-03-12T15:58:10.063Z,1773331090.063 [keepstation:NeedComms] Running Loop=1
2026-03-12T15:58:10.063Z,1773331090.063 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2026-03-12T15:58:10.063Z,1773331090.063 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2026-03-12T15:58:10.063Z,1773331090.063 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T15:58:10.064Z,1773331090.064 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2026-03-12T15:58:10.064Z,1773331090.064 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2026-03-12T15:58:10.064Z,1773331090.064 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2026-03-12T15:58:10.065Z,1773331090.065 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2026-03-12T15:58:10.065Z,1773331090.065 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T15:58:10.065Z,1773331090.065 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T15:58:10.066Z,1773331090.066 [keepstation:NeedComms:A] Running Loop=1
2026-03-12T15:58:10.067Z,1773331090.067 [keepstation:NeedComms:A](INFO): last time_fix was: 1773330719.000000 second since 1970/01/01T00:00:00Z
2026-03-12T15:58:10.067Z,1773331090.067 [keepstation:NeedComms:A] Stopped
2026-03-12T15:58:10.067Z,1773331090.067 [keepstation:NeedComms:setActive] Running Loop=1
2026-03-12T15:58:10.067Z,1773331090.067 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2026-03-12T15:58:10.067Z,1773331090.067 [keepstation:NeedComms:setActive:A] Running Loop=1
2026-03-12T15:58:10.469Z,1773331090.469 [keepstation:NeedComms:setActive:A] Stopped
2026-03-12T15:58:10.469Z,1773331090.469 [keepstation:NeedComms:setActive:B] Running Loop=1
2026-03-12T15:58:10.865Z,1773331090.865 [keepstation:NeedComms:setActive:B] Stopped
2026-03-12T15:58:10.865Z,1773331090.865 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2026-03-12T15:58:10.865Z,1773331090.865 [keepstation:NeedComms:setActive] Stopped
2026-03-12T15:58:10.865Z,1773331090.865 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2026-03-12T15:58:11.273Z,1773331091.273 [keepstation:NeedComms:D] Running Loop=1
2026-03-12T15:58:11.681Z,1773331091.681 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense time_fix
2026-03-12T15:58:13.287Z,1773331093.287 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155807.00,A,3647.80417,N,12150.96743,W,0.058,273.76,120326,,,A*78
2026-03-12T15:58:13.289Z,1773331093.289 [NAL9602](INFO): GPS fix at 20260312T155807: (36.796736, -121.849457)
2026-03-12T15:58:13.302Z,1773331093.302 [keepstation:NeedComms:D] Stopped
2026-03-12T15:58:13.302Z,1773331093.302 [keepstation:NeedComms:E] Running Loop=1
2026-03-12T15:58:13.722Z,1773331093.722 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-03-12T15:58:20.163Z,1773331100.163 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20260312T154850/Courier0007.lzma
2026-03-12T15:58:21.165Z,1773331101.165 [DataOverHttps](INFO): Moved sent file to Logs/20260312T154850/Courier0007.lzma.bak
2026-03-12T15:58:21.165Z,1773331101.165 [DataOverHttps](INFO): SBD MOMSN=26722190
2026-03-12T15:58:36.880Z,1773331116.880 [DataOverHttps](INFO): Sending 416 bytes from file Logs/20260312T154850/Express0008.lzma
2026-03-12T15:58:37.874Z,1773331117.874 [DataOverHttps](INFO): Moved sent file to Logs/20260312T154850/Express0008.lzma.bak
2026-03-12T15:58:37.874Z,1773331117.874 [DataOverHttps](INFO): SBD MOMSN=26722193
2026-03-12T15:58:39.238Z,1773331119.238 [keepstation:NeedComms:E] Stopped
2026-03-12T15:58:39.238Z,1773331119.238 [keepstation:NeedComms:F] Running Loop=1
2026-03-12T15:58:39.641Z,1773331119.641 [keepstation:NeedComms:F](DEBUG): Initialize ReadDataComponent to sense time_fix
2026-03-12T15:58:50.128Z,1773331130.128 [NAL9602](INFO): SBD MO Status=1, MOMSN=13180, MT Status=0, MTMSN=0
2026-03-12T15:58:50.128Z,1773331130.128 [NAL9602](INFO): No messages in MT queue
2026-03-12T15:58:51.331Z,1773331131.331 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155845.00,A,3647.80614,N,12150.97262,W,0.680,259.60,120326,,,D*71
2026-03-12T15:58:51.333Z,1773331131.333 [NAL9602](INFO): GPS fix at 20260312T155845: (36.796769, -121.849544)
2026-03-12T15:58:51.346Z,1773331131.346 [keepstation:NeedComms:F] Stopped
2026-03-12T15:58:51.346Z,1773331131.346 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2026-03-12T15:58:51.346Z,1773331131.346 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2026-03-12T15:58:51.346Z,1773331131.346 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2026-03-12T15:58:51.777Z,1773331131.777 [keepstation:NeedComms:setRestoreMode:A] Stopped
2026-03-12T15:58:51.777Z,1773331131.777 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2026-03-12T15:58:51.777Z,1773331131.777 [keepstation:NeedComms:setRestoreMode] Stopped
2026-03-12T15:58:51.777Z,1773331131.777 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2026-03-12T15:58:51.778Z,1773331131.778 [keepstation:NeedComms:setInactive] Running Loop=1
2026-03-12T15:58:51.778Z,1773331131.778 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2026-03-12T15:58:51.778Z,1773331131.778 [keepstation:NeedComms:setInactive:A] Running Loop=1
2026-03-12T15:58:52.148Z,1773331132.148 [keepstation:NeedComms:setInactive:A] Stopped
2026-03-12T15:58:52.148Z,1773331132.148 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2026-03-12T15:58:52.148Z,1773331132.148 [keepstation:NeedComms:setInactive] Stopped
2026-03-12T15:58:52.148Z,1773331132.148 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2026-03-12T15:58:52.149Z,1773331132.149 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2026-03-12T15:58:52.149Z,1773331132.149 [keepstation:NeedComms] Stopped
2026-03-12T15:58:52.149Z,1773331132.149 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2026-03-12T15:58:52.149Z,1773331132.149 [keepstation:NeedComms:C.GoToSurface] Stopped
2026-03-12T15:58:52.149Z,1773331132.149 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T15:58:52.561Z,1773331132.561 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2026-03-12T15:58:52.561Z,1773331132.561 [keepstation:SurfaceComms:B] Stopped
2026-03-12T15:58:52.561Z,1773331132.561 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2026-03-12T15:58:52.561Z,1773331132.561 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2026-03-12T15:58:52.561Z,1773331132.561 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2026-03-12T15:58:52.561Z,1773331132.561 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2026-03-12T15:58:52.561Z,1773331132.561 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T15:58:52.561Z,1773331132.561 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2026-03-12T15:58:52.968Z,1773331132.968 [keepstation:SurfaceComms:HandleNan] Stopped
2026-03-12T15:58:52.968Z,1773331132.968 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2026-03-12T15:58:52.968Z,1773331132.968 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2026-03-12T15:58:52.968Z,1773331132.968 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T15:58:52.968Z,1773331132.968 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2026-03-12T15:58:52.969Z,1773331132.969 [keepstation:SurfaceComms:setTransit] Running Loop=1
2026-03-12T15:58:52.969Z,1773331132.969 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2026-03-12T15:58:52.969Z,1773331132.969 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2026-03-12T15:58:53.376Z,1773331133.376 [keepstation:SurfaceComms:setTransit:A] Stopped
2026-03-12T15:58:53.376Z,1773331133.376 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2026-03-12T15:58:53.376Z,1773331133.376 [keepstation:SurfaceComms:setTransit] Stopped
2026-03-12T15:58:53.377Z,1773331133.377 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2026-03-12T15:58:53.377Z,1773331133.377 [keepstation:SurfaceComms:setStation] Running Loop=1
2026-03-12T15:58:53.377Z,1773331133.377 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2026-03-12T15:58:53.377Z,1773331133.377 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2026-03-12T15:58:53.770Z,1773331133.770 [keepstation:SurfaceComms:setStation] Stopped
2026-03-12T15:58:53.770Z,1773331133.770 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2026-03-12T15:58:53.770Z,1773331133.770 [keepstation:SurfaceComms:setStation:A] Stopped
2026-03-12T15:58:53.770Z,1773331133.770 [keepstation:SurfaceComms:setSink] Running Loop=1
2026-03-12T15:58:53.771Z,1773331133.771 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2026-03-12T15:58:53.771Z,1773331133.771 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2026-03-12T15:58:54.172Z,1773331134.172 [keepstation:SurfaceComms:setSink] Stopped
2026-03-12T15:58:54.172Z,1773331134.172 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2026-03-12T15:58:54.172Z,1773331134.172 [keepstation:SurfaceComms:setSink:A] Stopped
2026-03-12T15:58:54.172Z,1773331134.172 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2026-03-12T15:58:54.172Z,1773331134.172 [keepstation:SurfaceComms] Stopped
2026-03-12T15:58:54.172Z,1773331134.172 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2026-03-12T15:58:54.576Z,1773331134.576 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2026-03-12T15:58:54.576Z,1773331134.576 [keepstation:StartingMission] Stopped
2026-03-12T15:58:54.576Z,1773331134.576 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2026-03-12T15:58:54.576Z,1773331134.576 [keepstation:TransitToStation] Running Loop=1
2026-03-12T15:58:54.576Z,1773331134.576 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2026-03-12T15:58:54.576Z,1773331134.576 [keepstation:TransitToStation:Dive] Running Loop=1
2026-03-12T15:58:54.576Z,1773331134.576 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2026-03-12T15:58:54.576Z,1773331134.576 [keepstation:TransitToStation:Dive:A] Running Loop=1
2026-03-12T15:58:54.577Z,1773331134.577 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2026-03-12T15:58:54.577Z,1773331134.577 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2026-03-12T15:58:54.577Z,1773331134.577 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2026-03-12T15:58:54.578Z,1773331134.578 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2026-03-12T15:58:54.988Z,1773331134.988 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2026-03-12T15:58:54.989Z,1773331134.989 [keepstation:TransitToStation:Dive] Running Loop=1
2026-03-12T15:58:54.989Z,1773331134.989 [keepstation:DiveCmd] Running Loop=1
2026-03-12T15:58:54.989Z,1773331134.989 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2026-03-12T15:58:54.989Z,1773331134.989 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2026-03-12T15:58:54.989Z,1773331134.989 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2026-03-12T15:58:54.990Z,1773331134.990 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2026-03-12T15:58:54.990Z,1773331134.990 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2026-03-12T15:58:54.990Z,1773331134.990 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2026-03-12T15:58:54.990Z,1773331134.990 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2026-03-12T15:58:54.990Z,1773331134.990 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2026-03-12T15:58:54.990Z,1773331134.990 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2026-03-12T15:58:54.990Z,1773331134.990 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2026-03-12T15:58:54.990Z,1773331134.990 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2026-03-12T15:58:54.990Z,1773331134.990 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2026-03-12T15:58:54.990Z,1773331134.990 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2026-03-12T15:58:54.991Z,1773331134.991 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2026-03-12T15:58:54.991Z,1773331134.991 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2026-03-12T15:58:54.991Z,1773331134.991 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2026-03-12T15:58:54.991Z,1773331134.991 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2026-03-12T15:58:54.991Z,1773331134.991 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2026-03-12T15:58:54.991Z,1773331134.991 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2026-03-12T15:58:54.991Z,1773331134.991 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2026-03-12T15:58:54.996Z,1773331134.996 [keepstation:DiveCmd:StationKeep] Running Loop=1
2026-03-12T15:58:54.996Z,1773331134.996 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2026-03-12T15:58:54.996Z,1773331134.996 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2026-03-12T15:58:54.996Z,1773331134.996 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2026-03-12T15:58:54.996Z,1773331134.996 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2026-03-12T15:58:54.997Z,1773331134.997 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2026-03-12T15:58:54.997Z,1773331134.997 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2026-03-12T15:58:54.998Z,1773331134.997 [keepstation:DiveCmd:StationKeep] Stopped
2026-03-12T15:58:54.998Z,1773331134.998 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2026-03-12T15:58:54.998Z,1773331134.998 [keepstation:DiveCmd:StationKeep:A] Stopped
2026-03-12T15:58:54.998Z,1773331134.998 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2026-03-12T15:58:54.998Z,1773331134.998 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2026-03-12T15:58:54.998Z,1773331134.998 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2026-03-12T15:58:54.998Z,1773331134.998 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2026-03-12T15:58:54.998Z,1773331134.998 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2026-03-12T15:58:54.998Z,1773331134.998 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2026-03-12T15:58:54.999Z,1773331134.999 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2026-03-12T15:58:54.999Z,1773331134.999 [keepstation:DiveCmd:SurfaceSink] Stopped
2026-03-12T15:58:54.999Z,1773331134.999 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2026-03-12T15:58:55.004Z,1773331135.004 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2026-03-12T15:58:55.004Z,1773331135.004 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2026-03-12T15:58:55.005Z,1773331135.005 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2026-03-12T15:58:55.005Z,1773331135.005 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2026-03-12T15:58:55.005Z,1773331135.005 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2026-03-12T15:58:55.005Z,1773331135.005 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2026-03-12T15:58:55.005Z,1773331135.005 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2026-03-12T15:58:55.021Z,1773331135.021 [HorizontalControl](DEBUG): kpHeading = 0.400000
2026-03-12T15:58:55.021Z,1773331135.021 [HorizontalControl](DEBUG): kiHeading = 0.001000
2026-03-12T15:58:55.022Z,1773331135.022 [HorizontalControl](DEBUG): kdHeading = 0.050000
2026-03-12T15:59:23.960Z,1773331163.960 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T16:00:55.440Z,1773331255.440 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:00:58.452Z,1773331258.452 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.238991
2026-03-12T16:01:07.260Z,1773331267.260 [Radio_Surface](INFO): Powering down
2026-03-12T16:01:30.596Z,1773331290.596 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:01:31.601Z,1773331291.601 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2026-03-12T16:05:31.090Z,1773331531.090 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000
2026-03-12T16:05:31.090Z,1773331531.090 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2026-03-12T16:05:31.090Z,1773331531.090 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2026-03-12T16:05:31.090Z,1773331531.090 [keepstation:TransitToStation:C] Running Loop=1
2026-03-12T16:05:31.548Z,1773331531.548 [keepstation:TransitToStation:C] Stopped
2026-03-12T16:05:31.548Z,1773331531.548 [keepstation:TransitToStation:D] Running Loop=1
2026-03-12T16:05:31.549Z,1773331531.549 [keepstation:DiveCmd:StationKeep] Running Loop=1
2026-03-12T16:05:31.549Z,1773331531.549 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2026-03-12T16:05:31.549Z,1773331531.549 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2026-03-12T16:05:31.549Z,1773331531.549 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2026-03-12T16:05:31.549Z,1773331531.549 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2026-03-12T16:05:31.549Z,1773331531.549 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2026-03-12T16:05:31.549Z,1773331531.549 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2026-03-12T16:05:31.550Z,1773331531.550 [keepstation:DiveCmd:StationKeep] Running Loop=1
2026-03-12T16:05:31.550Z,1773331531.550 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2026-03-12T16:05:31.550Z,1773331531.550 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2026-03-12T16:05:31.551Z,1773331531.551 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2026-03-12T16:05:31.551Z,1773331531.551 [keepstation:DiveCmd:TransitToStation] Stopped
2026-03-12T16:05:31.552Z,1773331531.552 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2026-03-12T16:05:31.552Z,1773331531.552 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2026-03-12T16:05:31.552Z,1773331531.552 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2026-03-12T16:05:31.552Z,1773331531.552 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2026-03-12T16:05:31.552Z,1773331531.552 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2026-03-12T16:05:31.552Z,1773331531.552 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2026-03-12T16:05:31.552Z,1773331531.552 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2026-03-12T16:05:31.552Z,1773331531.552 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2026-03-12T16:05:31.869Z,1773331531.869 [keepstation:TransitToStation:D] Stopped
2026-03-12T16:05:31.869Z,1773331531.869 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2026-03-12T16:05:31.869Z,1773331531.869 [keepstation:TransitToStation] Stopped
2026-03-12T16:05:31.869Z,1773331531.869 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2026-03-12T16:05:31.869Z,1773331531.869 [keepstation:TransitToStation:Dive] Stopped
2026-03-12T16:05:31.869Z,1773331531.869 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2026-03-12T16:05:31.869Z,1773331531.869 [keepstation:TransitToStation:Dive:A] Stopped
2026-03-12T16:05:31.869Z,1773331531.869 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2026-03-12T16:05:31.869Z,1773331531.869 [keepstation:OnStation] Running Loop=1
2026-03-12T16:05:31.870Z,1773331531.870 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2026-03-12T16:05:32.289Z,1773331532.289 [keepstation:NeedComms] Running Loop=1
2026-03-12T16:05:32.289Z,1773331532.289 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2026-03-12T16:05:32.289Z,1773331532.289 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2026-03-12T16:05:32.289Z,1773331532.289 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T16:05:32.290Z,1773331532.290 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2026-03-12T16:05:32.290Z,1773331532.290 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2026-03-12T16:05:32.290Z,1773331532.290 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2026-03-12T16:05:32.291Z,1773331532.291 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2026-03-12T16:05:32.291Z,1773331532.291 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T16:05:32.291Z,1773331532.291 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T16:05:32.292Z,1773331532.292 [keepstation:NeedComms:A] Running Loop=1
2026-03-12T16:05:32.294Z,1773331532.294 [keepstation:NeedComms:A](INFO): last time_fix was: 1773331125.000000 second since 1970/01/01T00:00:00Z
2026-03-12T16:05:32.294Z,1773331532.294 [keepstation:NeedComms:A] Stopped
2026-03-12T16:05:32.294Z,1773331532.294 [keepstation:NeedComms:setActive] Running Loop=1
2026-03-12T16:05:32.294Z,1773331532.294 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2026-03-12T16:05:32.294Z,1773331532.294 [keepstation:NeedComms:setActive:A] Running Loop=1
2026-03-12T16:05:32.674Z,1773331532.674 [keepstation:NeedComms:setActive:A] Stopped
2026-03-12T16:05:32.674Z,1773331532.674 [keepstation:NeedComms:setActive:B] Running Loop=1
2026-03-12T16:05:33.272Z,1773331533.272 [keepstation:NeedComms:setActive:B] Stopped
2026-03-12T16:05:33.272Z,1773331533.272 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2026-03-12T16:05:33.272Z,1773331533.272 [keepstation:NeedComms:setActive] Stopped
2026-03-12T16:05:33.273Z,1773331533.273 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2026-03-12T16:06:32.554Z,1773331592.554 [keepstation:NeedComms:D] Running Loop=1
2026-03-12T16:06:33.020Z,1773331593.020 [Waterlinked](INFO): Powering down
2026-03-12T16:06:33.040Z,1773331593.040 [Radio_Surface](INFO): Powering up
2026-03-12T16:06:38.888Z,1773331598.888 [DataOverHttps](INFO): Radio surface powered ON.
2026-03-12T16:07:09.020Z,1773331629.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:07:26.699Z,1773331646.699 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:07:26.699Z,1773331646.699 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:07:43.674Z,1773331663.674 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:07:43.674Z,1773331663.674 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:07:44.161Z,1773331664.161 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:08:15.182Z,1773331695.182 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:08:15.182Z,1773331695.182 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:08:19.309Z,1773331699.309 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:08:35.782Z,1773331715.782 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:08:35.782Z,1773331715.782 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:08:51.548Z,1773331731.548 [keepstation:SurfaceComms] Running Loop=1
2026-03-12T16:08:51.548Z,1773331731.548 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2026-03-12T16:08:51.548Z,1773331731.548 [keepstation:SurfaceComms:A] Running Loop=1
2026-03-12T16:08:51.549Z,1773331731.549 [keepstation:SurfaceComms:A] Stopped
2026-03-12T16:08:51.549Z,1773331731.549 [keepstation:SurfaceComms:B] Running Loop=1
2026-03-12T16:08:51.549Z,1773331731.549 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2026-03-12T16:08:51.959Z,1773331731.959 [keepstation:DiveCmd:StationKeep] Stopped
2026-03-12T16:08:51.959Z,1773331731.959 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2026-03-12T16:08:51.959Z,1773331731.959 [keepstation:DiveCmd:StationKeep:A] Stopped
2026-03-12T16:08:51.959Z,1773331731.959 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2026-03-12T16:08:51.960Z,1773331731.960 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2026-03-12T16:08:51.960Z,1773331731.960 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2026-03-12T16:08:54.454Z,1773331734.454 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:09:02.043Z,1773331742.043 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:09:02.043Z,1773331742.043 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:09:21.842Z,1773331761.842 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:09:21.842Z,1773331761.842 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:09:29.592Z,1773331769.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:10:02.064Z,1773331802.064 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003202
2026-03-12T16:10:07.898Z,1773331807.898 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:10:07.898Z,1773331807.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:10:33.750Z,1773331833.750 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:10:33.750Z,1773331833.750 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:11:16.991Z,1773331876.991 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:11:16.991Z,1773331876.991 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:11:36.777Z,1773331896.777 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-12T16:11:45.268Z,1773331905.268 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161139.00,A,3647.84748,N,12150.78924,W,0.505,355.84,120326,,,D*79
2026-03-12T16:11:45.270Z,1773331905.270 [NAL9602](INFO): GPS fix at 20260312T161139: (36.797458, -121.846487)
2026-03-12T16:11:45.307Z,1773331905.307 [keepstation:NeedComms:D] Stopped
2026-03-12T16:11:45.308Z,1773331905.308 [keepstation:NeedComms:E] Running Loop=1
2026-03-12T16:11:52.143Z,1773331912.143 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20260312T154850/Courier0010.lzma
2026-03-12T16:11:53.146Z,1773331913.146 [DataOverHttps](INFO): Moved sent file to Logs/20260312T154850/Courier0010.lzma.bak
2026-03-12T16:11:53.146Z,1773331913.146 [DataOverHttps](INFO): SBD MOMSN=26722240
2026-03-12T16:12:09.436Z,1773331929.436 [DataOverHttps](INFO): Sending 439 bytes from file Logs/20260312T154850/Express0014.lzma
2026-03-12T16:12:10.437Z,1773331930.437 [DataOverHttps](INFO): Moved sent file to Logs/20260312T154850/Express0014.lzma.bak
2026-03-12T16:12:10.437Z,1773331930.437 [DataOverHttps](INFO): SBD MOMSN=26722243
2026-03-12T16:12:11.957Z,1773331931.957 [keepstation:NeedComms:E] Stopped
2026-03-12T16:12:11.957Z,1773331931.957 [keepstation:NeedComms:F] Running Loop=1
2026-03-12T16:12:13.955Z,1773331933.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161207.00,A,3647.84880,N,12150.79165,W,1.633,12.91,120326,,,D*43
2026-03-12T16:12:13.957Z,1773331933.957 [NAL9602](INFO): GPS fix at 20260312T161207: (36.797480, -121.846527)
2026-03-12T16:12:13.996Z,1773331933.996 [keepstation:NeedComms:F] Stopped
2026-03-12T16:12:13.996Z,1773331933.996 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2026-03-12T16:12:13.997Z,1773331933.997 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2026-03-12T16:12:13.997Z,1773331933.997 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2026-03-12T16:12:14.401Z,1773331934.401 [keepstation:NeedComms:setRestoreMode:A] Stopped
2026-03-12T16:12:14.401Z,1773331934.401 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2026-03-12T16:12:14.401Z,1773331934.401 [keepstation:NeedComms:setRestoreMode] Stopped
2026-03-12T16:12:14.401Z,1773331934.401 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2026-03-12T16:12:14.401Z,1773331934.401 [keepstation:NeedComms:setInactive] Running Loop=1
2026-03-12T16:12:14.401Z,1773331934.401 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2026-03-12T16:12:14.402Z,1773331934.402 [keepstation:NeedComms:setInactive:A] Running Loop=1
2026-03-12T16:12:14.781Z,1773331934.781 [keepstation:NeedComms:setInactive:A] Stopped
2026-03-12T16:12:14.781Z,1773331934.781 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2026-03-12T16:12:14.781Z,1773331934.781 [keepstation:NeedComms:setInactive] Stopped
2026-03-12T16:12:14.781Z,1773331934.781 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2026-03-12T16:12:14.782Z,1773331934.782 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2026-03-12T16:12:14.782Z,1773331934.782 [keepstation:NeedComms] Stopped
2026-03-12T16:12:14.782Z,1773331934.782 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2026-03-12T16:12:14.782Z,1773331934.782 [keepstation:NeedComms:C.GoToSurface] Stopped
2026-03-12T16:12:14.782Z,1773331934.782 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T16:12:15.190Z,1773331935.190 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2026-03-12T16:12:15.190Z,1773331935.190 [keepstation:OnStation] Stopped
2026-03-12T16:12:15.190Z,1773331935.190 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2026-03-12T16:12:15.191Z,1773331935.191 [keepstation:KeepStation] Running Loop=1
2026-03-12T16:12:15.191Z,1773331935.191 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2026-03-12T16:12:15.191Z,1773331935.191 [keepstation:KeepStation:Dive] Running Loop=1
2026-03-12T16:12:15.191Z,1773331935.191 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2026-03-12T16:12:15.191Z,1773331935.191 [keepstation:KeepStation:Dive:A] Running Loop=1
2026-03-12T16:12:15.191Z,1773331935.191 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2026-03-12T16:12:15.195Z,1773331935.195 [keepstation:KeepStation:B.Wait] Running Loop=1
2026-03-12T16:12:15.196Z,1773331935.196 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2026-03-12T16:12:15.196Z,1773331935.196 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2026-03-12T16:12:15.196Z,1773331935.196 [keepstation:SurfaceComms:B] Stopped
2026-03-12T16:12:15.196Z,1773331935.196 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2026-03-12T16:12:15.196Z,1773331935.196 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2026-03-12T16:12:15.196Z,1773331935.196 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2026-03-12T16:12:15.197Z,1773331935.197 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2026-03-12T16:12:15.197Z,1773331935.197 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T16:12:15.197Z,1773331935.197 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2026-03-12T16:12:15.597Z,1773331935.597 [keepstation:KeepStation:Dive] Stopped
2026-03-12T16:12:15.597Z,1773331935.597 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2026-03-12T16:12:15.597Z,1773331935.597 [keepstation:KeepStation:Dive:A] Stopped
2026-03-12T16:12:15.597Z,1773331935.597 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2026-03-12T16:12:15.598Z,1773331935.598 [keepstation:SurfaceComms:HandleNan] Stopped
2026-03-12T16:12:15.598Z,1773331935.598 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2026-03-12T16:12:15.598Z,1773331935.598 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2026-03-12T16:12:15.598Z,1773331935.598 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T16:12:15.598Z,1773331935.598 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2026-03-12T16:12:15.598Z,1773331935.598 [keepstation:SurfaceComms:setTransit] Running Loop=1
2026-03-12T16:12:15.598Z,1773331935.598 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2026-03-12T16:12:15.598Z,1773331935.598 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2026-03-12T16:12:16.007Z,1773331936.007 [keepstation:SurfaceComms:setTransit] Stopped
2026-03-12T16:12:16.007Z,1773331936.007 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2026-03-12T16:12:16.007Z,1773331936.007 [keepstation:SurfaceComms:setTransit:A] Stopped
2026-03-12T16:12:16.011Z,1773331936.011 [keepstation:SurfaceComms:setStation] Running Loop=1
2026-03-12T16:12:16.012Z,1773331936.012 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2026-03-12T16:12:16.012Z,1773331936.012 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2026-03-12T16:12:16.393Z,1773331936.393 [keepstation:SurfaceComms:setStation:A] Stopped
2026-03-12T16:12:16.394Z,1773331936.394 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2026-03-12T16:12:16.394Z,1773331936.394 [keepstation:SurfaceComms:setStation] Stopped
2026-03-12T16:12:16.394Z,1773331936.394 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2026-03-12T16:12:16.394Z,1773331936.394 [keepstation:SurfaceComms:setSink] Running Loop=1
2026-03-12T16:12:16.394Z,1773331936.394 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2026-03-12T16:12:16.394Z,1773331936.394 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2026-03-12T16:12:16.834Z,1773331936.834 [keepstation:KeepStation:Dive] Running Loop=1
2026-03-12T16:12:16.834Z,1773331936.834 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2026-03-12T16:12:16.834Z,1773331936.834 [keepstation:KeepStation:Dive:A] Running Loop=1
2026-03-12T16:12:16.834Z,1773331936.834 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2026-03-12T16:12:16.834Z,1773331936.834 [keepstation:KeepStation:Dive] Running Loop=1
2026-03-12T16:12:16.835Z,1773331936.835 [keepstation:DiveCmd:StationKeep] Running Loop=1
2026-03-12T16:12:16.835Z,1773331936.835 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2026-03-12T16:12:16.835Z,1773331936.835 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2026-03-12T16:12:16.835Z,1773331936.835 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2026-03-12T16:12:16.835Z,1773331936.835 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2026-03-12T16:12:16.835Z,1773331936.835 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2026-03-12T16:12:16.836Z,1773331936.836 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2026-03-12T16:12:16.837Z,1773331936.837 [keepstation:SurfaceComms:setSink] Stopped
2026-03-12T16:12:16.837Z,1773331936.837 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2026-03-12T16:12:16.837Z,1773331936.837 [keepstation:SurfaceComms:setSink:A] Stopped
2026-03-12T16:12:16.837Z,1773331936.837 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2026-03-12T16:12:16.837Z,1773331936.837 [keepstation:SurfaceComms] Stopped
2026-03-12T16:12:16.838Z,1773331936.838 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2026-03-12T16:12:45.862Z,1773331965.862 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T16:15:10.876Z,1773332110.876 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239449
2026-03-12T16:15:59.889Z,1773332159.889 [Radio_Surface](INFO): Powering down
2026-03-12T16:16:14.137Z,1773332174.137 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:16:15.141Z,1773332175.141 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2026-03-12T16:22:14.453Z,1773332534.453 [keepstation:SurfaceComms] Running Loop=1
2026-03-12T16:22:14.453Z,1773332534.453 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2026-03-12T16:22:14.454Z,1773332534.454 [keepstation:SurfaceComms:A] Running Loop=1
2026-03-12T16:22:14.454Z,1773332534.454 [keepstation:SurfaceComms:A] Stopped
2026-03-12T16:22:14.454Z,1773332534.454 [keepstation:SurfaceComms:B] Running Loop=1
2026-03-12T16:22:14.455Z,1773332534.455 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2026-03-12T16:22:14.459Z,1773332534.459 [keepstation:NeedComms] Running Loop=1
2026-03-12T16:22:14.459Z,1773332534.459 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2026-03-12T16:22:14.459Z,1773332534.459 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2026-03-12T16:22:14.460Z,1773332534.460 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T16:22:14.461Z,1773332534.461 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2026-03-12T16:22:14.462Z,1773332534.462 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2026-03-12T16:22:14.462Z,1773332534.462 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2026-03-12T16:22:14.463Z,1773332534.463 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2026-03-12T16:22:14.464Z,1773332534.464 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T16:22:14.465Z,1773332534.465 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T16:22:14.465Z,1773332534.465 [keepstation:NeedComms:A] Running Loop=1
2026-03-12T16:22:14.485Z,1773332534.485 [keepstation:NeedComms:A](INFO): last time_fix was: 1773331927.000000 second since 1970/01/01T00:00:00Z
2026-03-12T16:22:14.485Z,1773332534.485 [keepstation:NeedComms:A] Stopped
2026-03-12T16:22:14.486Z,1773332534.486 [keepstation:NeedComms:setActive] Running Loop=1
2026-03-12T16:22:14.486Z,1773332534.486 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2026-03-12T16:22:14.486Z,1773332534.486 [keepstation:NeedComms:setActive:A] Running Loop=1
2026-03-12T16:22:14.741Z,1773332534.741 [keepstation:KeepStation:Dive] Stopped
2026-03-12T16:22:14.741Z,1773332534.741 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2026-03-12T16:22:14.741Z,1773332534.741 [keepstation:KeepStation:Dive:A] Stopped
2026-03-12T16:22:14.741Z,1773332534.741 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2026-03-12T16:22:14.741Z,1773332534.741 [keepstation:DiveCmd:StationKeep] Stopped
2026-03-12T16:22:14.742Z,1773332534.742 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2026-03-12T16:22:14.742Z,1773332534.742 [keepstation:DiveCmd:StationKeep:A] Stopped
2026-03-12T16:22:14.742Z,1773332534.742 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2026-03-12T16:22:14.742Z,1773332534.742 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2026-03-12T16:22:14.742Z,1773332534.742 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2026-03-12T16:22:14.745Z,1773332534.745 [keepstation:NeedComms:setActive:A] Stopped
2026-03-12T16:22:14.745Z,1773332534.745 [keepstation:NeedComms:setActive:B] Running Loop=1
2026-03-12T16:22:15.207Z,1773332535.207 [keepstation:NeedComms:setActive:B] Stopped
2026-03-12T16:22:15.208Z,1773332535.208 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2026-03-12T16:22:15.208Z,1773332535.208 [keepstation:NeedComms:setActive] Stopped
2026-03-12T16:22:15.208Z,1773332535.208 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2026-03-12T16:23:41.929Z,1773332621.929 [keepstation:NeedComms:D] Running Loop=1
2026-03-12T16:23:42.208Z,1773332622.208 [Radio_Surface](INFO): Powering up
2026-03-12T16:23:42.420Z,1773332622.420 [Waterlinked](INFO): Powering down
2026-03-12T16:23:48.092Z,1773332628.092 [DataOverHttps](INFO): Radio surface powered ON.
2026-03-12T16:24:14.697Z,1773332654.697 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-12T16:24:14.780Z,1773332654.780 [NAL9602](ERROR): received:
+CSQ:0
OK181, 2, 0, 0, 0
OK
2026-03-12T16:24:18.212Z,1773332658.212 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:24:32.473Z,1773332672.473 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:24:32.473Z,1773332672.473 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:24:48.654Z,1773332688.654 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:24:48.654Z,1773332688.654 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:24:53.352Z,1773332693.352 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:25:06.823Z,1773332706.823 [NAL9602](INFO): SBD MO Status=2, MOMSN=13181, MT Status=2, MTMSN=0
2026-03-12T16:25:06.823Z,1773332706.823 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:25:20.953Z,1773332720.953 [NAL9602](INFO): SBD MO Status=1, MOMSN=13181, MT Status=0, MTMSN=0
2026-03-12T16:25:20.953Z,1773332720.953 [NAL9602](INFO): No messages in MT queue
2026-03-12T16:25:22.166Z,1773332722.166 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162517.00,A,3647.86682,N,12150.76564,W,0.311,350.29,120326,,,D*70
2026-03-12T16:25:22.168Z,1773332722.168 [NAL9602](INFO): GPS fix at 20260312T162517: (36.797780, -121.846094)
2026-03-12T16:25:22.205Z,1773332722.205 [keepstation:NeedComms:D] Stopped
2026-03-12T16:25:22.205Z,1773332722.205 [keepstation:NeedComms:E] Running Loop=1
2026-03-12T16:25:28.500Z,1773332728.500 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:25:46.886Z,1773332746.886 [NAL9602](INFO): SBD MO Status=2, MOMSN=13182, MT Status=2, MTMSN=0
2026-03-12T16:25:46.886Z,1773332746.886 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:26:03.648Z,1773332763.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:26:07.974Z,1773332767.974 [NAL9602](INFO): SBD MO Status=1, MOMSN=13182, MT Status=0, MTMSN=0
2026-03-12T16:26:08.024Z,1773332768.024 [NAL9602](INFO): Sent 74 bytes from file Logs/20260312T154850/Courier0016.lzma
2026-03-12T16:26:08.024Z,1773332768.024 [NAL9602](INFO): Packets left to send: 0
2026-03-12T16:26:31.122Z,1773332791.122 [NAL9602](INFO): SBD MO Status=2, MOMSN=13183, MT Status=2, MTMSN=0
2026-03-12T16:26:31.122Z,1773332791.122 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:26:38.788Z,1773332798.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T16:26:55.846Z,1773332815.846 [NAL9602](INFO): SBD MO Status=2, MOMSN=13183, MT Status=2, MTMSN=0
2026-03-12T16:26:55.847Z,1773332815.847 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:27:13.450Z,1773332833.450 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003577
2026-03-12T16:27:17.314Z,1773332837.314 [NAL9602](INFO): SBD MO Status=2, MOMSN=13183, MT Status=2, MTMSN=0
2026-03-12T16:27:17.314Z,1773332837.314 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T16:27:21.323Z,1773332841.323 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20260312T154850/Express0017.lzma
2026-03-12T16:27:22.326Z,1773332842.326 [DataOverHttps](INFO): Moved sent file to Logs/20260312T154850/Express0017.lzma.bak
2026-03-12T16:27:22.326Z,1773332842.326 [DataOverHttps](INFO): SBD MOMSN=26722357
2026-03-12T16:27:23.801Z,1773332843.801 [keepstation:NeedComms:E] Stopped
2026-03-12T16:27:23.801Z,1773332843.801 [keepstation:NeedComms:F] Running Loop=1
2026-03-12T16:27:25.803Z,1773332845.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162720.00,A,3647.87303,N,12150.77108,W,0.194,280.06,120326,,,D*7A
2026-03-12T16:27:25.805Z,1773332845.805 [NAL9602](INFO): GPS fix at 20260312T162720: (36.797884, -121.846185)
2026-03-12T16:27:25.853Z,1773332845.853 [keepstation:NeedComms:F] Stopped
2026-03-12T16:27:25.853Z,1773332845.853 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2026-03-12T16:27:25.853Z,1773332845.853 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2026-03-12T16:27:25.853Z,1773332845.853 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2026-03-12T16:27:26.257Z,1773332846.257 [keepstation:NeedComms:setRestoreMode:A] Stopped
2026-03-12T16:27:26.257Z,1773332846.257 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2026-03-12T16:27:26.257Z,1773332846.257 [keepstation:NeedComms:setRestoreMode] Stopped
2026-03-12T16:27:26.257Z,1773332846.257 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2026-03-12T16:27:26.257Z,1773332846.257 [keepstation:NeedComms:setInactive] Running Loop=1
2026-03-12T16:27:26.258Z,1773332846.258 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2026-03-12T16:27:26.258Z,1773332846.258 [keepstation:NeedComms:setInactive:A] Running Loop=1
2026-03-12T16:27:26.640Z,1773332846.640 [keepstation:NeedComms:setInactive:A] Stopped
2026-03-12T16:27:26.640Z,1773332846.640 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2026-03-12T16:27:26.640Z,1773332846.640 [keepstation:NeedComms:setInactive] Stopped
2026-03-12T16:27:26.640Z,1773332846.640 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2026-03-12T16:27:26.641Z,1773332846.641 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2026-03-12T16:27:26.641Z,1773332846.641 [keepstation:NeedComms] Stopped
2026-03-12T16:27:26.641Z,1773332846.641 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2026-03-12T16:27:26.641Z,1773332846.641 [keepstation:NeedComms:C.GoToSurface] Stopped
2026-03-12T16:27:26.641Z,1773332846.641 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T16:27:27.059Z,1773332847.059 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2026-03-12T16:27:27.059Z,1773332847.059 [keepstation:SurfaceComms:B] Stopped
2026-03-12T16:27:27.063Z,1773332847.063 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2026-03-12T16:27:27.064Z,1773332847.064 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2026-03-12T16:27:27.064Z,1773332847.064 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2026-03-12T16:27:27.064Z,1773332847.064 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2026-03-12T16:27:27.064Z,1773332847.064 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T16:27:27.064Z,1773332847.064 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2026-03-12T16:27:27.433Z,1773332847.433 [keepstation:SurfaceComms:HandleNan] Stopped
2026-03-12T16:27:27.433Z,1773332847.433 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2026-03-12T16:27:27.434Z,1773332847.434 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2026-03-12T16:27:27.434Z,1773332847.434 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T16:27:27.434Z,1773332847.434 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2026-03-12T16:27:27.434Z,1773332847.434 [keepstation:SurfaceComms:setTransit] Running Loop=1
2026-03-12T16:27:27.434Z,1773332847.434 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2026-03-12T16:27:27.434Z,1773332847.434 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2026-03-12T16:27:27.845Z,1773332847.845 [keepstation:SurfaceComms:setTransit] Stopped
2026-03-12T16:27:27.845Z,1773332847.845 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2026-03-12T16:27:27.845Z,1773332847.845 [keepstation:SurfaceComms:setTransit:A] Stopped
2026-03-12T16:27:27.845Z,1773332847.845 [keepstation:SurfaceComms:setStation] Running Loop=1
2026-03-12T16:27:27.845Z,1773332847.845 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2026-03-12T16:27:27.845Z,1773332847.845 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2026-03-12T16:27:28.249Z,1773332848.249 [keepstation:SurfaceComms:setStation:A] Stopped
2026-03-12T16:27:28.249Z,1773332848.249 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2026-03-12T16:27:28.249Z,1773332848.249 [keepstation:SurfaceComms:setStation] Stopped
2026-03-12T16:27:28.249Z,1773332848.249 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2026-03-12T16:27:28.249Z,1773332848.249 [keepstation:SurfaceComms:setSink] Running Loop=1
2026-03-12T16:27:28.249Z,1773332848.249 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2026-03-12T16:27:28.249Z,1773332848.249 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2026-03-12T16:27:28.665Z,1773332848.665 [keepstation:KeepStation:Dive] Running Loop=1
2026-03-12T16:27:28.665Z,1773332848.665 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2026-03-12T16:27:28.665Z,1773332848.665 [keepstation:KeepStation:Dive:A] Running Loop=1
2026-03-12T16:27:28.665Z,1773332848.665 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2026-03-12T16:27:28.666Z,1773332848.666 [keepstation:DiveCmd:StationKeep] Running Loop=1
2026-03-12T16:27:28.666Z,1773332848.666 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2026-03-12T16:27:28.666Z,1773332848.666 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2026-03-12T16:27:28.666Z,1773332848.666 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2026-03-12T16:27:28.666Z,1773332848.666 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2026-03-12T16:27:28.666Z,1773332848.666 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2026-03-12T16:27:28.666Z,1773332848.666 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2026-03-12T16:27:28.668Z,1773332848.668 [keepstation:SurfaceComms:setSink] Stopped
2026-03-12T16:27:28.668Z,1773332848.668 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2026-03-12T16:27:28.668Z,1773332848.668 [keepstation:SurfaceComms:setSink:A] Stopped
2026-03-12T16:27:28.668Z,1773332848.668 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2026-03-12T16:27:28.668Z,1773332848.668 [keepstation:SurfaceComms] Stopped
2026-03-12T16:27:28.669Z,1773332848.669 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2026-03-12T16:27:57.717Z,1773332877.717 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T16:28:42.857Z,1773332922.857 [DataOverHttps](IMPORTANT): SBD MTMSN=20260312T162837
2026-03-12T16:28:50.203Z,1773332930.203 [DataOverHttps](INFO): Received command: schedule resume;restart logs