2025-12-11T01:15:00.400Z,1765415700.400 [CommandExec](IMPORTANT): got command configSet CBIT.navErrorThreshold 200 percent persist 2025-12-11T01:15:00.404Z,1765415700.404 [CommandExec](IMPORTANT): got command restart logs 2025-12-11T01:15:08.578Z,1765415708.578 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-12-11T01:15:08.579Z,1765415708.579 [MissionManager](INFO): Uninitializing Mission sci2_vtyoyo 2025-12-11T01:15:08.579Z,1765415708.579 [sci2_vtyoyo] Stopped 2025-12-11T01:15:08.579Z,1765415708.579 [sci2_vtyoyo](DEBUG): Aggregate::uninitialize sci2_vtyoyo 2025-12-11T01:15:08.579Z,1765415708.579 [sci2_vtyoyo:Science] Stopped 2025-12-11T01:15:08.579Z,1765415708.579 [sci2_vtyoyo:Science](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Science 2025-12-11T01:15:08.579Z,1765415708.579 [sci2_vtyoyo:Science:B] Stopped 2025-12-11T01:15:08.579Z,1765415708.579 [sci2_vtyoyo:Science:C] Stopped 2025-12-11T01:15:08.579Z,1765415708.579 [sci2_vtyoyo:Science:E] Stopped 2025-12-11T01:15:08.579Z,1765415708.579 [sci2_vtyoyo:Science:OceanCurrent] Stopped 2025-12-11T01:15:08.579Z,1765415708.579 [sci2_vtyoyo:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Science:OceanCurrent 2025-12-11T01:15:08.579Z,1765415708.579 [sci2_vtyoyo:Science:OceanCurrent:A.] Stopped 2025-12-11T01:15:08.579Z,1765415708.579 [sci2_vtyoyo:StandardEnvelopes] Stopped 2025-12-11T01:15:08.584Z,1765415708.584 [sci2_vtyoyo:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2_vtyoyo:StandardEnvelopes 2025-12-11T01:15:08.584Z,1765415708.584 [sci2_vtyoyo:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-12-11T01:15:08.584Z,1765415708.584 [sci2_vtyoyo:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-12-11T01:15:08.584Z,1765415708.584 [sci2_vtyoyo:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-12-11T01:15:08.584Z,1765415708.584 [sci2_vtyoyo:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-12-11T01:15:08.584Z,1765415708.584 [sci2_vtyoyo:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-12-11T01:15:08.584Z,1765415708.584 [sci2_vtyoyo:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-12-11T01:15:08.584Z,1765415708.584 [sci2_vtyoyo:BackseatDriver] Stopped 2025-12-11T01:15:08.584Z,1765415708.584 [sci2_vtyoyo:BackseatDriver](DEBUG): Aggregate::uninitialize sci2_vtyoyo:BackseatDriver 2025-12-11T01:15:08.585Z,1765415708.585 [sci2_vtyoyo:BackseatDriver:A.BackseatDriver] Stopped 2025-12-11T01:15:08.585Z,1765415708.585 [sci2_vtyoyo:PowerOnly] Stopped 2025-12-11T01:15:08.585Z,1765415708.585 [sci2_vtyoyo:PowerOnly](DEBUG): Aggregate::uninitialize sci2_vtyoyo:PowerOnly 2025-12-11T01:15:08.585Z,1765415708.585 [sci2_vtyoyo:PowerOnly:E.Wait] Stopped 2025-12-11T01:15:08.585Z,1765415708.585 [sci2_vtyoyo:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-12-11T01:15:08.585Z,1765415708.585 [sci2_vtyoyo:manageDiveMode] Stopped 2025-12-11T01:15:08.585Z,1765415708.585 [sci2_vtyoyo:manageDiveMode](DEBUG): Aggregate::uninitialize sci2_vtyoyo:manageDiveMode 2025-12-11T01:15:08.585Z,1765415708.585 [sci2_vtyoyo:Lap] Stopped 2025-12-11T01:15:08.585Z,1765415708.585 [sci2_vtyoyo:Lap](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap 2025-12-11T01:15:08.585Z,1765415708.585 [sci2_vtyoyo:Lap:Dive] Stopped 2025-12-11T01:15:08.585Z,1765415708.585 [sci2_vtyoyo:Lap:Dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A] Stopped 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink] Stopped 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:SurfaceSink 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A:YoYoDive] Stopped 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A:YoYoDive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive] Stopped 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:A.Pitch] Stopped 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy] Stopped 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed] Stopped 2025-12-11T01:15:08.586Z,1765415708.586 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-12-11T01:15:08.587Z,1765415708.587 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope] Stopped 2025-12-11T01:15:08.587Z,1765415708.587 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2025-12-11T01:15:08.587Z,1765415708.587 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2025-12-11T01:15:08.587Z,1765415708.587 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-12-11T01:15:08.587Z,1765415708.587 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor] Stopped 2025-12-11T01:15:08.587Z,1765415708.587 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor](DEBUG): Uninitialize Value-Terminated YoYo. 2025-12-11T01:15:08.600Z,1765415708.600 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo] Stopped 2025-12-11T01:15:08.600Z,1765415708.600 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo](DEBUG): Uninitialize YoYoComponent. 2025-12-11T01:15:08.600Z,1765415708.600 [sci2_vtyoyo:Lap:Wpt4] Stopped 2025-12-11T01:15:08.600Z,1765415708.600 [sci2_vtyoyo:Lap:Wpt4](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Wpt4 2025-12-11T01:15:08.600Z,1765415708.600 [sci2_vtyoyo:Lap:Wpt4:A.Waypoint] Stopped 2025-12-11T01:15:08.600Z,1765415708.600 [sci2_vtyoyo:Lap:Wpt4:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-12-11T01:15:08.853Z,1765415708.853 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-12-11T01:15:08.853Z,1765415708.853 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-12-11T01:15:08.862Z,1765415708.862 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-12-11T01:15:08.880Z,1765415708.880 [WetLabsBB2FL](INFO): Powering down 2025-12-11T01:15:09.190Z,1765415709.190 [BackseatComponent](INFO): Sent LCM shutdown request. 2025-12-11T01:15:09.190Z,1765415709.190 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.). 2025-12-11T01:15:11.310Z,1765415711.310 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into: 4 45 NaN NaN 30 15 4 0.75 200 10 10 45 2 0 0 1 2 3 NaN 0 Sinking to Timed out while trying to reach target sink depth at current depth of 2025-12-11T01:15:11.318Z,1765415711.318 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-12-11T01:15:11.321Z,1765415711.321 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-12-11T01:15:11.323Z,1765415711.323 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-12-11T01:15:11.345Z,1765415711.345 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-12-11T01:15:11.347Z,1765415711.347 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-12-11T01:15:11.350Z,1765415711.350 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-12-11T01:15:11.352Z,1765415711.352 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-12-11T01:15:11.354Z,1765415711.354 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-12-11T01:15:11.356Z,1765415711.356 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-12-11T01:15:11.358Z,1765415711.358 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool 2025-12-11T01:15:11.360Z,1765415711.360 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m 2025-12-11T01:15:11.362Z,1765415711.362 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min 2025-12-11T01:15:11.365Z,1765415711.365 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-12-11T01:15:11.367Z,1765415711.367 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-12-11T01:15:11.420Z,1765415711.420 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count 2025-12-11T01:15:11.422Z,1765415711.422 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count 2025-12-11T01:15:11.431Z,1765415711.431 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count 2025-12-11T01:15:11.450Z,1765415711.450 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count 2025-12-11T01:15:11.464Z,1765415711.464 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count 2025-12-11T01:15:11.466Z,1765415711.466 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool 2025-12-11T01:15:11.467Z,1765415711.467 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-12-11T01:15:11.467Z,1765415711.467 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-12-11T01:15:11.473Z,1765415711.473 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-12-11T01:15:11.841Z,1765415711.841 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1500 7 30 0 0 0 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2025-12-11T01:15:11.847Z,1765415711.847 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-12-11T01:15:11.849Z,1765415711.849 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-12-11T01:15:11.852Z,1765415711.852 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-12-11T01:15:11.855Z,1765415711.855 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-12-11T01:15:11.881Z,1765415711.881 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-12-11T01:15:11.883Z,1765415711.883 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1500.000000 s 2025-12-11T01:15:11.890Z,1765415711.890 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-12-11T01:15:11.898Z,1765415711.898 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-12-11T01:15:11.905Z,1765415711.905 [MissionManager](INFO): DefineArg keepstation:NeedComms.DoingCommsMode = 0.000000 count 2025-12-11T01:15:11.907Z,1765415711.907 [MissionManager](INFO): DefineArg keepstation:NeedComms.RestoreMode = 0.000000 count 2025-12-11T01:15:11.914Z,1765415711.914 [MissionManager](INFO): DefineOutput keepstation:NeedComms.IsActive = 0 bool 2025-12-11T01:15:11.920Z,1765415711.920 [MissionManager](INFO): DefineOutput keepstation:NeedComms.RequiredDiveMode = 0.000000 count 2025-12-11T01:15:11.952Z,1765415711.952 [keepstation:NeedComms:C.GoToSurface](DEBUG): Construct GoToSurface. 2025-12-11T01:15:12.012Z,1765415712.012 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-12-11T01:15:12.012Z,1765415712.012 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-12-11T01:15:12.013Z,1765415712.013 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-12-11T01:15:12.083Z,1765415712.083 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2025-12-11T01:15:12.087Z,1765415712.087 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-12-11T01:15:12.089Z,1765415712.089 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2025-12-11T01:15:12.091Z,1765415712.091 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-12-11T01:15:12.094Z,1765415712.094 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-12-11T01:15:12.095Z,1765415712.095 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-12-11T01:15:12.100Z,1765415712.100 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-12-11T01:15:12.106Z,1765415712.106 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-12-11T01:15:12.114Z,1765415712.114 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-12-11T01:15:12.114Z,1765415712.114 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-12-11T01:15:12.115Z,1765415712.115 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-12-11T01:15:12.167Z,1765415712.167 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-12-11T01:15:12.170Z,1765415712.170 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-12-11T01:15:12.209Z,1765415712.209 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-12-11T01:15:12.211Z,1765415712.211 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-12-11T01:15:12.211Z,1765415712.211 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-12-11T01:15:12.212Z,1765415712.212 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-12-11T01:15:12.370Z,1765415712.370 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-12-11T01:15:12.374Z,1765415712.374 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-12-11T01:15:12.375Z,1765415712.375 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-12-11T01:15:12.377Z,1765415712.377 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-12-11T01:15:12.379Z,1765415712.379 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-12-11T01:15:12.402Z,1765415712.402 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool 2025-12-11T01:15:12.414Z,1765415712.414 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-12-11T01:15:12.416Z,1765415712.416 [keepstation:H.Pitch](DEBUG): Construct. 2025-12-11T01:15:12.448Z,1765415712.448 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-12-11T01:15:12.451Z,1765415712.451 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct. 2025-12-11T01:15:12.455Z,1765415712.455 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct. 2025-12-11T01:15:12.467Z,1765415712.467 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-12-11T01:15:12.470Z,1765415712.470 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct. 2025-12-11T01:15:12.474Z,1765415712.474 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct. 2025-12-11T01:15:12.479Z,1765415712.479 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct. 2025-12-11T01:15:12.483Z,1765415712.483 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation. 2025-12-11T01:15:12.502Z,1765415712.502 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-12-11T01:15:12.513Z,1765415712.513 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait. 2025-12-11T01:15:12.530Z,1765415712.530 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission keepstation { """ Vehicle transits to desired waypoint (or stays put if none is commanded) and stays within the specified radius. """ arguments { MissionTimeout = 4 hour """ Maximum duration of mission """ NeedCommsTime = 45 minute """ How often to surface for communications """ Latitude = NaN degree """ Latitude of waypoint to hold. If NaN, hold the latitude at start of mission. """ Longitude = NaN degree """ Longitude of waypoint to hold. If NaN, hold the longitude at start of mission. """ Depth = 30 meter """ Depth held during drift mode """ ApproachDepth = 15 meter """ Depth for initial approach to station. """ DepthDeadband = 4 meter """ How much vertical drift from the specified depth is allowed in drift mode """ Speed = 0.75 meter_per_second """ Vehicle transit speed. """ Radius = 200 meter """ Radius of circle around waypoint to hold. """ SinkOffSurface = false """ If true, use buoyancy only to decend to SinkDepth before driving to first target depth """ SinkDepth = 10 meter """ Initial depth to decend using only buoyancy before driving """ SinkDepthTimeout = 10 minute """ Max wait time for the vehicle to reach the target depth """ MaxDepth = 45 meter """ Maximum allowable depth during the mission. """ MinOffshore = 2 kilometer """ Minimum distance from the shoreline to maintain """ } output { DiveMode = 0 count """ Mission variable (don't change). The mission sets this variable to switch between dive modes. Initialized to DoingComms. """ DoingComms = 0 count """ Mission variable (don't change). The mission will run in this mode when doing surface comms. """ SurfaceSink = 1 count """ Mission variable (don't change). The mission will run in this mode when sinking from the surface using only buoyancy. """ TransitToStation = 2 count """ Mission variable (don't change). The mission will run in this mode when performing the transit to station """ StationKeep = 3 count """ Mission variable (don't change). The mission will run in this mode when keeping station """ OnStation = false """ Mission variable (don't change). Determines last mode (Transit or StationKeep) to resume after needcomms """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Pitch { run in parallel set massPosition = Control:VerticalControl.massDefault } aggregate SurfaceComms { run when ( called or ( elapsed ( Universal:time_fix ) > NeedCommsTime ) ) assign in sequence DiveMode = DoingComms call priorityHere=false refId="NeedComms" aggregate HandleNan { run in sequence break if ( not isNaN ( Latitude ) and not isNaN ( Longitude ) ) # If both lat and lon are nan, we are implicitly on station and should skip initial transit, so force OnStation to true aggregate SetOnStation { run in sequence break if ( not isNaN ( Latitude ) or not isNaN ( Longitude ) ) assign in sequence OnStation = true } # The "assigns" here mean we should only ever run these aggregates once # An initial NaN lat/lon should be set here during the initial surface NeedComms call # Use the most recent fix -- if there isn't one, the keepstation behavior will use Universal:lat/lon aggregate SetLat { run in sequence break if ( not isNaN ( Latitude ) ) assign in sequence Latitude = Universal:latitude_fix } aggregate SetLon { run in sequence break if ( not isNaN ( Longitude ) ) assign in sequence Longitude = Universal:longitude_fix } } aggregate setTransit { run in sequence break if ( SinkOffSurface or OnStation ) assign in sequence DiveMode = TransitToStation } aggregate setStation { run in sequence break if ( SinkOffSurface or not OnStation ) assign in sequence DiveMode = StationKeep } aggregate setSink { run in sequence break if ( not SinkOffSurface ) assign in sequence DiveMode = SurfaceSink } } call id="StartingMission" priorityHere=false refId="SurfaceComms" aggregate DiveCmd { run when ( called ) aggregate SurfaceSink { run while ( DiveMode == SurfaceSink ) aggregate sink { run in sequence behavior Guidance:Buoyancy id="BuoyancyHold" { run in parallel set position = NaN cc } behavior Guidance:SetSpeed { run in parallel set speed = 0 m/s } syslog info "Sinking to " + SinkDepth~meter behavior Guidance:Pitch { run in sequence timeout duration=SinkDepthTimeout { syslog important "Timed out while trying to reach target sink depth at current depth of " + Universal:depth~meter } set depth = SinkDepth } # Once we've sunk, either resume transit or station keeping depths and speeds aggregate sinkToTransit { run in sequence break if ( OnStation ) assign in sequence DiveMode = TransitToStation } aggregate sinkToStation { run in sequence break if ( not OnStation ) assign in sequence DiveMode = StationKeep } } } aggregate TransitToStation { run while ( DiveMode == TransitToStation ) aggregate dive { run in sequence behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch { run in parallel set depth = ApproachDepth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } } } aggregate StationKeep { run while ( DiveMode == StationKeep ) assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:KeepStation { run in parallel set latitude = Latitude set longitude = Longitude set radius = Radius set speed = Speed } } } aggregate TransitToStation { """ Need a separate aggregate for transit if we want to specify a different depth for the approach. """ run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude } assign in sequence DiveMode = StationKeep assign in sequence OnStation = true } call id="OnStation" priorityHere=false refId="NeedComms" aggregate KeepStation { run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Wait { """ Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. """ run in sequence set duration = MissionTimeout } } } 2025-12-11T01:15:12.531Z,1765415712.531 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-12-11T01:15:12.627Z,1765415712.627 [MissionManager](IMPORTANT): Started mission Default 2025-12-11T01:15:12.627Z,1765415712.627 [Default] Running Loop=1 2025-12-11T01:15:12.627Z,1765415712.627 [Default](DEBUG): Aggregate::initialize Default 2025-12-11T01:15:12.627Z,1765415712.627 [Default:B.GoToSurface] Running Loop=1 2025-12-11T01:15:12.627Z,1765415712.627 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-11T01:15:12.632Z,1765415712.632 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-12-11T01:15:12.632Z,1765415712.632 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-12-11T01:15:12.632Z,1765415712.632 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-12-11T01:15:12.633Z,1765415712.633 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-12-11T01:15:12.633Z,1765415712.633 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-11T01:15:12.633Z,1765415712.633 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-11T01:15:12.634Z,1765415712.634 [Default:A.Wait] Running Loop=1 2025-12-11T01:15:12.634Z,1765415712.634 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-12-11T01:15:13.416Z,1765415713.416 [Radio_Surface](INFO): Powering up 2025-12-11T01:15:19.148Z,1765415719.148 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-11T01:15:25.734Z,1765415725.734 [Default:A.Wait](INFO): Done Waiting. 2025-12-11T01:15:25.734Z,1765415725.734 [Default:A.Wait] Stopped 2025-12-11T01:15:25.734Z,1765415725.734 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-12-11T01:15:27.208Z,1765415727.208 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2025-12-11T01:15:27.209Z,1765415727.209 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2025-12-11T01:15:27.209Z,1765415727.209 [LcmPublisher](INFO): Deactivating messaging. 2025-12-11T01:15:31.438Z,1765415731.438 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 14 hour 2025-12-11T01:15:31.439Z,1765415731.439 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 90 minute 2025-12-11T01:15:31.439Z,1765415731.439 [CommandExec](IMPORTANT): got command set keepstation.Depth 75 meter 2025-12-11T01:15:31.440Z,1765415731.440 [CommandExec](IMPORTANT): got command set keepstation.Speed 1 meter_per_second 2025-12-11T01:15:31.441Z,1765415731.441 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool 2025-12-11T01:15:38.835Z,1765415738.835 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.17 mm. 2025-12-11T01:15:38.840Z,1765415738.840 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm. 2025-12-11T01:15:38.841Z,1765415738.841 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.70 m/s, pitch -20.00 deg, mass-position 31.17 mm. 2025-12-11T01:15:38.850Z,1765415738.850 [Default:CheckIn] Running Loop=1 2025-12-11T01:15:38.850Z,1765415738.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-11T01:15:38.850Z,1765415738.850 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-11T01:15:46.639Z,1765415746.639 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 45 meter 2025-12-11T01:15:46.640Z,1765415746.640 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second 2025-12-11T01:15:46.641Z,1765415746.641 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 30 minute 2025-12-11T01:15:46.642Z,1765415746.642 [CommandExec](IMPORTANT): got command run 2025-12-11T01:15:46.645Z,1765415746.645 [CommandExec](IMPORTANT): Running 2025-12-11T01:15:47.017Z,1765415747.017 [Default] Stopped 2025-12-11T01:15:47.017Z,1765415747.017 [Default](DEBUG): Aggregate::uninitialize Default 2025-12-11T01:15:47.017Z,1765415747.017 [Default:B.GoToSurface] Stopped 2025-12-11T01:15:47.017Z,1765415747.017 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-11T01:15:47.017Z,1765415747.017 [Default:CheckIn] Stopped 2025-12-11T01:15:47.017Z,1765415747.017 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-11T01:15:47.017Z,1765415747.017 [Default:CheckIn:Read_GPS] Stopped 2025-12-11T01:15:47.018Z,1765415747.018 [MissionManager](IMPORTANT): Started mission keepstation 2025-12-11T01:15:47.018Z,1765415747.018 [keepstation] Running Loop=1 2025-12-11T01:15:47.018Z,1765415747.018 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-12-11T01:15:47.018Z,1765415747.018 [keepstation:StandardEnvelopes] Running Loop=1 2025-12-11T01:15:47.018Z,1765415747.018 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-12-11T01:15:47.018Z,1765415747.018 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-12-11T01:15:47.018Z,1765415747.018 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-12-11T01:15:47.018Z,1765415747.018 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-12-11T01:15:47.019Z,1765415747.019 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-12-11T01:15:47.019Z,1765415747.019 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-12-11T01:15:47.019Z,1765415747.019 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-12-11T01:15:47.019Z,1765415747.019 [keepstation:BackseatDriver] Running Loop=1 2025-12-11T01:15:47.019Z,1765415747.019 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-12-11T01:15:47.019Z,1765415747.019 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-12-11T01:15:47.019Z,1765415747.019 [keepstation:PowerOnly] Running Loop=1 2025-12-11T01:15:47.019Z,1765415747.019 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-12-11T01:15:47.019Z,1765415747.019 [keepstation:PowerOnly:A] Running Loop=1 2025-12-11T01:15:47.019Z,1765415747.019 [keepstation:PowerOnly:B] Running Loop=1 2025-12-11T01:15:47.019Z,1765415747.019 [keepstation:PowerOnly:C] Running Loop=1 2025-12-11T01:15:47.020Z,1765415747.020 [keepstation:PowerOnly:D] Running Loop=1 2025-12-11T01:15:47.020Z,1765415747.020 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-12-11T01:15:47.020Z,1765415747.020 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-12-11T01:15:47.020Z,1765415747.020 [keepstation:H.Pitch] Running Loop=1 2025-12-11T01:15:47.020Z,1765415747.020 [keepstation:H.Pitch](DEBUG): Initialize. 2025-12-11T01:15:47.020Z,1765415747.020 [keepstation:B] Running Loop=1 2025-12-11T01:15:47.021Z,1765415747.021 [keepstation:H.Pitch] Running Loop=1 2025-12-11T01:15:47.021Z,1765415747.021 [keepstation:PowerOnly] Running Loop=1 2025-12-11T01:15:47.022Z,1765415747.022 [keepstation:PowerOnly:D] Stopped 2025-12-11T01:15:47.022Z,1765415747.022 [keepstation:PowerOnly:C] Stopped 2025-12-11T01:15:47.022Z,1765415747.022 [keepstation:PowerOnly:B] Stopped 2025-12-11T01:15:47.022Z,1765415747.022 [keepstation:PowerOnly:A] Stopped 2025-12-11T01:15:47.022Z,1765415747.022 [keepstation:BackseatDriver] Running Loop=1 2025-12-11T01:15:47.022Z,1765415747.022 [keepstation:StandardEnvelopes] Running Loop=1 2025-12-11T01:15:47.022Z,1765415747.022 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-12-11T01:15:47.023Z,1765415747.023 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-12-11T01:15:47.023Z,1765415747.023 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-12-11T01:15:47.023Z,1765415747.023 [keepstation:B] Stopped 2025-12-11T01:15:47.023Z,1765415747.023 [keepstation:D] Running Loop=1 2025-12-11T01:15:47.407Z,1765415747.407 [keepstation:D] Stopped 2025-12-11T01:15:47.407Z,1765415747.407 [keepstation:E] Running Loop=1 2025-12-11T01:15:47.834Z,1765415747.834 [keepstation:E] Stopped 2025-12-11T01:15:47.834Z,1765415747.834 [keepstation:StartingMission] Running Loop=1 2025-12-11T01:15:47.834Z,1765415747.834 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-12-11T01:15:48.203Z,1765415748.203 [keepstation:SurfaceComms] Running Loop=1 2025-12-11T01:15:48.203Z,1765415748.203 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-11T01:15:48.204Z,1765415748.204 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-11T01:15:48.204Z,1765415748.204 [keepstation:SurfaceComms:A] Stopped 2025-12-11T01:15:48.204Z,1765415748.204 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-11T01:15:48.204Z,1765415748.204 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-11T01:15:48.615Z,1765415748.615 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2025-12-11T01:15:48.615Z,1765415748.615 [keepstation:NeedComms] Running Loop=1 2025-12-11T01:15:48.615Z,1765415748.615 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-11T01:15:48.615Z,1765415748.615 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-11T01:15:48.615Z,1765415748.615 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-11T01:15:48.615Z,1765415748.615 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-11T01:15:48.616Z,1765415748.616 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-11T01:15:48.616Z,1765415748.616 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-11T01:15:48.617Z,1765415748.617 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-11T01:15:48.617Z,1765415748.617 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-11T01:15:48.617Z,1765415748.617 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-11T01:15:48.618Z,1765415748.618 [keepstation:NeedComms:A] Running Loop=1 2025-12-11T01:15:48.619Z,1765415748.619 [keepstation:NeedComms:A](INFO): last time_fix was: 1765413437.000000 second since 1970/01/01T00:00:00Z 2025-12-11T01:15:48.619Z,1765415748.619 [keepstation:NeedComms:A] Stopped 2025-12-11T01:15:48.619Z,1765415748.619 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-11T01:15:48.619Z,1765415748.619 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-11T01:15:48.619Z,1765415748.619 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-11T01:15:49.015Z,1765415749.015 [keepstation:NeedComms:setActive:A] Stopped 2025-12-11T01:15:49.015Z,1765415749.015 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-11T01:15:49.277Z,1765415749.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:15:49.420Z,1765415749.420 [keepstation:NeedComms:setActive:B] Stopped 2025-12-11T01:15:49.420Z,1765415749.420 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-11T01:15:49.420Z,1765415749.420 [keepstation:NeedComms:setActive] Stopped 2025-12-11T01:15:49.420Z,1765415749.420 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-11T01:15:49.825Z,1765415749.825 [keepstation:NeedComms:D] Running Loop=1 2025-12-11T01:15:50.233Z,1765415750.233 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-12-11T01:16:11.623Z,1765415771.623 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-11T01:16:11.700Z,1765415771.700 [NAL9602](ERROR): received: +CSQ:0 OK413, 2, 0, 0, 0 OK 2025-12-11T01:16:20.416Z,1765415780.416 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:16:51.544Z,1765415811.544 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:17:22.672Z,1765415842.672 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:18:25.456Z,1765415905.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:18:56.604Z,1765415936.604 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:19:27.736Z,1765415967.736 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:19:58.880Z,1765415998.880 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:20:30.020Z,1765416030.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:20:43.516Z,1765416043.516 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-11T01:20:54.024Z,1765416054.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012052.00,A,3643.54705,N,12210.74455,W,2.138,128.15,111225,,,A*7D 2025-12-11T01:20:54.026Z,1765416054.026 [NAL9602](INFO): GPS fix at 20251211T012052: (36.725784, -122.179076) 2025-12-11T01:20:54.038Z,1765416054.038 [UniversalFixResidualReporter](INFO): Fix residual: 7.0 %DT, over the last 1153.7 m. Residual distance 80.6 m at bearing -31.7 degrees. Fix at (36.7258, -122.1791) with 1062.3 m made good. 2025-12-11T01:20:54.071Z,1765416054.071 [keepstation:NeedComms:D] Stopped 2025-12-11T01:20:54.076Z,1765416054.076 [keepstation:NeedComms:E] Running Loop=1 2025-12-11T01:20:54.458Z,1765416054.458 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-12-11T01:21:01.160Z,1765416061.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:21:24.730Z,1765416084.730 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2025-12-11T01:21:24.804Z,1765416084.804 [NAL9602](ERROR): received: +CSQ:1 OK413, 2, 0, 0, 0 OK 2025-12-11T01:21:32.292Z,1765416092.292 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:21:48.640Z,1765416108.640 [NAL9602](INFO): SBD MO Status=2, MOMSN=24413, MT Status=2, MTMSN=0 2025-12-11T01:21:48.641Z,1765416108.641 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T01:22:03.416Z,1765416123.416 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:22:31.509Z,1765416151.509 [NAL9602](INFO): SBD MO Status=1, MOMSN=24413, MT Status=0, MTMSN=0 2025-12-11T01:22:31.572Z,1765416151.572 [NAL9602](INFO): Sent 91 bytes from file Logs/20251210T143000/Courier0024.lzma 2025-12-11T01:22:31.572Z,1765416151.572 [NAL9602](INFO): Packets left to send: 0 2025-12-11T01:22:34.556Z,1765416154.556 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:22:48.938Z,1765416168.938 [NAL9602](INFO): SBD MO Status=2, MOMSN=24414, MT Status=2, MTMSN=0 2025-12-11T01:22:48.938Z,1765416168.938 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T01:23:05.680Z,1765416185.680 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:23:36.812Z,1765416216.812 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:23:54.463Z,1765416234.463 [NAL9602](INFO): SBD MO Status=1, MOMSN=24414, MT Status=0, MTMSN=0 2025-12-11T01:23:54.520Z,1765416234.520 [NAL9602](INFO): Sent 329 bytes from file Logs/20251211T011500/Courier0000.lzma 2025-12-11T01:23:54.520Z,1765416234.520 [NAL9602](INFO): Packets left to send: 0 2025-12-11T01:24:03.393Z,1765416243.393 [NAL9602](INFO): SBD MO Status=1, MOMSN=24415, MT Status=0, MTMSN=0 2025-12-11T01:24:03.456Z,1765416243.456 [NAL9602](INFO): Sent 332 bytes from file Logs/20251210T143000/Express0025.lzma 2025-12-11T01:24:03.456Z,1765416243.456 [NAL9602](INFO): Packets left to send: 2 2025-12-11T01:24:07.936Z,1765416247.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:24:24.857Z,1765416264.857 [NAL9602](INFO): SBD MO Status=2, MOMSN=24416, MT Status=2, MTMSN=0 2025-12-11T01:24:24.857Z,1765416264.857 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T01:24:39.068Z,1765416279.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:24:47.944Z,1765416287.944 [NAL9602](INFO): SBD MO Status=2, MOMSN=24416, MT Status=2, MTMSN=0 2025-12-11T01:24:47.945Z,1765416287.945 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T01:25:05.799Z,1765416305.799 [NAL9602](INFO): SBD MO Status=1, MOMSN=24416, MT Status=0, MTMSN=0 2025-12-11T01:25:05.848Z,1765416305.848 [NAL9602](INFO): Sent 332 bytes from file Logs/20251210T143000/Express0025.lzma 2025-12-11T01:25:05.848Z,1765416305.848 [NAL9602](INFO): Packets left to send: 1 2025-12-11T01:25:07.668Z,1765416307.668 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002904 2025-12-11T01:25:15.185Z,1765416315.185 [DataOverHttps](INFO): Sending 725 bytes from file Logs/20251210T143000/Express0025.lzma 2025-12-11T01:25:16.186Z,1765416316.186 [DataOverHttps](INFO): Moved sent file to Logs/20251210T143000/Express0025.lzma.bak 2025-12-11T01:25:16.186Z,1765416316.186 [DataOverHttps](INFO): SBD MOMSN=26486112 2025-12-11T01:25:25.755Z,1765416325.755 [NAL9602](INFO): SBD MO Status=2, MOMSN=24417, MT Status=2, MTMSN=0 2025-12-11T01:25:25.776Z,1765416325.776 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T01:25:32.117Z,1765416332.117 [DataOverHttps](INFO): Sending 1050 bytes from file Logs/20251211T011500/Express0001.lzma 2025-12-11T01:25:33.118Z,1765416333.118 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Express0001.lzma.bak 2025-12-11T01:25:33.118Z,1765416333.118 [DataOverHttps](INFO): SBD MOMSN=26486117 2025-12-11T01:25:34.542Z,1765416334.542 [keepstation:NeedComms:E] Stopped 2025-12-11T01:25:34.543Z,1765416334.543 [keepstation:NeedComms:F] Running Loop=1 2025-12-11T01:25:34.957Z,1765416334.957 [keepstation:NeedComms:F](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-12-11T01:25:36.550Z,1765416336.550 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012534.00,A,3643.53416,N,12210.73009,W,0.603,139.23,111225,,,A*7C 2025-12-11T01:25:36.557Z,1765416336.557 [NAL9602](INFO): GPS fix at 20251211T012534: (36.725569, -122.178835) 2025-12-11T01:25:36.597Z,1765416336.597 [keepstation:NeedComms:F] Stopped 2025-12-11T01:25:36.597Z,1765416336.597 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-11T01:25:36.598Z,1765416336.598 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-11T01:25:36.598Z,1765416336.598 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-11T01:25:37.007Z,1765416337.007 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-11T01:25:37.012Z,1765416337.012 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-11T01:25:37.012Z,1765416337.012 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-11T01:25:37.012Z,1765416337.012 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-11T01:25:37.012Z,1765416337.012 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-11T01:25:37.012Z,1765416337.012 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-11T01:25:37.012Z,1765416337.012 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-11T01:25:37.375Z,1765416337.375 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-11T01:25:37.375Z,1765416337.375 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-11T01:25:37.380Z,1765416337.380 [keepstation:NeedComms:setInactive] Stopped 2025-12-11T01:25:37.380Z,1765416337.380 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-11T01:25:37.381Z,1765416337.381 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-11T01:25:37.381Z,1765416337.381 [keepstation:NeedComms] Stopped 2025-12-11T01:25:37.381Z,1765416337.381 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-11T01:25:37.381Z,1765416337.381 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-11T01:25:37.381Z,1765416337.381 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-11T01:25:37.816Z,1765416337.816 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-11T01:25:37.816Z,1765416337.816 [keepstation:SurfaceComms:B] Stopped 2025-12-11T01:25:37.816Z,1765416337.816 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-11T01:25:37.816Z,1765416337.816 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-11T01:25:37.816Z,1765416337.816 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-11T01:25:37.817Z,1765416337.817 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-11T01:25:37.817Z,1765416337.817 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T01:25:37.817Z,1765416337.817 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-11T01:25:38.184Z,1765416338.184 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-11T01:25:38.184Z,1765416338.184 [keepstation:SurfaceComms:HandleNan:SetOnStation](INFO): Completed keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T01:25:38.184Z,1765416338.184 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-11T01:25:38.184Z,1765416338.184 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T01:25:38.184Z,1765416338.184 [keepstation:SurfaceComms:HandleNan:SetLat] Running Loop=1 2025-12-11T01:25:38.185Z,1765416338.185 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLat 2025-12-11T01:25:38.185Z,1765416338.185 [keepstation:SurfaceComms:HandleNan:SetLat:A] Running Loop=1 2025-12-11T01:25:38.584Z,1765416338.584 [keepstation:SurfaceComms:HandleNan:SetLat:A] Stopped 2025-12-11T01:25:38.584Z,1765416338.584 [keepstation:SurfaceComms:HandleNan:SetLat](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLat 2025-12-11T01:25:38.584Z,1765416338.584 [keepstation:SurfaceComms:HandleNan:SetLat] Stopped 2025-12-11T01:25:38.584Z,1765416338.584 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLat 2025-12-11T01:25:38.585Z,1765416338.585 [keepstation:SurfaceComms:HandleNan:SetLon] Running Loop=1 2025-12-11T01:25:38.585Z,1765416338.585 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLon 2025-12-11T01:25:38.585Z,1765416338.585 [keepstation:SurfaceComms:HandleNan:SetLon:A] Running Loop=1 2025-12-11T01:25:38.997Z,1765416338.997 [keepstation:SurfaceComms:HandleNan:SetLon:A] Stopped 2025-12-11T01:25:38.997Z,1765416338.997 [keepstation:SurfaceComms:HandleNan:SetLon](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLon 2025-12-11T01:25:38.997Z,1765416338.997 [keepstation:SurfaceComms:HandleNan:SetLon] Stopped 2025-12-11T01:25:38.997Z,1765416338.997 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLon 2025-12-11T01:25:38.997Z,1765416338.997 [keepstation:SurfaceComms:HandleNan](INFO): Completed keepstation:SurfaceComms:HandleNan 2025-12-11T01:25:38.997Z,1765416338.997 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-11T01:25:38.997Z,1765416338.997 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-11T01:25:38.997Z,1765416338.997 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-11T01:25:38.998Z,1765416338.998 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-11T01:25:38.998Z,1765416338.998 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-11T01:25:39.556Z,1765416339.556 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-11T01:25:39.556Z,1765416339.556 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-11T01:25:39.556Z,1765416339.556 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-11T01:25:39.556Z,1765416339.556 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-11T01:25:39.557Z,1765416339.557 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-11T01:25:39.557Z,1765416339.557 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-11T01:25:39.832Z,1765416339.832 [keepstation:SurfaceComms:setStation] Stopped 2025-12-11T01:25:39.832Z,1765416339.832 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-11T01:25:39.832Z,1765416339.832 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-11T01:25:39.833Z,1765416339.833 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-11T01:25:39.833Z,1765416339.833 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-11T01:25:39.833Z,1765416339.833 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-11T01:25:40.203Z,1765416340.203 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-11T01:25:40.204Z,1765416340.204 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-11T01:25:40.204Z,1765416340.204 [keepstation:SurfaceComms:setSink] Stopped 2025-12-11T01:25:40.204Z,1765416340.204 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-11T01:25:40.204Z,1765416340.204 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-11T01:25:40.204Z,1765416340.204 [keepstation:SurfaceComms] Stopped 2025-12-11T01:25:40.204Z,1765416340.204 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-11T01:25:40.608Z,1765416340.608 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-12-11T01:25:40.608Z,1765416340.608 [keepstation:StartingMission] Stopped 2025-12-11T01:25:40.608Z,1765416340.608 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-12-11T01:25:40.609Z,1765416340.609 [keepstation:TransitToStation] Running Loop=1 2025-12-11T01:25:40.609Z,1765416340.609 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-12-11T01:25:40.609Z,1765416340.609 [keepstation:TransitToStation:Dive] Running Loop=1 2025-12-11T01:25:40.609Z,1765416340.609 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-12-11T01:25:40.609Z,1765416340.609 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-12-11T01:25:40.609Z,1765416340.609 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-12-11T01:25:40.609Z,1765416340.609 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-12-11T01:25:40.610Z,1765416340.610 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-12-11T01:25:40.610Z,1765416340.610 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.725569,-122.178835 2025-12-11T01:25:40.995Z,1765416340.995 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.725569,-122.178835 2025-12-11T01:25:40.996Z,1765416340.996 [keepstation:TransitToStation:Dive] Running Loop=1 2025-12-11T01:25:40.996Z,1765416340.996 [keepstation:DiveCmd] Running Loop=1 2025-12-11T01:25:40.996Z,1765416340.996 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2025-12-11T01:25:40.996Z,1765416340.996 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-11T01:25:40.996Z,1765416340.996 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-11T01:25:40.996Z,1765416340.996 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-11T01:25:40.996Z,1765416340.996 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T01:25:40.997Z,1765416340.997 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-11T01:25:40.997Z,1765416340.997 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-11T01:25:40.997Z,1765416340.997 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-11T01:25:40.997Z,1765416340.997 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-11T01:25:40.997Z,1765416340.997 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-11T01:25:40.997Z,1765416340.997 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-12-11T01:25:40.997Z,1765416340.997 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-12-11T01:25:40.997Z,1765416340.997 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-12-11T01:25:40.997Z,1765416340.997 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-12-11T01:25:40.997Z,1765416340.997 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-12-11T01:25:40.997Z,1765416340.997 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-11T01:25:40.998Z,1765416340.998 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-12-11T01:25:40.998Z,1765416340.998 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-12-11T01:25:40.998Z,1765416340.998 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-12-11T01:25:40.998Z,1765416340.998 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-12-11T01:25:40.998Z,1765416340.998 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-11T01:25:40.998Z,1765416340.998 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-11T01:25:40.998Z,1765416340.998 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-11T01:25:40.998Z,1765416340.998 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-11T01:25:40.998Z,1765416340.998 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-11T01:25:40.998Z,1765416340.998 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-11T01:25:40.999Z,1765416340.999 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-11T01:25:40.999Z,1765416340.999 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-11T01:25:40.999Z,1765416340.999 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-11T01:25:40.999Z,1765416340.999 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-11T01:25:40.999Z,1765416340.999 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-11T01:25:40.999Z,1765416340.999 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-11T01:25:41.012Z,1765416341.012 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-11T01:25:41.012Z,1765416341.012 [keepstation:DiveCmd:TransitToStation] Stopped 2025-12-11T01:25:41.012Z,1765416341.012 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-12-11T01:25:41.012Z,1765416341.012 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-12-11T01:25:41.012Z,1765416341.012 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-12-11T01:25:41.012Z,1765416341.012 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-12-11T01:25:41.012Z,1765416341.012 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-11T01:25:41.012Z,1765416341.012 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-12-11T01:25:41.012Z,1765416341.012 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-12-11T01:25:41.013Z,1765416341.013 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-12-11T01:25:41.013Z,1765416341.013 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-11T01:25:41.013Z,1765416341.013 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-11T01:25:41.013Z,1765416341.013 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-11T01:25:41.013Z,1765416341.013 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-11T01:25:41.014Z,1765416341.014 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-11T01:25:41.014Z,1765416341.014 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-11T01:25:41.014Z,1765416341.014 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-11T01:26:08.463Z,1765416368.463 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T01:27:37.888Z,1765416457.888 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:27:55.412Z,1765416475.412 [Radio_Surface](INFO): Powering down 2025-12-11T01:28:09.012Z,1765416489.012 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T01:28:10.017Z,1765416490.017 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-11T01:29:07.445Z,1765416547.445 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-11T01:29:07.445Z,1765416547.445 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-11T01:29:07.445Z,1765416547.445 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T01:29:07.446Z,1765416547.446 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-11T01:29:07.853Z,1765416547.853 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-11T01:29:07.853Z,1765416547.853 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T01:29:07.854Z,1765416547.853 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-11T01:29:07.854Z,1765416547.854 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-11T01:29:07.854Z,1765416547.854 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T01:29:07.854Z,1765416547.854 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-11T01:29:08.270Z,1765416548.270 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-11T01:29:08.270Z,1765416548.270 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T01:29:08.270Z,1765416548.270 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-11T01:29:08.270Z,1765416548.270 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T01:29:08.271Z,1765416548.271 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T01:29:08.271Z,1765416548.271 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-11T01:29:08.271Z,1765416548.271 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T01:29:08.271Z,1765416548.271 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-11T01:29:08.271Z,1765416548.271 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-11T01:29:08.271Z,1765416548.271 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-11T01:29:08.271Z,1765416548.271 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-11T01:29:08.271Z,1765416548.271 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-11T01:29:08.659Z,1765416548.659 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-11T01:29:08.659Z,1765416548.659 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-11T01:29:08.659Z,1765416548.659 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-11T01:29:08.659Z,1765416548.659 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-11T01:29:08.659Z,1765416548.659 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-11T01:29:08.659Z,1765416548.659 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-11T01:29:08.659Z,1765416548.659 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-11T01:29:08.660Z,1765416548.660 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-11T01:29:08.660Z,1765416548.660 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-11T01:29:08.660Z,1765416548.660 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-11T01:29:08.661Z,1765416548.661 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-11T01:29:13.276Z,1765416553.276 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.431229 2025-12-11T01:29:14.367Z,1765416554.367 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T01:38:50.363Z,1765417130.363 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T01:48:02.626Z,1765417682.626 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T01:48:02.630Z,1765417682.630 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T02:29:04.714Z,1765420144.714 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T02:29:04.718Z,1765420144.718 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T02:55:36.838Z,1765421736.838 [keepstation:SurfaceComms] Running Loop=1 2025-12-11T02:55:36.838Z,1765421736.838 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-11T02:55:36.838Z,1765421736.838 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-11T02:55:36.839Z,1765421736.839 [keepstation:SurfaceComms:A] Stopped 2025-12-11T02:55:36.839Z,1765421736.839 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-11T02:55:36.839Z,1765421736.839 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-11T02:55:36.841Z,1765421736.841 [keepstation:NeedComms] Running Loop=1 2025-12-11T02:55:36.841Z,1765421736.841 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-11T02:55:36.841Z,1765421736.841 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-11T02:55:36.841Z,1765421736.841 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-11T02:55:36.842Z,1765421736.842 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-11T02:55:36.842Z,1765421736.842 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-11T02:55:36.842Z,1765421736.842 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-11T02:55:36.843Z,1765421736.843 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-11T02:55:36.843Z,1765421736.843 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-11T02:55:36.843Z,1765421736.843 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-11T02:55:36.844Z,1765421736.844 [keepstation:NeedComms:A] Running Loop=1 2025-12-11T02:55:36.845Z,1765421736.845 [keepstation:NeedComms:A](INFO): last time_fix was: 1765416334.000000 second since 1970/01/01T00:00:00Z 2025-12-11T02:55:36.846Z,1765421736.846 [keepstation:NeedComms:A] Stopped 2025-12-11T02:55:36.846Z,1765421736.846 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-11T02:55:36.846Z,1765421736.846 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-11T02:55:36.846Z,1765421736.846 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-11T02:55:37.238Z,1765421737.238 [keepstation:TransitToStation:Dive] Stopped 2025-12-11T02:55:37.238Z,1765421737.238 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-12-11T02:55:37.238Z,1765421737.238 [keepstation:TransitToStation:Dive:A] Stopped 2025-12-11T02:55:37.238Z,1765421737.238 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-12-11T02:55:37.239Z,1765421737.239 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-11T02:55:37.239Z,1765421737.239 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-11T02:55:37.239Z,1765421737.239 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-11T02:55:37.239Z,1765421737.239 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-11T02:55:37.239Z,1765421737.239 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-11T02:55:37.239Z,1765421737.239 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-11T02:55:37.243Z,1765421737.243 [keepstation:NeedComms:setActive:A] Stopped 2025-12-11T02:55:37.243Z,1765421737.243 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-11T02:55:37.652Z,1765421737.652 [keepstation:NeedComms:setActive:B] Stopped 2025-12-11T02:55:37.653Z,1765421737.653 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-11T02:55:37.653Z,1765421737.653 [keepstation:NeedComms:setActive] Stopped 2025-12-11T02:55:37.653Z,1765421737.653 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-11T02:55:41.294Z,1765421741.294 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.725569,-122.178835 2025-12-11T02:55:41.294Z,1765421741.294 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-12-11T02:55:41.294Z,1765421741.294 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-12-11T02:55:41.294Z,1765421741.294 [keepstation:TransitToStation:C] Running Loop=1 2025-12-11T02:55:41.692Z,1765421741.692 [keepstation:TransitToStation:C] Stopped 2025-12-11T02:55:41.692Z,1765421741.692 [keepstation:TransitToStation:D] Running Loop=1 2025-12-11T02:55:41.693Z,1765421741.693 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-11T02:55:41.693Z,1765421741.693 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-11T02:55:41.693Z,1765421741.693 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-11T02:55:41.693Z,1765421741.693 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-11T02:55:41.693Z,1765421741.693 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-11T02:55:41.693Z,1765421741.693 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-11T02:55:41.693Z,1765421741.693 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-11T02:55:42.081Z,1765421742.081 [keepstation:TransitToStation:D] Stopped 2025-12-11T02:55:42.081Z,1765421742.081 [keepstation:TransitToStation:Dive] Running Loop=1 2025-12-11T02:55:42.081Z,1765421742.081 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-12-11T02:55:42.081Z,1765421742.081 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-12-11T02:55:42.081Z,1765421742.081 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-12-11T02:55:42.082Z,1765421742.082 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-12-11T02:55:42.082Z,1765421742.082 [keepstation:TransitToStation] Stopped 2025-12-11T02:55:42.082Z,1765421742.082 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-12-11T02:55:42.082Z,1765421742.082 [keepstation:TransitToStation:Dive] Stopped 2025-12-11T02:55:42.082Z,1765421742.082 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-12-11T02:55:42.082Z,1765421742.082 [keepstation:TransitToStation:Dive:A] Stopped 2025-12-11T02:55:42.082Z,1765421742.082 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-12-11T02:55:42.082Z,1765421742.082 [keepstation:OnStation] Running Loop=1 2025-12-11T02:55:42.082Z,1765421742.082 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-12-11T02:56:03.632Z,1765421763.632 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-12-11T02:56:08.367Z,1765421768.367 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.004113 CHAN A1 (24V): 0.021404 CHAN A2 (12V): 0.000112 CHAN A3 (5V): -0.005255 CHAN B0 (3.3V): -0.016963 CHAN B1 (3.15aV): -0.002636 CHAN B2 (3.15bV): -0.002180 CHAN B3 (GND): -0.003647 OPEN: 0.011763 Full Scale: +/- 1 mA 2025-12-11T02:58:28.403Z,1765421908.403 [keepstation:NeedComms:D] Running Loop=1 2025-12-11T02:58:28.804Z,1765421908.804 [Radio_Surface](INFO): Powering up 2025-12-11T02:58:34.464Z,1765421914.464 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-11T02:59:04.587Z,1765421944.587 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T02:59:35.716Z,1765421975.716 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T03:00:06.844Z,1765422006.844 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T03:00:16.666Z,1765422016.666 [NAL9602](INFO): SBD MO Status=2, MOMSN=24417, MT Status=2, MTMSN=0 2025-12-11T03:00:16.666Z,1765422016.666 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T03:00:37.968Z,1765422037.968 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T03:01:09.092Z,1765422069.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T03:01:10.813Z,1765422070.813 [NAL9602](INFO): SBD MO Status=2, MOMSN=24417, MT Status=2, MTMSN=0 2025-12-11T03:01:10.814Z,1765422070.814 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T03:01:40.218Z,1765422100.218 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T03:02:23.744Z,1765422143.744 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002878 2025-12-11T03:03:32.213Z,1765422212.213 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-11T03:03:37.049Z,1765422217.049 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030335.00,A,3643.63689,N,12210.14535,W,0.330,283.37,111225,,,D*70 2025-12-11T03:03:37.052Z,1765422217.052 [NAL9602](INFO): GPS fix at 20251211T030335: (36.727281, -122.169089) 2025-12-11T03:03:37.063Z,1765422217.063 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-11T03:03:37.066Z,1765422217.066 [keepstation:NeedComms:D] Stopped 2025-12-11T03:03:37.067Z,1765422217.067 [keepstation:NeedComms:E] Running Loop=1 2025-12-11T03:03:53.540Z,1765422233.540 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20251211T011500/Courier0003.lzma 2025-12-11T03:03:54.542Z,1765422234.542 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Courier0003.lzma.bak 2025-12-11T03:03:54.542Z,1765422234.542 [DataOverHttps](INFO): SBD MOMSN=26486416 2025-12-11T03:04:09.383Z,1765422249.383 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T03:04:11.053Z,1765422251.053 [DataOverHttps](INFO): Sending 1037 bytes from file Logs/20251211T011500/Express0004.lzma 2025-12-11T03:04:12.054Z,1765422252.054 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Express0004.lzma.bak 2025-12-11T03:04:12.054Z,1765422252.054 [DataOverHttps](INFO): SBD MOMSN=26486419 2025-12-11T03:04:13.449Z,1765422253.449 [keepstation:NeedComms:E] Stopped 2025-12-11T03:04:13.449Z,1765422253.449 [keepstation:NeedComms:F] Running Loop=1 2025-12-11T03:04:15.463Z,1765422255.463 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030413.00,A,3643.63493,N,12210.14285,W,0.447,137.64,111225,,,D*7B 2025-12-11T03:04:15.469Z,1765422255.469 [NAL9602](INFO): GPS fix at 20251211T030413: (36.727249, -122.169048) 2025-12-11T03:04:15.495Z,1765422255.495 [keepstation:NeedComms:F] Stopped 2025-12-11T03:04:15.495Z,1765422255.495 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-11T03:04:15.495Z,1765422255.495 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-11T03:04:15.495Z,1765422255.495 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-11T03:04:15.904Z,1765422255.904 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-11T03:04:15.904Z,1765422255.904 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-11T03:04:15.904Z,1765422255.904 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-11T03:04:15.904Z,1765422255.904 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-11T03:04:15.904Z,1765422255.904 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-11T03:04:15.904Z,1765422255.904 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-11T03:04:15.904Z,1765422255.904 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-11T03:04:16.302Z,1765422256.302 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-11T03:04:16.302Z,1765422256.302 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-11T03:04:16.302Z,1765422256.302 [keepstation:NeedComms:setInactive] Stopped 2025-12-11T03:04:16.302Z,1765422256.302 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-11T03:04:16.303Z,1765422256.303 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-11T03:04:16.303Z,1765422256.303 [keepstation:NeedComms] Stopped 2025-12-11T03:04:16.303Z,1765422256.303 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-11T03:04:16.303Z,1765422256.303 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-11T03:04:16.303Z,1765422256.303 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-11T03:04:16.698Z,1765422256.698 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-12-11T03:04:16.698Z,1765422256.698 [keepstation:OnStation] Stopped 2025-12-11T03:04:16.698Z,1765422256.698 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-12-11T03:04:16.698Z,1765422256.698 [keepstation:KeepStation] Running Loop=1 2025-12-11T03:04:16.698Z,1765422256.698 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-12-11T03:04:16.699Z,1765422256.699 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-11T03:04:16.699Z,1765422256.699 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-11T03:04:16.699Z,1765422256.699 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-11T03:04:16.699Z,1765422256.699 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-11T03:04:16.699Z,1765422256.699 [keepstation:KeepStation:B.Wait] Running Loop=1 2025-12-11T03:04:16.699Z,1765422256.699 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component. 2025-12-11T03:04:16.733Z,1765422256.733 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-11T03:04:16.734Z,1765422256.734 [keepstation:SurfaceComms:B] Stopped 2025-12-11T03:04:16.734Z,1765422256.734 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-11T03:04:16.734Z,1765422256.734 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-11T03:04:16.734Z,1765422256.734 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-11T03:04:16.734Z,1765422256.734 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-11T03:04:16.734Z,1765422256.734 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T03:04:16.734Z,1765422256.734 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-11T03:04:17.089Z,1765422257.089 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-11T03:04:17.091Z,1765422257.091 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-11T03:04:17.096Z,1765422257.096 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-11T03:04:17.096Z,1765422257.096 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-11T03:04:17.096Z,1765422257.096 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T03:04:17.096Z,1765422257.096 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-11T03:04:17.096Z,1765422257.096 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-11T03:04:17.096Z,1765422257.096 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-11T03:04:17.096Z,1765422257.096 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-11T03:04:17.545Z,1765422257.545 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-11T03:04:17.546Z,1765422257.546 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-11T03:04:17.546Z,1765422257.546 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-11T03:04:17.546Z,1765422257.546 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-11T03:04:17.546Z,1765422257.546 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-11T03:04:17.546Z,1765422257.546 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-11T03:04:17.897Z,1765422257.897 [keepstation:SurfaceComms:setStation] Stopped 2025-12-11T03:04:17.898Z,1765422257.898 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-11T03:04:17.898Z,1765422257.898 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-11T03:04:17.898Z,1765422257.898 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-11T03:04:17.898Z,1765422257.898 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-11T03:04:17.898Z,1765422257.898 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-11T03:04:18.313Z,1765422258.313 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-11T03:04:18.313Z,1765422258.313 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-11T03:04:18.313Z,1765422258.313 [keepstation:SurfaceComms:setSink] Stopped 2025-12-11T03:04:18.313Z,1765422258.313 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-11T03:04:18.313Z,1765422258.313 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-11T03:04:18.314Z,1765422258.314 [keepstation:SurfaceComms] Stopped 2025-12-11T03:04:18.314Z,1765422258.314 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-11T03:04:18.719Z,1765422258.719 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-11T03:04:18.719Z,1765422258.719 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-11T03:04:18.719Z,1765422258.719 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-11T03:04:18.719Z,1765422258.719 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-11T03:04:18.719Z,1765422258.719 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-11T03:04:18.719Z,1765422258.719 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-11T03:04:18.719Z,1765422258.719 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-11T03:04:18.719Z,1765422258.719 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-11T03:04:18.719Z,1765422258.719 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-11T03:04:18.724Z,1765422258.724 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T03:04:18.724Z,1765422258.724 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-11T03:04:18.724Z,1765422258.724 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-11T03:04:18.724Z,1765422258.724 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-11T03:04:18.724Z,1765422258.724 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-11T03:04:18.724Z,1765422258.724 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-11T03:04:18.725Z,1765422258.725 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-11T03:04:18.725Z,1765422258.725 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-11T03:04:18.725Z,1765422258.725 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-11T03:04:18.725Z,1765422258.725 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-11T03:04:46.253Z,1765422286.253 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-11T03:04:46.337Z,1765422286.337 [NAL9602](ERROR): received: +CSQ:0 OK417, 2, 0, 0, 0 OK 2025-12-11T03:06:15.744Z,1765422375.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T03:06:41.080Z,1765422401.080 [Radio_Surface](INFO): Powering down 2025-12-11T03:06:46.872Z,1765422406.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T03:06:47.879Z,1765422407.879 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-11T03:07:54.924Z,1765422474.924 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-11T03:07:54.925Z,1765422474.925 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-11T03:07:54.925Z,1765422474.925 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T03:07:54.925Z,1765422474.925 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-11T03:07:55.336Z,1765422475.336 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-11T03:07:55.337Z,1765422475.337 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T03:07:55.337Z,1765422475.337 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-11T03:07:55.337Z,1765422475.337 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-11T03:07:55.337Z,1765422475.337 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T03:07:55.337Z,1765422475.337 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-11T03:07:55.747Z,1765422475.747 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-11T03:07:55.747Z,1765422475.747 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T03:07:55.748Z,1765422475.748 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-11T03:07:55.748Z,1765422475.748 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T03:07:55.748Z,1765422475.748 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T03:07:55.748Z,1765422475.748 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-11T03:07:55.749Z,1765422475.749 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T03:07:55.749Z,1765422475.749 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-11T03:07:55.749Z,1765422475.749 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-11T03:07:55.749Z,1765422475.749 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-11T03:07:55.749Z,1765422475.749 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-11T03:07:55.749Z,1765422475.749 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-11T03:07:56.131Z,1765422476.131 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-11T03:07:56.131Z,1765422476.131 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-11T03:07:56.131Z,1765422476.131 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-11T03:07:56.131Z,1765422476.131 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-11T03:07:56.131Z,1765422476.131 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-11T03:07:56.131Z,1765422476.131 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-11T03:07:56.131Z,1765422476.131 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-11T03:07:56.133Z,1765422476.133 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-11T03:07:56.538Z,1765422476.538 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.17 mm (1 active estimators). 2025-12-11T03:08:23.268Z,1765422503.268 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739624 2025-12-11T03:09:17.376Z,1765422557.376 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-11T03:09:48.079Z,1765422588.079 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T03:10:06.699Z,1765422606.699 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T03:10:06.702Z,1765422606.702 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T03:27:39.242Z,1765423659.242 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-11T03:51:08.374Z,1765425068.374 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T03:51:08.378Z,1765425068.378 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T04:22:59.302Z,1765426979.302 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T04:32:10.366Z,1765427530.366 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T04:32:10.370Z,1765427530.370 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T04:34:15.609Z,1765427655.609 [keepstation:SurfaceComms] Running Loop=1 2025-12-11T04:34:15.609Z,1765427655.609 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-11T04:34:15.609Z,1765427655.609 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-11T04:34:15.609Z,1765427655.609 [keepstation:SurfaceComms:A] Stopped 2025-12-11T04:34:15.609Z,1765427655.609 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-11T04:34:15.610Z,1765427655.610 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-11T04:34:15.612Z,1765427655.612 [keepstation:NeedComms] Running Loop=1 2025-12-11T04:34:15.612Z,1765427655.612 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-11T04:34:15.612Z,1765427655.612 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-11T04:34:15.612Z,1765427655.612 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-11T04:34:15.612Z,1765427655.612 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-11T04:34:15.613Z,1765427655.613 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-11T04:34:15.613Z,1765427655.613 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-11T04:34:15.614Z,1765427655.614 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-11T04:34:15.614Z,1765427655.614 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-11T04:34:15.614Z,1765427655.614 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-11T04:34:15.614Z,1765427655.614 [keepstation:NeedComms:A] Running Loop=1 2025-12-11T04:34:15.616Z,1765427655.616 [keepstation:NeedComms:A](INFO): last time_fix was: 1765422253.000000 second since 1970/01/01T00:00:00Z 2025-12-11T04:34:15.616Z,1765427655.616 [keepstation:NeedComms:A] Stopped 2025-12-11T04:34:15.616Z,1765427655.616 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-11T04:34:15.616Z,1765427655.616 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-11T04:34:15.616Z,1765427655.616 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-11T04:34:16.015Z,1765427656.015 [keepstation:KeepStation:Dive] Stopped 2025-12-11T04:34:16.015Z,1765427656.015 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-11T04:34:16.015Z,1765427656.015 [keepstation:KeepStation:Dive:A] Stopped 2025-12-11T04:34:16.015Z,1765427656.015 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-11T04:34:16.015Z,1765427656.015 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-11T04:34:16.016Z,1765427656.016 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-11T04:34:16.016Z,1765427656.016 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-11T04:34:16.016Z,1765427656.016 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-11T04:34:16.016Z,1765427656.016 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-11T04:34:16.016Z,1765427656.016 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-11T04:34:16.019Z,1765427656.019 [keepstation:NeedComms:setActive:A] Stopped 2025-12-11T04:34:16.019Z,1765427656.019 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-11T04:34:16.438Z,1765427656.438 [keepstation:NeedComms:setActive:B] Stopped 2025-12-11T04:34:16.438Z,1765427656.438 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-11T04:34:16.438Z,1765427656.438 [keepstation:NeedComms:setActive] Stopped 2025-12-11T04:34:16.438Z,1765427656.438 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-11T04:37:23.765Z,1765427843.765 [keepstation:NeedComms:D] Running Loop=1 2025-12-11T04:37:24.180Z,1765427844.180 [Radio_Surface](INFO): Powering up 2025-12-11T04:37:30.388Z,1765427850.388 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-11T04:37:56.570Z,1765427876.570 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-11T04:37:56.648Z,1765427876.648 [NAL9602](ERROR): received: +CSQ:0 OK417, 2, 0, 0, 0 OK 2025-12-11T04:38:00.508Z,1765427880.508 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T04:38:16.369Z,1765427896.369 [NAL9602](INFO): SBD MO Status=2, MOMSN=24417, MT Status=2, MTMSN=0 2025-12-11T04:38:16.369Z,1765427896.369 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T04:38:31.652Z,1765427911.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T04:39:02.777Z,1765427942.777 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T04:39:33.904Z,1765427973.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T04:39:42.484Z,1765427982.484 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002785 2025-12-11T04:42:28.463Z,1765428148.463 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-11T04:42:38.171Z,1765428158.171 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,044236.00,A,3643.20065,N,12210.33324,W,1.244,119.88,111225,,,D*76 2025-12-11T04:42:38.173Z,1765428158.173 [NAL9602](INFO): GPS fix at 20251211T044236: (36.720011, -122.172221) 2025-12-11T04:42:38.185Z,1765428158.185 [UniversalFixResidualReporter](INFO): Fix residual: 73.3 %DT, over the last 1034.4 m. Residual distance 757.9 m at bearing -46.3 degrees. Fix at (36.7200, -122.1722) with 853.1 m made good. 2025-12-11T04:42:38.188Z,1765428158.188 [keepstation:NeedComms:D] Stopped 2025-12-11T04:42:38.188Z,1765428158.188 [keepstation:NeedComms:E] Running Loop=1 2025-12-11T04:42:44.907Z,1765428164.907 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20251211T011500/Courier0006.lzma 2025-12-11T04:42:45.910Z,1765428165.910 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Courier0006.lzma.bak 2025-12-11T04:42:45.910Z,1765428165.910 [DataOverHttps](INFO): SBD MOMSN=26486789 2025-12-11T04:43:01.885Z,1765428181.885 [DataOverHttps](INFO): Sending 1006 bytes from file Logs/20251211T011500/Express0007.lzma 2025-12-11T04:43:02.886Z,1765428182.886 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Express0007.lzma.bak 2025-12-11T04:43:02.886Z,1765428182.886 [DataOverHttps](INFO): SBD MOMSN=26486792 2025-12-11T04:43:04.442Z,1765428184.442 [keepstation:NeedComms:E] Stopped 2025-12-11T04:43:04.442Z,1765428184.442 [keepstation:NeedComms:F] Running Loop=1 2025-12-11T04:43:06.449Z,1765428186.449 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,044304.00,A,3643.19748,N,12210.33231,W,0.253,198.45,111225,,,D*7E 2025-12-11T04:43:06.464Z,1765428186.464 [NAL9602](INFO): GPS fix at 20251211T044304: (36.719958, -122.172205) 2025-12-11T04:43:06.489Z,1765428186.489 [keepstation:NeedComms:F] Stopped 2025-12-11T04:43:06.489Z,1765428186.489 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-11T04:43:06.489Z,1765428186.489 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-11T04:43:06.490Z,1765428186.490 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-11T04:43:06.898Z,1765428186.898 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-11T04:43:06.898Z,1765428186.898 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-11T04:43:06.898Z,1765428186.898 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-11T04:43:06.898Z,1765428186.898 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-11T04:43:06.898Z,1765428186.898 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-11T04:43:06.898Z,1765428186.898 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-11T04:43:06.898Z,1765428186.898 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-11T04:43:07.303Z,1765428187.303 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-11T04:43:07.308Z,1765428187.308 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-11T04:43:07.308Z,1765428187.308 [keepstation:NeedComms:setInactive] Stopped 2025-12-11T04:43:07.308Z,1765428187.308 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-11T04:43:07.318Z,1765428187.318 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-11T04:43:07.318Z,1765428187.318 [keepstation:NeedComms] Stopped 2025-12-11T04:43:07.329Z,1765428187.329 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-11T04:43:07.330Z,1765428187.330 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-11T04:43:07.330Z,1765428187.330 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-11T04:43:07.691Z,1765428187.691 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-11T04:43:07.691Z,1765428187.691 [keepstation:SurfaceComms:B] Stopped 2025-12-11T04:43:07.691Z,1765428187.691 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-11T04:43:07.691Z,1765428187.691 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-11T04:43:07.691Z,1765428187.691 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-11T04:43:07.691Z,1765428187.691 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-11T04:43:07.696Z,1765428187.696 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T04:43:07.696Z,1765428187.696 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-11T04:43:08.090Z,1765428188.090 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-11T04:43:08.090Z,1765428188.090 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-11T04:43:08.090Z,1765428188.090 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-11T04:43:08.090Z,1765428188.090 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T04:43:08.091Z,1765428188.091 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-11T04:43:08.091Z,1765428188.091 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-11T04:43:08.091Z,1765428188.091 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-11T04:43:08.091Z,1765428188.091 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-11T04:43:08.504Z,1765428188.504 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-11T04:43:08.504Z,1765428188.504 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-11T04:43:08.504Z,1765428188.504 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-11T04:43:08.504Z,1765428188.504 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-11T04:43:08.505Z,1765428188.505 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-11T04:43:08.505Z,1765428188.505 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-11T04:43:08.890Z,1765428188.890 [keepstation:SurfaceComms:setStation] Stopped 2025-12-11T04:43:08.890Z,1765428188.890 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-11T04:43:08.890Z,1765428188.890 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-11T04:43:08.890Z,1765428188.890 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-11T04:43:08.890Z,1765428188.890 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-11T04:43:08.890Z,1765428188.890 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-11T04:43:09.289Z,1765428189.289 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-11T04:43:09.290Z,1765428189.290 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-11T04:43:09.290Z,1765428189.290 [keepstation:SurfaceComms:setSink] Stopped 2025-12-11T04:43:09.290Z,1765428189.290 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-11T04:43:09.290Z,1765428189.290 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-11T04:43:09.290Z,1765428189.290 [keepstation:SurfaceComms] Stopped 2025-12-11T04:43:09.290Z,1765428189.290 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-11T04:43:09.691Z,1765428189.691 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-11T04:43:09.691Z,1765428189.691 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-11T04:43:09.691Z,1765428189.691 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-11T04:43:09.691Z,1765428189.691 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-11T04:43:09.692Z,1765428189.692 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-11T04:43:09.692Z,1765428189.692 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-11T04:43:09.692Z,1765428189.692 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-11T04:43:09.693Z,1765428189.693 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T04:43:09.693Z,1765428189.693 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-11T04:43:09.693Z,1765428189.693 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-11T04:43:09.693Z,1765428189.693 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-11T04:43:09.693Z,1765428189.693 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-11T04:43:09.693Z,1765428189.693 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-11T04:43:09.693Z,1765428189.693 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-11T04:43:09.694Z,1765428189.694 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-11T04:43:09.694Z,1765428189.694 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-11T04:43:09.694Z,1765428189.694 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-11T04:43:38.353Z,1765428218.353 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T04:45:06.112Z,1765428306.112 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T04:45:37.236Z,1765428337.236 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T04:45:42.716Z,1765428342.716 [Radio_Surface](INFO): Powering down 2025-12-11T04:46:08.368Z,1765428368.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T04:46:09.372Z,1765428369.372 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-11T04:46:56.348Z,1765428416.348 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-11T04:46:56.349Z,1765428416.349 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-11T04:46:56.349Z,1765428416.349 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T04:46:56.349Z,1765428416.349 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-11T04:46:56.730Z,1765428416.730 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-11T04:46:56.730Z,1765428416.730 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T04:46:56.730Z,1765428416.730 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-11T04:46:56.731Z,1765428416.731 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-11T04:46:56.731Z,1765428416.731 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T04:46:56.731Z,1765428416.731 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-11T04:46:57.137Z,1765428417.137 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-11T04:46:57.137Z,1765428417.137 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T04:46:57.137Z,1765428417.137 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-11T04:46:57.137Z,1765428417.137 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T04:46:57.137Z,1765428417.137 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T04:46:57.137Z,1765428417.137 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-11T04:46:57.138Z,1765428417.138 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T04:46:57.138Z,1765428417.138 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-11T04:46:57.138Z,1765428417.138 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-11T04:46:57.138Z,1765428417.138 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-11T04:46:57.138Z,1765428417.138 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-11T04:46:57.138Z,1765428417.138 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-11T04:46:57.555Z,1765428417.555 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-11T04:46:57.555Z,1765428417.555 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-11T04:46:57.555Z,1765428417.555 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-11T04:46:57.555Z,1765428417.555 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-11T04:46:57.555Z,1765428417.555 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-11T04:46:57.555Z,1765428417.555 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-11T04:46:57.556Z,1765428417.556 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-11T04:46:57.557Z,1765428417.557 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-11T04:47:13.628Z,1765428433.628 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739258 2025-12-11T04:59:20.457Z,1765429160.457 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-11T05:04:00.333Z,1765429440.333 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T05:08:12.355Z,1765429692.355 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-11T05:13:12.109Z,1765429992.109 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T05:13:12.114Z,1765429992.114 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T05:41:20.039Z,1765431680.039 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-11T05:42:58.624Z,1765431778.624 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-11T05:45:03.052Z,1765431903.052 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T05:54:14.110Z,1765432454.110 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T05:54:14.121Z,1765432454.121 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T06:13:06.554Z,1765433586.554 [keepstation:SurfaceComms] Running Loop=1 2025-12-11T06:13:06.555Z,1765433586.555 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-11T06:13:06.555Z,1765433586.555 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-11T06:13:06.555Z,1765433586.555 [keepstation:SurfaceComms:A] Stopped 2025-12-11T06:13:06.555Z,1765433586.555 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-11T06:13:06.555Z,1765433586.555 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-11T06:13:06.938Z,1765433586.938 [keepstation:KeepStation:Dive] Stopped 2025-12-11T06:13:06.938Z,1765433586.938 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-11T06:13:06.938Z,1765433586.938 [keepstation:KeepStation:Dive:A] Stopped 2025-12-11T06:13:06.938Z,1765433586.938 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-11T06:13:06.938Z,1765433586.938 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-11T06:13:06.939Z,1765433586.939 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-11T06:13:06.939Z,1765433586.939 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-11T06:13:06.939Z,1765433586.939 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-11T06:13:06.939Z,1765433586.939 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-11T06:13:06.939Z,1765433586.939 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-11T06:13:06.941Z,1765433586.941 [keepstation:NeedComms] Running Loop=1 2025-12-11T06:13:06.941Z,1765433586.941 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-11T06:13:06.941Z,1765433586.941 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-11T06:13:06.941Z,1765433586.941 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-11T06:13:06.942Z,1765433586.942 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-11T06:13:06.942Z,1765433586.942 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-11T06:13:06.942Z,1765433586.942 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-11T06:13:06.943Z,1765433586.943 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-11T06:13:06.943Z,1765433586.943 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-11T06:13:06.943Z,1765433586.943 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-11T06:13:06.944Z,1765433586.944 [keepstation:NeedComms:A] Running Loop=1 2025-12-11T06:13:06.945Z,1765433586.945 [keepstation:NeedComms:A](INFO): last time_fix was: 1765428184.000000 second since 1970/01/01T00:00:00Z 2025-12-11T06:13:06.945Z,1765433586.945 [keepstation:NeedComms:A] Stopped 2025-12-11T06:13:06.945Z,1765433586.945 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-11T06:13:06.946Z,1765433586.946 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-11T06:13:06.946Z,1765433586.946 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-11T06:13:07.346Z,1765433587.346 [keepstation:NeedComms:setActive:A] Stopped 2025-12-11T06:13:07.346Z,1765433587.346 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-11T06:13:07.755Z,1765433587.755 [keepstation:NeedComms:setActive:B] Stopped 2025-12-11T06:13:07.755Z,1765433587.755 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-11T06:13:07.755Z,1765433587.755 [keepstation:NeedComms:setActive] Stopped 2025-12-11T06:13:07.755Z,1765433587.755 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-11T06:16:17.306Z,1765433777.306 [keepstation:NeedComms:D] Running Loop=1 2025-12-11T06:16:17.960Z,1765433777.960 [Radio_Surface](INFO): Powering up 2025-12-11T06:16:23.904Z,1765433783.904 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-11T06:16:28.720Z,1765433788.720 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T06:16:50.105Z,1765433810.105 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-11T06:16:50.184Z,1765433810.184 [NAL9602](ERROR): received: +CSQ:0 OK417, 2, 0, 0, 0 OK 2025-12-11T06:16:54.040Z,1765433814.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T06:17:25.164Z,1765433845.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T06:17:56.292Z,1765433876.292 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T06:18:27.416Z,1765433907.416 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T06:18:41.064Z,1765433921.064 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002868 2025-12-11T06:21:21.611Z,1765434081.611 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-11T06:21:22.825Z,1765434082.825 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,062121.00,A,3643.16158,N,12211.02273,W,0.486,217.25,111225,,,A*7B 2025-12-11T06:21:22.828Z,1765434082.828 [NAL9602](INFO): GPS fix at 20251211T062121: (36.719360, -122.183712) 2025-12-11T06:21:22.859Z,1765434082.859 [UniversalFixResidualReporter](INFO): Fix residual: 62.2 %DT, over the last 1103.8 m. Residual distance 686.7 m at bearing 31.1 degrees. Fix at (36.7194, -122.1837) with 1027.8 m made good. 2025-12-11T06:21:22.866Z,1765434082.866 [keepstation:NeedComms:D] Stopped 2025-12-11T06:21:22.867Z,1765434082.867 [keepstation:NeedComms:E] Running Loop=1 2025-12-11T06:21:30.579Z,1765434090.579 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20251211T011500/Courier0009.lzma 2025-12-11T06:21:31.582Z,1765434091.582 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Courier0009.lzma.bak 2025-12-11T06:21:31.582Z,1765434091.582 [DataOverHttps](INFO): SBD MOMSN=26487301 2025-12-11T06:21:47.529Z,1765434107.529 [DataOverHttps](INFO): Sending 1025 bytes from file Logs/20251211T011500/Express0010.lzma 2025-12-11T06:21:48.523Z,1765434108.523 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Express0010.lzma.bak 2025-12-11T06:21:48.524Z,1765434108.524 [DataOverHttps](INFO): SBD MOMSN=26487304 2025-12-11T06:21:49.908Z,1765434109.908 [keepstation:NeedComms:E] Stopped 2025-12-11T06:21:49.908Z,1765434109.908 [keepstation:NeedComms:F] Running Loop=1 2025-12-11T06:21:51.911Z,1765434111.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,062150.00,A,3643.15951,N,12211.02354,W,0.797,227.66,111225,,,D*79 2025-12-11T06:21:51.913Z,1765434111.913 [NAL9602](INFO): GPS fix at 20251211T062150: (36.719325, -122.183726) 2025-12-11T06:21:51.938Z,1765434111.938 [keepstation:NeedComms:F] Stopped 2025-12-11T06:21:51.938Z,1765434111.938 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-11T06:21:51.938Z,1765434111.938 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-11T06:21:51.938Z,1765434111.938 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-11T06:21:52.391Z,1765434112.391 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-11T06:21:52.391Z,1765434112.391 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-11T06:21:52.391Z,1765434112.391 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-11T06:21:52.391Z,1765434112.391 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-11T06:21:52.391Z,1765434112.391 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-11T06:21:52.391Z,1765434112.391 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-11T06:21:52.391Z,1765434112.391 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-11T06:21:52.748Z,1765434112.748 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-11T06:21:52.749Z,1765434112.749 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-11T06:21:52.749Z,1765434112.749 [keepstation:NeedComms:setInactive] Stopped 2025-12-11T06:21:52.749Z,1765434112.749 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-11T06:21:52.750Z,1765434112.750 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-11T06:21:52.750Z,1765434112.750 [keepstation:NeedComms] Stopped 2025-12-11T06:21:52.750Z,1765434112.750 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-11T06:21:52.750Z,1765434112.750 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-11T06:21:52.750Z,1765434112.750 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-11T06:21:53.239Z,1765434113.239 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-11T06:21:53.239Z,1765434113.239 [keepstation:SurfaceComms:B] Stopped 2025-12-11T06:21:53.239Z,1765434113.239 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-11T06:21:53.239Z,1765434113.239 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-11T06:21:53.239Z,1765434113.239 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-11T06:21:53.260Z,1765434113.260 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-11T06:21:53.260Z,1765434113.260 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T06:21:53.260Z,1765434113.260 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-11T06:21:53.576Z,1765434113.576 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-11T06:21:53.576Z,1765434113.576 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-11T06:21:53.576Z,1765434113.576 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-11T06:21:53.576Z,1765434113.576 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T06:21:53.577Z,1765434113.577 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-11T06:21:53.577Z,1765434113.577 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-11T06:21:53.577Z,1765434113.577 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-11T06:21:53.577Z,1765434113.577 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-11T06:21:53.997Z,1765434113.997 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-11T06:21:53.997Z,1765434113.997 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-11T06:21:53.998Z,1765434113.998 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-11T06:21:53.998Z,1765434113.998 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-11T06:21:53.998Z,1765434113.998 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-11T06:21:53.998Z,1765434113.998 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-11T06:21:54.373Z,1765434114.373 [keepstation:SurfaceComms:setStation] Stopped 2025-12-11T06:21:54.373Z,1765434114.373 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-11T06:21:54.373Z,1765434114.373 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-11T06:21:54.373Z,1765434114.373 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-11T06:21:54.373Z,1765434114.373 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-11T06:21:54.373Z,1765434114.373 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-11T06:21:54.767Z,1765434114.767 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-11T06:21:54.767Z,1765434114.767 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-11T06:21:54.767Z,1765434114.767 [keepstation:SurfaceComms:setSink] Stopped 2025-12-11T06:21:54.767Z,1765434114.767 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-11T06:21:54.767Z,1765434114.767 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-11T06:21:54.767Z,1765434114.767 [keepstation:SurfaceComms] Stopped 2025-12-11T06:21:54.768Z,1765434114.768 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-11T06:21:55.189Z,1765434115.189 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-11T06:21:55.189Z,1765434115.189 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-11T06:21:55.189Z,1765434115.189 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-11T06:21:55.190Z,1765434115.190 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-11T06:21:55.190Z,1765434115.190 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-11T06:21:55.190Z,1765434115.190 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-11T06:21:55.190Z,1765434115.190 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-11T06:21:55.190Z,1765434115.190 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T06:21:55.190Z,1765434115.190 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-11T06:21:55.190Z,1765434115.190 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-11T06:21:55.191Z,1765434115.191 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-11T06:21:55.191Z,1765434115.191 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-11T06:21:55.191Z,1765434115.191 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-11T06:21:55.191Z,1765434115.191 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-11T06:21:55.191Z,1765434115.191 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-11T06:21:55.192Z,1765434115.192 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-11T06:21:55.196Z,1765434115.196 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-11T06:22:24.645Z,1765434144.645 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T06:23:52.252Z,1765434232.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T06:24:15.124Z,1765434255.124 [Radio_Surface](INFO): Powering down 2025-12-11T06:24:23.376Z,1765434263.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T06:24:24.380Z,1765434264.380 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-11T06:25:29.680Z,1765434329.680 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-11T06:25:29.680Z,1765434329.680 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-11T06:25:29.680Z,1765434329.680 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T06:25:29.681Z,1765434329.681 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-11T06:25:30.083Z,1765434330.083 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-11T06:25:30.083Z,1765434330.083 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T06:25:30.083Z,1765434330.083 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-11T06:25:30.083Z,1765434330.083 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-11T06:25:30.083Z,1765434330.083 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T06:25:30.084Z,1765434330.084 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-11T06:25:30.492Z,1765434330.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-11T06:25:30.492Z,1765434330.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T06:25:30.492Z,1765434330.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-11T06:25:30.492Z,1765434330.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T06:25:30.493Z,1765434330.493 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T06:25:30.493Z,1765434330.493 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-11T06:25:30.493Z,1765434330.493 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T06:25:30.493Z,1765434330.493 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-11T06:25:30.493Z,1765434330.493 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-11T06:25:30.493Z,1765434330.493 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-11T06:25:30.493Z,1765434330.493 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-11T06:25:30.493Z,1765434330.493 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-11T06:25:30.890Z,1765434330.890 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-11T06:25:30.891Z,1765434330.891 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-11T06:25:30.891Z,1765434330.891 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-11T06:25:30.891Z,1765434330.891 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-11T06:25:30.891Z,1765434330.891 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-11T06:25:30.891Z,1765434330.891 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-11T06:25:30.891Z,1765434330.891 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-11T06:25:30.908Z,1765434330.908 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-11T06:25:59.780Z,1765434359.780 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.759094 2025-12-11T06:32:54.381Z,1765434774.381 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-12-11T06:35:16.092Z,1765434916.092 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T06:35:16.097Z,1765434916.097 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T06:44:41.887Z,1765435481.887 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-11T06:56:25.390Z,1765436185.390 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-11T07:06:07.559Z,1765436767.559 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-11T07:07:06.597Z,1765436826.597 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T07:16:17.994Z,1765437377.994 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T07:16:17.998Z,1765437377.998 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T07:38:32.465Z,1765438712.465 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T07:48:08.960Z,1765439288.960 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T07:51:51.941Z,1765439511.941 [keepstation:SurfaceComms] Running Loop=1 2025-12-11T07:51:51.941Z,1765439511.941 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-11T07:51:51.941Z,1765439511.941 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-11T07:51:51.941Z,1765439511.941 [keepstation:SurfaceComms:A] Stopped 2025-12-11T07:51:51.941Z,1765439511.941 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-11T07:51:51.942Z,1765439511.942 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-11T07:51:51.944Z,1765439511.944 [keepstation:NeedComms] Running Loop=1 2025-12-11T07:51:51.944Z,1765439511.944 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-11T07:51:51.944Z,1765439511.944 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-11T07:51:51.944Z,1765439511.944 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-11T07:51:51.945Z,1765439511.945 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-11T07:51:51.945Z,1765439511.945 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-11T07:51:51.945Z,1765439511.945 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-11T07:51:51.946Z,1765439511.946 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-11T07:51:51.946Z,1765439511.946 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-11T07:51:51.946Z,1765439511.946 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-11T07:51:51.946Z,1765439511.946 [keepstation:NeedComms:A] Running Loop=1 2025-12-11T07:51:51.948Z,1765439511.948 [keepstation:NeedComms:A](INFO): last time_fix was: 1765434110.000000 second since 1970/01/01T00:00:00Z 2025-12-11T07:51:51.948Z,1765439511.948 [keepstation:NeedComms:A] Stopped 2025-12-11T07:51:51.948Z,1765439511.948 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-11T07:51:51.948Z,1765439511.948 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-11T07:51:51.948Z,1765439511.948 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-11T07:51:52.330Z,1765439512.330 [keepstation:KeepStation:Dive] Stopped 2025-12-11T07:51:52.331Z,1765439512.331 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-11T07:51:52.331Z,1765439512.331 [keepstation:KeepStation:Dive:A] Stopped 2025-12-11T07:51:52.331Z,1765439512.331 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-11T07:51:52.331Z,1765439512.331 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-11T07:51:52.331Z,1765439512.331 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-11T07:51:52.331Z,1765439512.331 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-11T07:51:52.331Z,1765439512.331 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-11T07:51:52.331Z,1765439512.331 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-11T07:51:52.332Z,1765439512.332 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-11T07:51:52.335Z,1765439512.335 [keepstation:NeedComms:setActive:A] Stopped 2025-12-11T07:51:52.335Z,1765439512.335 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-11T07:51:52.741Z,1765439512.741 [keepstation:NeedComms:setActive:B] Stopped 2025-12-11T07:51:52.741Z,1765439512.741 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-11T07:51:52.741Z,1765439512.741 [keepstation:NeedComms:setActive] Stopped 2025-12-11T07:51:52.741Z,1765439512.741 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-11T07:54:42.131Z,1765439682.131 [keepstation:NeedComms:D] Running Loop=1 2025-12-11T07:54:42.668Z,1765439682.668 [Radio_Surface](INFO): Powering up 2025-12-11T07:54:48.044Z,1765439688.044 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-11T07:55:01.518Z,1765439701.518 [NAL9602](INFO): SBD MO Status=2, MOMSN=24417, MT Status=2, MTMSN=0 2025-12-11T07:55:01.518Z,1765439701.518 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T07:55:18.164Z,1765439718.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T07:55:49.297Z,1765439749.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T07:56:01.777Z,1765439761.777 [NAL9602](INFO): SBD MO Status=2, MOMSN=24417, MT Status=2, MTMSN=0 2025-12-11T07:56:01.777Z,1765439761.777 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T07:56:20.424Z,1765439780.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T07:56:20.697Z,1765439780.697 [NAL9602](INFO): SBD MO Status=1, MOMSN=24417, MT Status=0, MTMSN=0 2025-12-11T07:56:20.697Z,1765439780.697 [NAL9602](INFO): No messages in MT queue 2025-12-11T07:56:21.915Z,1765439781.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,075620.00,A,3643.21280,N,12211.07838,W,0.641,218.66,111225,,,A*78 2025-12-11T07:56:21.917Z,1765439781.917 [NAL9602](INFO): GPS fix at 20251211T075620: (36.720213, -122.184640) 2025-12-11T07:56:21.944Z,1765439781.944 [UniversalFixResidualReporter](INFO): Fix residual: 68.9 %DT, over the last 992.7 m. Residual distance 684.0 m at bearing 40.0 degrees. Fix at (36.7202, -122.1846) with 128.0 m made good. 2025-12-11T07:56:21.949Z,1765439781.949 [keepstation:NeedComms:D] Stopped 2025-12-11T07:56:21.951Z,1765439781.951 [keepstation:NeedComms:E] Running Loop=1 2025-12-11T07:56:43.781Z,1765439803.781 [NAL9602](INFO): SBD MO Status=2, MOMSN=24418, MT Status=2, MTMSN=0 2025-12-11T07:56:43.781Z,1765439803.781 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T07:56:51.548Z,1765439811.548 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T07:57:00.217Z,1765439820.217 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003066 2025-12-11T07:57:07.715Z,1765439827.715 [DataOverHttps](INFO): Sending 97 bytes from file Logs/20251211T011500/Courier0012.lzma 2025-12-11T07:57:08.721Z,1765439828.721 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Courier0012.lzma.bak 2025-12-11T07:57:08.721Z,1765439828.721 [DataOverHttps](INFO): SBD MOMSN=26487550 2025-12-11T07:57:19.998Z,1765439839.998 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T07:57:20.002Z,1765439840.002 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T07:57:20.006Z,1765439840.006 [NAL9602](INFO): SBD MO Status=2, MOMSN=24418, MT Status=2, MTMSN=0 2025-12-11T07:57:20.006Z,1765439840.006 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T07:57:24.893Z,1765439844.893 [DataOverHttps](INFO): Sending 834 bytes from file Logs/20251211T011500/Express0013.lzma 2025-12-11T07:57:25.894Z,1765439845.894 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Express0013.lzma.bak 2025-12-11T07:57:25.894Z,1765439845.894 [DataOverHttps](INFO): SBD MOMSN=26487553 2025-12-11T07:57:27.278Z,1765439847.278 [keepstation:NeedComms:E] Stopped 2025-12-11T07:57:27.279Z,1765439847.279 [keepstation:NeedComms:F] Running Loop=1 2025-12-11T07:57:29.279Z,1765439849.279 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,075727.00,A,3643.20890,N,12211.08203,W,0.486,306.14,111225,,,D*7E 2025-12-11T07:57:29.281Z,1765439849.281 [NAL9602](INFO): GPS fix at 20251211T075727: (36.720148, -122.184701) 2025-12-11T07:57:29.319Z,1765439849.319 [keepstation:NeedComms:F] Stopped 2025-12-11T07:57:29.319Z,1765439849.319 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-11T07:57:29.319Z,1765439849.319 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-11T07:57:29.319Z,1765439849.319 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-11T07:57:29.726Z,1765439849.726 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-11T07:57:29.726Z,1765439849.726 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-11T07:57:29.726Z,1765439849.726 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-11T07:57:29.726Z,1765439849.726 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-11T07:57:29.726Z,1765439849.726 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-11T07:57:29.727Z,1765439849.727 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-11T07:57:29.727Z,1765439849.727 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-11T07:57:30.113Z,1765439850.113 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-11T07:57:30.113Z,1765439850.113 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-11T07:57:30.114Z,1765439850.114 [keepstation:NeedComms:setInactive] Stopped 2025-12-11T07:57:30.114Z,1765439850.114 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-11T07:57:30.114Z,1765439850.114 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-11T07:57:30.114Z,1765439850.114 [keepstation:NeedComms] Stopped 2025-12-11T07:57:30.115Z,1765439850.115 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-11T07:57:30.115Z,1765439850.115 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-11T07:57:30.115Z,1765439850.115 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-11T07:57:30.517Z,1765439850.517 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-11T07:57:30.517Z,1765439850.517 [keepstation:SurfaceComms:B] Stopped 2025-12-11T07:57:30.517Z,1765439850.517 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-11T07:57:30.517Z,1765439850.517 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-11T07:57:30.517Z,1765439850.517 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-11T07:57:30.517Z,1765439850.517 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-11T07:57:30.517Z,1765439850.517 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T07:57:30.517Z,1765439850.517 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-11T07:57:30.936Z,1765439850.936 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-11T07:57:30.936Z,1765439850.936 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-11T07:57:30.936Z,1765439850.936 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-11T07:57:30.936Z,1765439850.936 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T07:57:30.936Z,1765439850.936 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-11T07:57:30.936Z,1765439850.936 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-11T07:57:30.936Z,1765439850.936 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-11T07:57:30.936Z,1765439850.936 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-11T07:57:31.350Z,1765439851.350 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-11T07:57:31.350Z,1765439851.350 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-11T07:57:31.350Z,1765439851.350 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-11T07:57:31.350Z,1765439851.350 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-11T07:57:31.350Z,1765439851.350 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-11T07:57:31.350Z,1765439851.350 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-11T07:57:31.729Z,1765439851.729 [keepstation:SurfaceComms:setStation] Stopped 2025-12-11T07:57:31.729Z,1765439851.729 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-11T07:57:31.729Z,1765439851.729 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-11T07:57:31.729Z,1765439851.729 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-11T07:57:31.729Z,1765439851.729 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-11T07:57:31.729Z,1765439851.729 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-11T07:57:32.136Z,1765439852.136 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-11T07:57:32.136Z,1765439852.136 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-11T07:57:32.136Z,1765439852.136 [keepstation:SurfaceComms:setSink] Stopped 2025-12-11T07:57:32.136Z,1765439852.136 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-11T07:57:32.137Z,1765439852.137 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-11T07:57:32.137Z,1765439852.137 [keepstation:SurfaceComms] Stopped 2025-12-11T07:57:32.137Z,1765439852.137 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-11T07:57:32.532Z,1765439852.532 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-11T07:57:32.533Z,1765439852.533 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-11T07:57:32.533Z,1765439852.533 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-11T07:57:32.533Z,1765439852.533 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-11T07:57:32.534Z,1765439852.534 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-11T07:57:32.534Z,1765439852.534 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-11T07:57:32.534Z,1765439852.534 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-11T07:57:32.534Z,1765439852.534 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T07:57:32.534Z,1765439852.534 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-11T07:57:32.534Z,1765439852.534 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-11T07:57:32.534Z,1765439852.534 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-11T07:57:32.534Z,1765439852.534 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-11T07:57:32.534Z,1765439852.534 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-11T07:57:32.535Z,1765439852.535 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-11T07:57:32.535Z,1765439852.535 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-11T07:57:32.535Z,1765439852.535 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-11T07:57:32.535Z,1765439852.535 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-11T07:58:01.601Z,1765439881.601 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T07:59:28.428Z,1765439968.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T07:59:55.804Z,1765439995.804 [Radio_Surface](INFO): Powering down 2025-12-11T07:59:59.552Z,1765439999.552 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T08:00:00.562Z,1765440000.562 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-11T08:01:23.215Z,1765440083.215 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-11T08:01:23.215Z,1765440083.215 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-11T08:01:23.215Z,1765440083.215 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T08:01:23.215Z,1765440083.215 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-11T08:01:23.617Z,1765440083.617 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-11T08:01:23.617Z,1765440083.617 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T08:01:23.617Z,1765440083.617 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-11T08:01:23.617Z,1765440083.617 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-11T08:01:23.617Z,1765440083.617 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T08:01:23.617Z,1765440083.617 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-11T08:01:24.021Z,1765440084.021 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-11T08:01:24.021Z,1765440084.021 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T08:01:24.021Z,1765440084.021 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-11T08:01:24.021Z,1765440084.021 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T08:01:24.022Z,1765440084.022 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T08:01:24.022Z,1765440084.022 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-11T08:01:24.022Z,1765440084.022 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T08:01:24.022Z,1765440084.022 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-11T08:01:24.022Z,1765440084.022 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-11T08:01:24.022Z,1765440084.022 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-11T08:01:24.022Z,1765440084.022 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-11T08:01:24.023Z,1765440084.023 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-11T08:01:24.429Z,1765440084.429 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-11T08:01:24.429Z,1765440084.429 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-11T08:01:24.429Z,1765440084.429 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-11T08:01:24.429Z,1765440084.429 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-11T08:01:24.429Z,1765440084.429 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-11T08:01:24.429Z,1765440084.429 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-11T08:01:24.429Z,1765440084.429 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-11T08:01:24.430Z,1765440084.430 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-11T08:01:35.952Z,1765440095.952 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.738403 2025-12-11T08:19:34.036Z,1765441174.036 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-11T08:21:14.970Z,1765441274.970 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.17 mm. 2025-12-11T08:29:10.116Z,1765441750.116 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T08:38:21.926Z,1765442301.926 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T08:38:21.930Z,1765442301.930 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T08:56:03.717Z,1765443363.717 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-12-11T08:56:08.179Z,1765443368.179 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.004994 CHAN A1 (24V): 0.026046 CHAN A2 (12V): -0.001715 CHAN A3 (5V): -0.006100 CHAN B0 (3.3V): -0.002600 CHAN B1 (3.15aV): -0.001824 CHAN B2 (3.15bV): -0.001843 CHAN B3 (GND): -0.002478 OPEN: 0.012213 Full Scale: +/- 1 mA 2025-12-11T09:00:36.422Z,1765443636.422 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T09:19:23.934Z,1765444763.934 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T09:19:23.947Z,1765444763.947 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T09:27:29.557Z,1765445249.557 [keepstation:SurfaceComms] Running Loop=1 2025-12-11T09:27:29.557Z,1765445249.557 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-11T09:27:29.558Z,1765445249.558 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-11T09:27:29.558Z,1765445249.558 [keepstation:SurfaceComms:A] Stopped 2025-12-11T09:27:29.558Z,1765445249.558 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-11T09:27:29.558Z,1765445249.558 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-11T09:27:29.963Z,1765445249.963 [keepstation:KeepStation:Dive] Stopped 2025-12-11T09:27:29.963Z,1765445249.963 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-11T09:27:29.963Z,1765445249.963 [keepstation:KeepStation:Dive:A] Stopped 2025-12-11T09:27:29.963Z,1765445249.963 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-11T09:27:29.964Z,1765445249.964 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-11T09:27:29.964Z,1765445249.964 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-11T09:27:29.964Z,1765445249.964 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-11T09:27:29.964Z,1765445249.964 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-11T09:27:29.964Z,1765445249.964 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-11T09:27:29.964Z,1765445249.964 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-11T09:27:29.966Z,1765445249.966 [keepstation:NeedComms] Running Loop=1 2025-12-11T09:27:29.966Z,1765445249.966 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-11T09:27:29.966Z,1765445249.966 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-11T09:27:29.966Z,1765445249.966 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-11T09:27:29.967Z,1765445249.967 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-11T09:27:29.967Z,1765445249.967 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-11T09:27:29.968Z,1765445249.968 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-11T09:27:29.968Z,1765445249.968 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-11T09:27:29.968Z,1765445249.968 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-11T09:27:29.969Z,1765445249.969 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-11T09:27:29.969Z,1765445249.969 [keepstation:NeedComms:A] Running Loop=1 2025-12-11T09:27:29.970Z,1765445249.970 [keepstation:NeedComms:A](INFO): last time_fix was: 1765439847.000000 second since 1970/01/01T00:00:00Z 2025-12-11T09:27:29.971Z,1765445249.971 [keepstation:NeedComms:A] Stopped 2025-12-11T09:27:29.971Z,1765445249.971 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-11T09:27:29.971Z,1765445249.971 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-11T09:27:29.971Z,1765445249.971 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-11T09:27:30.361Z,1765445250.361 [keepstation:NeedComms:setActive:A] Stopped 2025-12-11T09:27:30.361Z,1765445250.361 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-11T09:27:30.769Z,1765445250.769 [keepstation:NeedComms:setActive:B] Stopped 2025-12-11T09:27:30.770Z,1765445250.770 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-11T09:27:30.770Z,1765445250.770 [keepstation:NeedComms:setActive] Stopped 2025-12-11T09:27:30.770Z,1765445250.770 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-11T09:30:30.338Z,1765445430.338 [keepstation:NeedComms:D] Running Loop=1 2025-12-11T09:30:30.732Z,1765445430.732 [Radio_Surface](INFO): Powering up 2025-12-11T09:30:36.964Z,1765445436.964 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-11T09:31:07.084Z,1765445467.084 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T09:31:38.212Z,1765445498.212 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T09:32:09.336Z,1765445529.336 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T09:32:40.464Z,1765445560.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T09:32:48.928Z,1765445568.928 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002896 2025-12-11T09:32:49.290Z,1765445569.290 [NAL9602](INFO): SBD MO Status=2, MOMSN=24418, MT Status=2, MTMSN=0 2025-12-11T09:32:49.290Z,1765445569.290 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T09:33:10.299Z,1765445590.299 [NAL9602](INFO): SBD MO Status=2, MOMSN=24418, MT Status=2, MTMSN=0 2025-12-11T09:33:10.300Z,1765445590.300 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T09:33:22.010Z,1765445602.010 [NAL9602](INFO): SBD MO Status=1, MOMSN=24418, MT Status=0, MTMSN=0 2025-12-11T09:33:22.010Z,1765445602.010 [NAL9602](INFO): No messages in MT queue 2025-12-11T09:33:23.220Z,1765445603.220 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,093321.00,A,3643.56397,N,12211.00157,W,0.914,157.17,111225,,,D*70 2025-12-11T09:33:23.222Z,1765445603.222 [NAL9602](INFO): GPS fix at 20251211T093321: (36.726066, -122.183359) 2025-12-11T09:33:23.234Z,1765445603.234 [UniversalFixResidualReporter](INFO): Fix residual: 42.1 %DT, over the last 955.8 m. Residual distance 402.8 m at bearing 82.1 degrees. Fix at (36.7261, -122.1834) with 668.8 m made good. 2025-12-11T09:33:23.238Z,1765445603.238 [keepstation:NeedComms:D] Stopped 2025-12-11T09:33:23.238Z,1765445603.238 [keepstation:NeedComms:E] Running Loop=1 2025-12-11T09:33:31.459Z,1765445611.459 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20251211T011500/Courier0015.lzma 2025-12-11T09:33:32.462Z,1765445612.462 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Courier0015.lzma.bak 2025-12-11T09:33:32.462Z,1765445612.462 [DataOverHttps](INFO): SBD MOMSN=26487775 2025-12-11T09:33:48.305Z,1765445628.305 [DataOverHttps](INFO): Sending 917 bytes from file Logs/20251211T011500/Express0016.lzma 2025-12-11T09:33:49.306Z,1765445629.306 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Express0016.lzma.bak 2025-12-11T09:33:49.306Z,1765445629.306 [DataOverHttps](INFO): SBD MOMSN=26487778 2025-12-11T09:33:50.725Z,1765445630.725 [keepstation:NeedComms:E] Stopped 2025-12-11T09:33:50.725Z,1765445630.725 [keepstation:NeedComms:F] Running Loop=1 2025-12-11T09:33:52.711Z,1765445632.711 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,093350.00,A,3643.56404,N,12211.00420,W,0.175,268.50,111225,,,A*78 2025-12-11T09:33:52.713Z,1765445632.713 [NAL9602](INFO): GPS fix at 20251211T093350: (36.726067, -122.183403) 2025-12-11T09:33:52.738Z,1765445632.738 [keepstation:NeedComms:F] Stopped 2025-12-11T09:33:52.738Z,1765445632.738 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-11T09:33:52.738Z,1765445632.738 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-11T09:33:52.738Z,1765445632.738 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-11T09:33:53.282Z,1765445633.282 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-11T09:33:53.282Z,1765445633.282 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-11T09:33:53.282Z,1765445633.282 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-11T09:33:53.283Z,1765445633.283 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-11T09:33:53.283Z,1765445633.283 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-11T09:33:53.283Z,1765445633.283 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-11T09:33:53.283Z,1765445633.283 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-11T09:33:53.571Z,1765445633.571 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-11T09:33:53.571Z,1765445633.571 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-11T09:33:53.571Z,1765445633.571 [keepstation:NeedComms:setInactive] Stopped 2025-12-11T09:33:53.588Z,1765445633.588 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-11T09:33:53.589Z,1765445633.589 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-11T09:33:53.589Z,1765445633.589 [keepstation:NeedComms] Stopped 2025-12-11T09:33:53.589Z,1765445633.589 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-11T09:33:53.589Z,1765445633.589 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-11T09:33:53.589Z,1765445633.589 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-11T09:33:54.111Z,1765445634.111 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-11T09:33:54.111Z,1765445634.111 [keepstation:SurfaceComms:B] Stopped 2025-12-11T09:33:54.116Z,1765445634.116 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-11T09:33:54.117Z,1765445634.117 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-11T09:33:54.117Z,1765445634.117 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-11T09:33:54.117Z,1765445634.117 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-11T09:33:54.117Z,1765445634.117 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T09:33:54.117Z,1765445634.117 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-11T09:33:54.397Z,1765445634.397 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-11T09:33:54.397Z,1765445634.397 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-11T09:33:54.397Z,1765445634.397 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-11T09:33:54.397Z,1765445634.397 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T09:33:54.397Z,1765445634.397 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-11T09:33:54.397Z,1765445634.397 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-11T09:33:54.397Z,1765445634.397 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-11T09:33:54.397Z,1765445634.397 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-11T09:33:54.796Z,1765445634.796 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-11T09:33:54.796Z,1765445634.796 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-11T09:33:54.796Z,1765445634.796 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-11T09:33:54.796Z,1765445634.796 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-11T09:33:54.796Z,1765445634.796 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-11T09:33:54.797Z,1765445634.797 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-11T09:33:55.165Z,1765445635.165 [keepstation:SurfaceComms:setStation] Stopped 2025-12-11T09:33:55.165Z,1765445635.165 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-11T09:33:55.165Z,1765445635.165 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-11T09:33:55.165Z,1765445635.165 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-11T09:33:55.165Z,1765445635.165 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-11T09:33:55.165Z,1765445635.165 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-11T09:33:55.569Z,1765445635.569 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-11T09:33:55.569Z,1765445635.569 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-11T09:33:55.569Z,1765445635.569 [keepstation:SurfaceComms:setSink] Stopped 2025-12-11T09:33:55.569Z,1765445635.569 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-11T09:33:55.569Z,1765445635.569 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-11T09:33:55.569Z,1765445635.569 [keepstation:SurfaceComms] Stopped 2025-12-11T09:33:55.570Z,1765445635.570 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-11T09:33:55.986Z,1765445635.986 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-11T09:33:55.986Z,1765445635.986 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-11T09:33:55.986Z,1765445635.986 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-11T09:33:55.986Z,1765445635.986 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-11T09:33:55.987Z,1765445635.987 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-11T09:33:55.987Z,1765445635.987 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-11T09:33:55.987Z,1765445635.987 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-11T09:33:55.987Z,1765445635.987 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T09:33:55.987Z,1765445635.987 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-11T09:33:55.987Z,1765445635.987 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-11T09:33:55.988Z,1765445635.988 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-11T09:33:55.992Z,1765445635.992 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-11T09:33:55.992Z,1765445635.992 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-11T09:33:55.992Z,1765445635.992 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-11T09:33:55.993Z,1765445635.993 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-11T09:33:55.993Z,1765445635.993 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-11T09:33:55.993Z,1765445635.993 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-11T09:34:25.437Z,1765445665.437 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T09:35:52.768Z,1765445752.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T09:36:23.892Z,1765445783.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T09:36:32.017Z,1765445792.017 [Radio_Surface](INFO): Powering down 2025-12-11T09:36:55.016Z,1765445815.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T09:36:56.020Z,1765445816.020 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-11T09:38:00.276Z,1765445880.276 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.722885 2025-12-11T09:38:02.021Z,1765445882.021 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-11T09:38:02.021Z,1765445882.021 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-11T09:38:02.021Z,1765445882.021 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T09:38:02.021Z,1765445882.021 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-11T09:38:02.396Z,1765445882.396 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-11T09:38:02.396Z,1765445882.396 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T09:38:02.396Z,1765445882.396 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-11T09:38:02.396Z,1765445882.396 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-11T09:38:02.396Z,1765445882.396 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T09:38:02.396Z,1765445882.396 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-11T09:38:02.805Z,1765445882.805 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-11T09:38:02.805Z,1765445882.805 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T09:38:02.805Z,1765445882.805 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-11T09:38:02.805Z,1765445882.805 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T09:38:02.806Z,1765445882.806 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T09:38:02.806Z,1765445882.806 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-11T09:38:02.806Z,1765445882.806 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T09:38:02.806Z,1765445882.806 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-11T09:38:02.806Z,1765445882.806 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-11T09:38:02.806Z,1765445882.806 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-11T09:38:02.806Z,1765445882.806 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-11T09:38:02.807Z,1765445882.807 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-11T09:38:03.202Z,1765445883.202 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-11T09:38:03.203Z,1765445883.203 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-11T09:38:03.203Z,1765445883.203 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-11T09:38:03.203Z,1765445883.203 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-11T09:38:03.203Z,1765445883.203 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-11T09:38:03.203Z,1765445883.203 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-11T09:38:03.203Z,1765445883.203 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-11T09:38:03.220Z,1765445883.220 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-11T09:38:03.602Z,1765445883.602 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.17 mm (1 active estimators). 2025-12-11T09:48:29.205Z,1765446509.205 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-11T09:51:14.472Z,1765446674.472 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T10:00:25.538Z,1765447225.538 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T10:00:25.542Z,1765447225.542 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T10:41:27.578Z,1765449687.578 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T10:41:27.581Z,1765449687.581 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T11:03:52.916Z,1765451032.916 [keepstation:SurfaceComms] Running Loop=1 2025-12-11T11:03:52.916Z,1765451032.916 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-11T11:03:52.916Z,1765451032.916 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-11T11:03:52.917Z,1765451032.917 [keepstation:SurfaceComms:A] Stopped 2025-12-11T11:03:52.917Z,1765451032.917 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-11T11:03:52.917Z,1765451032.917 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-11T11:03:52.919Z,1765451032.919 [keepstation:NeedComms] Running Loop=1 2025-12-11T11:03:52.919Z,1765451032.919 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-11T11:03:52.919Z,1765451032.919 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-11T11:03:52.919Z,1765451032.919 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-11T11:03:52.936Z,1765451032.936 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-11T11:03:52.936Z,1765451032.936 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-11T11:03:52.937Z,1765451032.937 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-11T11:03:52.937Z,1765451032.937 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-11T11:03:52.937Z,1765451032.937 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-11T11:03:52.938Z,1765451032.938 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-11T11:03:52.938Z,1765451032.938 [keepstation:NeedComms:A] Running Loop=1 2025-12-11T11:03:52.939Z,1765451032.939 [keepstation:NeedComms:A](INFO): last time_fix was: 1765445630.000000 second since 1970/01/01T00:00:00Z 2025-12-11T11:03:52.939Z,1765451032.939 [keepstation:NeedComms:A] Stopped 2025-12-11T11:03:52.939Z,1765451032.939 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-11T11:03:52.940Z,1765451032.940 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-11T11:03:52.940Z,1765451032.940 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-11T11:03:53.322Z,1765451033.322 [keepstation:KeepStation:Dive] Stopped 2025-12-11T11:03:53.322Z,1765451033.322 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-11T11:03:53.322Z,1765451033.322 [keepstation:KeepStation:Dive:A] Stopped 2025-12-11T11:03:53.322Z,1765451033.322 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-11T11:03:53.322Z,1765451033.322 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-11T11:03:53.322Z,1765451033.322 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-11T11:03:53.322Z,1765451033.322 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-11T11:03:53.323Z,1765451033.323 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-11T11:03:53.323Z,1765451033.323 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-11T11:03:53.323Z,1765451033.323 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-11T11:03:53.326Z,1765451033.326 [keepstation:NeedComms:setActive:A] Stopped 2025-12-11T11:03:53.326Z,1765451033.326 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-11T11:03:53.798Z,1765451033.798 [keepstation:NeedComms:setActive:B] Stopped 2025-12-11T11:03:53.799Z,1765451033.799 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-11T11:03:53.799Z,1765451033.799 [keepstation:NeedComms:setActive] Stopped 2025-12-11T11:03:53.799Z,1765451033.799 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-11T11:06:34.825Z,1765451194.825 [keepstation:NeedComms:D] Running Loop=1 2025-12-11T11:06:35.476Z,1765451195.476 [Radio_Surface](INFO): Powering up 2025-12-11T11:06:41.374Z,1765451201.374 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-11T11:07:11.492Z,1765451231.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T11:07:42.632Z,1765451262.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T11:08:13.776Z,1765451293.776 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T11:08:44.904Z,1765451324.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T11:08:53.464Z,1765451333.464 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002902 2025-12-11T11:11:39.460Z,1765451499.460 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-11T11:11:45.054Z,1765451505.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,111143.00,A,3643.69198,N,12210.72979,W,0.739,134.01,111225,,,A*7A 2025-12-11T11:11:45.056Z,1765451505.056 [NAL9602](INFO): GPS fix at 20251211T111143: (36.728200, -122.178830) 2025-12-11T11:11:45.067Z,1765451505.067 [UniversalFixResidualReporter](INFO): Fix residual: 69.0 %DT, over the last 590.4 m. Residual distance 407.7 m at bearing -178.3 degrees. Fix at (36.7282, -122.1788) with 471.5 m made good. 2025-12-11T11:11:45.070Z,1765451505.070 [keepstation:NeedComms:D] Stopped 2025-12-11T11:11:45.071Z,1765451505.071 [keepstation:NeedComms:E] Running Loop=1 2025-12-11T11:11:53.043Z,1765451513.043 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20251211T011500/Courier0018.lzma 2025-12-11T11:11:54.046Z,1765451514.046 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Courier0018.lzma.bak 2025-12-11T11:11:54.046Z,1765451514.046 [DataOverHttps](INFO): SBD MOMSN=26488266 2025-12-11T11:12:10.081Z,1765451530.081 [DataOverHttps](INFO): Sending 789 bytes from file Logs/20251211T011500/Express0019.lzma 2025-12-11T11:12:11.082Z,1765451531.082 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Express0019.lzma.bak 2025-12-11T11:12:11.082Z,1765451531.082 [DataOverHttps](INFO): SBD MOMSN=26488270 2025-12-11T11:12:12.561Z,1765451532.561 [keepstation:NeedComms:E] Stopped 2025-12-11T11:12:12.561Z,1765451532.561 [keepstation:NeedComms:F] Running Loop=1 2025-12-11T11:12:14.551Z,1765451534.551 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,111212.00,A,3643.69256,N,12210.72897,W,0.583,24.60,111225,,,A*49 2025-12-11T11:12:14.553Z,1765451534.553 [NAL9602](INFO): GPS fix at 20251211T111212: (36.728209, -122.178816) 2025-12-11T11:12:14.606Z,1765451534.606 [keepstation:NeedComms:F] Stopped 2025-12-11T11:12:14.606Z,1765451534.606 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-11T11:12:14.606Z,1765451534.606 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-11T11:12:14.606Z,1765451534.606 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-11T11:12:15.014Z,1765451535.014 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-11T11:12:15.014Z,1765451535.014 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-11T11:12:15.014Z,1765451535.014 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-11T11:12:15.014Z,1765451535.014 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-11T11:12:15.014Z,1765451535.014 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-11T11:12:15.015Z,1765451535.015 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-11T11:12:15.015Z,1765451535.015 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-11T11:12:15.393Z,1765451535.393 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-11T11:12:15.394Z,1765451535.394 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-11T11:12:15.394Z,1765451535.394 [keepstation:NeedComms:setInactive] Stopped 2025-12-11T11:12:15.394Z,1765451535.394 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-11T11:12:15.395Z,1765451535.395 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-11T11:12:15.395Z,1765451535.395 [keepstation:NeedComms] Stopped 2025-12-11T11:12:15.395Z,1765451535.395 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-11T11:12:15.395Z,1765451535.395 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-11T11:12:15.395Z,1765451535.395 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-11T11:12:15.815Z,1765451535.815 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-11T11:12:15.815Z,1765451535.815 [keepstation:SurfaceComms:B] Stopped 2025-12-11T11:12:15.815Z,1765451535.815 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-11T11:12:15.820Z,1765451535.820 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-11T11:12:15.820Z,1765451535.820 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-11T11:12:15.820Z,1765451535.820 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-11T11:12:15.820Z,1765451535.820 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T11:12:15.820Z,1765451535.820 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-11T11:12:16.183Z,1765451536.183 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-11T11:12:16.183Z,1765451536.183 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-11T11:12:16.183Z,1765451536.183 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-11T11:12:16.183Z,1765451536.183 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T11:12:16.183Z,1765451536.183 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-11T11:12:16.183Z,1765451536.183 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-11T11:12:16.183Z,1765451536.183 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-11T11:12:16.204Z,1765451536.204 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-11T11:12:16.589Z,1765451536.589 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-11T11:12:16.589Z,1765451536.589 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-11T11:12:16.590Z,1765451536.590 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-11T11:12:16.590Z,1765451536.590 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-11T11:12:16.590Z,1765451536.590 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-11T11:12:16.590Z,1765451536.590 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-11T11:12:16.993Z,1765451536.993 [keepstation:SurfaceComms:setStation] Stopped 2025-12-11T11:12:16.993Z,1765451536.993 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-11T11:12:16.993Z,1765451536.993 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-11T11:12:16.993Z,1765451536.993 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-11T11:12:16.993Z,1765451536.993 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-11T11:12:16.993Z,1765451536.993 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-11T11:12:17.406Z,1765451537.406 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-11T11:12:17.406Z,1765451537.406 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-11T11:12:17.406Z,1765451537.406 [keepstation:SurfaceComms:setSink] Stopped 2025-12-11T11:12:17.407Z,1765451537.407 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-11T11:12:17.407Z,1765451537.407 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-11T11:12:17.407Z,1765451537.407 [keepstation:SurfaceComms] Stopped 2025-12-11T11:12:17.407Z,1765451537.407 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-11T11:12:17.795Z,1765451537.795 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-11T11:12:17.795Z,1765451537.795 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-11T11:12:17.795Z,1765451537.795 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-11T11:12:17.795Z,1765451537.795 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-11T11:12:17.796Z,1765451537.796 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-11T11:12:17.796Z,1765451537.796 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-11T11:12:17.796Z,1765451537.796 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-11T11:12:17.796Z,1765451537.796 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T11:12:17.796Z,1765451537.796 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-11T11:12:17.796Z,1765451537.796 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-11T11:12:17.797Z,1765451537.797 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-11T11:12:17.797Z,1765451537.797 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-11T11:12:17.797Z,1765451537.797 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-11T11:12:17.797Z,1765451537.797 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-11T11:12:17.797Z,1765451537.797 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-11T11:12:17.798Z,1765451537.798 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-11T11:12:17.798Z,1765451537.798 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-11T11:12:46.588Z,1765451566.588 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T11:13:17.974Z,1765451597.974 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T11:14:15.504Z,1765451655.504 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T11:14:38.660Z,1765451678.660 [Radio_Surface](INFO): Powering down 2025-12-11T11:14:46.636Z,1765451686.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T11:14:47.640Z,1765451687.640 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-11T11:15:34.963Z,1765451734.963 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-11T11:15:34.963Z,1765451734.963 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-11T11:15:34.963Z,1765451734.963 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T11:15:34.963Z,1765451734.963 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-11T11:15:35.355Z,1765451735.355 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-11T11:15:35.356Z,1765451735.356 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T11:15:35.356Z,1765451735.356 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-11T11:15:35.356Z,1765451735.356 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-11T11:15:35.356Z,1765451735.356 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T11:15:35.356Z,1765451735.356 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-11T11:15:35.760Z,1765451735.760 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-11T11:15:35.760Z,1765451735.760 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T11:15:35.760Z,1765451735.760 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-11T11:15:35.760Z,1765451735.760 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T11:15:35.761Z,1765451735.761 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T11:15:35.761Z,1765451735.761 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-11T11:15:35.761Z,1765451735.761 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T11:15:35.761Z,1765451735.761 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-11T11:15:35.761Z,1765451735.761 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-11T11:15:35.761Z,1765451735.761 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-11T11:15:35.761Z,1765451735.761 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-11T11:15:35.762Z,1765451735.762 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-11T11:15:36.165Z,1765451736.165 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-11T11:15:36.165Z,1765451736.165 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-11T11:15:36.165Z,1765451736.165 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-11T11:15:36.165Z,1765451736.165 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-11T11:15:36.166Z,1765451736.166 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-11T11:15:36.166Z,1765451736.166 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-11T11:15:36.166Z,1765451736.166 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-11T11:15:36.167Z,1765451736.167 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-11T11:15:50.900Z,1765451750.900 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.263382 2025-12-11T11:22:29.415Z,1765452149.415 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T11:22:29.436Z,1765452149.436 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T11:26:50.348Z,1765452410.348 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-11T12:03:31.390Z,1765454611.390 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T12:03:31.394Z,1765454611.394 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T12:21:52.697Z,1765455712.697 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-11T12:42:14.839Z,1765456934.839 [keepstation:SurfaceComms] Running Loop=1 2025-12-11T12:42:14.840Z,1765456934.840 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-11T12:42:14.841Z,1765456934.841 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-11T12:42:14.841Z,1765456934.841 [keepstation:SurfaceComms:A] Stopped 2025-12-11T12:42:14.841Z,1765456934.841 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-11T12:42:14.841Z,1765456934.841 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-11T12:42:14.843Z,1765456934.843 [keepstation:NeedComms] Running Loop=1 2025-12-11T12:42:14.843Z,1765456934.843 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-11T12:42:14.843Z,1765456934.843 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-11T12:42:14.843Z,1765456934.843 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-11T12:42:14.843Z,1765456934.843 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-11T12:42:14.844Z,1765456934.844 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-11T12:42:14.844Z,1765456934.844 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-11T12:42:14.845Z,1765456934.845 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-11T12:42:14.845Z,1765456934.845 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-11T12:42:14.845Z,1765456934.845 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-11T12:42:14.846Z,1765456934.846 [keepstation:NeedComms:A] Running Loop=1 2025-12-11T12:42:14.847Z,1765456934.847 [keepstation:NeedComms:A](INFO): last time_fix was: 1765451532.000000 second since 1970/01/01T00:00:00Z 2025-12-11T12:42:14.847Z,1765456934.847 [keepstation:NeedComms:A] Stopped 2025-12-11T12:42:14.847Z,1765456934.847 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-11T12:42:14.847Z,1765456934.847 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-11T12:42:14.847Z,1765456934.847 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-11T12:42:15.230Z,1765456935.230 [keepstation:KeepStation:Dive] Stopped 2025-12-11T12:42:15.230Z,1765456935.230 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-11T12:42:15.230Z,1765456935.230 [keepstation:KeepStation:Dive:A] Stopped 2025-12-11T12:42:15.230Z,1765456935.230 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-11T12:42:15.230Z,1765456935.230 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-11T12:42:15.230Z,1765456935.230 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-11T12:42:15.230Z,1765456935.230 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-11T12:42:15.230Z,1765456935.230 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-11T12:42:15.231Z,1765456935.231 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-11T12:42:15.231Z,1765456935.231 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-11T12:42:15.234Z,1765456935.234 [keepstation:NeedComms:setActive:A] Stopped 2025-12-11T12:42:15.234Z,1765456935.234 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-11T12:42:15.643Z,1765456935.643 [keepstation:NeedComms:setActive:B] Stopped 2025-12-11T12:42:15.643Z,1765456935.643 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-11T12:42:15.643Z,1765456935.643 [keepstation:NeedComms:setActive] Stopped 2025-12-11T12:42:15.643Z,1765456935.643 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-11T12:44:33.291Z,1765457073.291 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T12:44:33.294Z,1765457073.294 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T12:44:57.602Z,1765457097.602 [keepstation:NeedComms:D] Running Loop=1 2025-12-11T12:44:58.184Z,1765457098.184 [Radio_Surface](INFO): Powering up 2025-12-11T12:45:03.960Z,1765457103.960 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-11T12:45:30.462Z,1765457130.462 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-11T12:45:30.544Z,1765457130.544 [NAL9602](ERROR): received: +CSQ:0 OK418, 0, 0, 0, 0 OK 2025-12-11T12:45:34.080Z,1765457134.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T12:46:05.204Z,1765457165.204 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T12:46:36.328Z,1765457196.328 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T12:47:07.456Z,1765457227.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T12:47:15.868Z,1765457235.868 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002888 2025-12-11T12:49:32.867Z,1765457372.867 [NAL9602](INFO): SBD MO Status=2, MOMSN=24419, MT Status=2, MTMSN=0 2025-12-11T12:49:32.867Z,1765457372.867 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T12:49:57.107Z,1765457397.107 [NAL9602](INFO): SBD MO Status=2, MOMSN=24419, MT Status=2, MTMSN=0 2025-12-11T12:49:57.107Z,1765457397.107 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T12:50:18.518Z,1765457418.518 [NAL9602](INFO): SBD MO Status=2, MOMSN=24419, MT Status=2, MTMSN=0 2025-12-11T12:50:18.518Z,1765457418.518 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T12:50:18.922Z,1765457418.922 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-11T12:50:20.129Z,1765457420.129 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,125018.00,A,3643.67509,N,12210.93201,W,1.322,94.41,111225,,,A*4B 2025-12-11T12:50:20.131Z,1765457420.131 [NAL9602](INFO): GPS fix at 20251211T125018: (36.727918, -122.182200) 2025-12-11T12:50:20.143Z,1765457420.143 [UniversalFixResidualReporter](INFO): Fix residual: 82.6 %DT, over the last 626.3 m. Residual distance 517.1 m at bearing 157.0 degrees. Fix at (36.7279, -122.1822) with 303.3 m made good. 2025-12-11T12:50:20.146Z,1765457420.146 [keepstation:NeedComms:D] Stopped 2025-12-11T12:50:20.146Z,1765457420.146 [keepstation:NeedComms:E] Running Loop=1 2025-12-11T12:50:27.119Z,1765457427.119 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20251211T011500/Courier0021.lzma 2025-12-11T12:50:28.122Z,1765457428.122 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Courier0021.lzma.bak 2025-12-11T12:50:28.122Z,1765457428.122 [DataOverHttps](INFO): SBD MOMSN=26488710 2025-12-11T12:50:46.098Z,1765457446.098 [DataOverHttps](INFO): Sending 914 bytes from file Logs/20251211T011500/Express0022.lzma 2025-12-11T12:50:47.098Z,1765457447.098 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Express0022.lzma.bak 2025-12-11T12:50:47.098Z,1765457447.098 [DataOverHttps](INFO): SBD MOMSN=26488713 2025-12-11T12:50:48.514Z,1765457448.514 [keepstation:NeedComms:E] Stopped 2025-12-11T12:50:48.515Z,1765457448.515 [keepstation:NeedComms:F] Running Loop=1 2025-12-11T12:50:50.515Z,1765457450.515 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,125048.00,A,3643.67279,N,12210.93226,W,0.272,179.06,111225,,,D*7A 2025-12-11T12:50:50.517Z,1765457450.517 [NAL9602](INFO): GPS fix at 20251211T125048: (36.727880, -122.182204) 2025-12-11T12:50:50.565Z,1765457450.565 [keepstation:NeedComms:F] Stopped 2025-12-11T12:50:50.565Z,1765457450.565 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-11T12:50:50.565Z,1765457450.565 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-11T12:50:50.565Z,1765457450.565 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-11T12:50:50.975Z,1765457450.975 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-11T12:50:50.975Z,1765457450.975 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-11T12:50:50.975Z,1765457450.975 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-11T12:50:50.980Z,1765457450.980 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-11T12:50:50.980Z,1765457450.980 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-11T12:50:50.980Z,1765457450.980 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-11T12:50:50.980Z,1765457450.980 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-11T12:50:51.386Z,1765457451.386 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-11T12:50:51.386Z,1765457451.386 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-11T12:50:51.386Z,1765457451.386 [keepstation:NeedComms:setInactive] Stopped 2025-12-11T12:50:51.387Z,1765457451.387 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-11T12:50:51.387Z,1765457451.387 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-11T12:50:51.387Z,1765457451.387 [keepstation:NeedComms] Stopped 2025-12-11T12:50:51.387Z,1765457451.387 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-11T12:50:51.387Z,1765457451.387 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-11T12:50:51.392Z,1765457451.392 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-11T12:50:51.798Z,1765457451.798 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-11T12:50:51.799Z,1765457451.799 [keepstation:SurfaceComms:B] Stopped 2025-12-11T12:50:51.799Z,1765457451.799 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-11T12:50:51.799Z,1765457451.799 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-11T12:50:51.799Z,1765457451.799 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-11T12:50:51.799Z,1765457451.799 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-11T12:50:51.799Z,1765457451.799 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T12:50:51.799Z,1765457451.799 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-11T12:50:52.172Z,1765457452.172 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-11T12:50:52.172Z,1765457452.172 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-11T12:50:52.172Z,1765457452.172 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-11T12:50:52.172Z,1765457452.172 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T12:50:52.172Z,1765457452.172 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-11T12:50:52.172Z,1765457452.172 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-11T12:50:52.173Z,1765457452.173 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-11T12:50:52.173Z,1765457452.173 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-11T12:50:52.580Z,1765457452.580 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-11T12:50:52.581Z,1765457452.581 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-11T12:50:52.581Z,1765457452.581 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-11T12:50:52.581Z,1765457452.581 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-11T12:50:52.581Z,1765457452.581 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-11T12:50:52.581Z,1765457452.581 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-11T12:50:52.965Z,1765457452.965 [keepstation:SurfaceComms:setStation] Stopped 2025-12-11T12:50:52.966Z,1765457452.966 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-11T12:50:52.966Z,1765457452.966 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-11T12:50:52.966Z,1765457452.966 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-11T12:50:52.966Z,1765457452.966 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-11T12:50:52.966Z,1765457452.966 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-11T12:50:53.365Z,1765457453.365 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-11T12:50:53.366Z,1765457453.366 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-11T12:50:53.366Z,1765457453.366 [keepstation:SurfaceComms:setSink] Stopped 2025-12-11T12:50:53.366Z,1765457453.366 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-11T12:50:53.366Z,1765457453.366 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-11T12:50:53.366Z,1765457453.366 [keepstation:SurfaceComms] Stopped 2025-12-11T12:50:53.366Z,1765457453.366 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-11T12:50:53.755Z,1765457453.755 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-11T12:50:53.755Z,1765457453.755 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-11T12:50:53.755Z,1765457453.755 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-11T12:50:53.755Z,1765457453.755 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-11T12:50:53.773Z,1765457453.773 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-11T12:50:53.773Z,1765457453.773 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-11T12:50:53.773Z,1765457453.773 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-11T12:50:53.773Z,1765457453.773 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T12:50:53.773Z,1765457453.773 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-11T12:50:53.773Z,1765457453.773 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-11T12:50:53.773Z,1765457453.773 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-11T12:50:53.773Z,1765457453.773 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-11T12:50:53.773Z,1765457453.773 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-11T12:50:53.774Z,1765457453.774 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-11T12:50:53.774Z,1765457453.774 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-11T12:50:53.774Z,1765457453.774 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-11T12:50:53.774Z,1765457453.774 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-11T12:51:22.429Z,1765457482.429 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T12:52:50.232Z,1765457570.232 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T12:53:12.800Z,1765457592.800 [Radio_Surface](INFO): Powering down 2025-12-11T12:53:21.364Z,1765457601.364 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T12:53:22.368Z,1765457602.368 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-11T12:53:58.396Z,1765457638.396 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-11T12:53:58.396Z,1765457638.396 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-11T12:53:58.396Z,1765457638.396 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T12:53:58.397Z,1765457638.397 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-11T12:53:58.790Z,1765457638.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-11T12:53:58.790Z,1765457638.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-11T12:53:58.790Z,1765457638.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-11T12:53:58.790Z,1765457638.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-11T12:53:58.790Z,1765457638.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T12:53:58.790Z,1765457638.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-11T12:53:59.190Z,1765457639.190 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-11T12:53:59.190Z,1765457639.190 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T12:53:59.190Z,1765457639.190 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-11T12:53:59.190Z,1765457639.190 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-11T12:53:59.191Z,1765457639.191 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T12:53:59.191Z,1765457639.191 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-11T12:53:59.191Z,1765457639.191 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T12:53:59.191Z,1765457639.191 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-11T12:53:59.191Z,1765457639.191 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-11T12:53:59.191Z,1765457639.191 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-11T12:53:59.191Z,1765457639.191 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-11T12:53:59.192Z,1765457639.192 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-11T12:53:59.600Z,1765457639.600 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-11T12:53:59.600Z,1765457639.600 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-11T12:53:59.600Z,1765457639.600 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-11T12:53:59.600Z,1765457639.600 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-11T12:53:59.600Z,1765457639.600 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-11T12:53:59.600Z,1765457639.600 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-11T12:53:59.601Z,1765457639.601 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-11T12:53:59.602Z,1765457639.602 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-11T12:54:57.780Z,1765457697.780 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.759201 2025-12-11T13:06:04.527Z,1765458364.527 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-11T13:16:23.113Z,1765458983.113 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T13:25:35.307Z,1765459535.307 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T13:25:35.311Z,1765459535.311 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T13:57:26.658Z,1765461446.658 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-11T14:06:37.312Z,1765461997.312 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-11T14:06:37.316Z,1765461997.316 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-11T14:20:50.586Z,1765462850.586 [keepstation:SurfaceComms] Running Loop=1 2025-12-11T14:20:50.586Z,1765462850.586 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-11T14:20:50.586Z,1765462850.586 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-11T14:20:50.586Z,1765462850.586 [keepstation:SurfaceComms:A] Stopped 2025-12-11T14:20:50.586Z,1765462850.586 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-11T14:20:50.587Z,1765462850.587 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-11T14:20:50.588Z,1765462850.588 [keepstation:NeedComms] Running Loop=1 2025-12-11T14:20:50.589Z,1765462850.589 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-11T14:20:50.589Z,1765462850.589 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-11T14:20:50.589Z,1765462850.589 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-11T14:20:50.589Z,1765462850.589 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-11T14:20:50.589Z,1765462850.589 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-11T14:20:50.590Z,1765462850.590 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-11T14:20:50.590Z,1765462850.590 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-11T14:20:50.590Z,1765462850.590 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-11T14:20:50.591Z,1765462850.591 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-11T14:20:50.591Z,1765462850.591 [keepstation:NeedComms:A] Running Loop=1 2025-12-11T14:20:50.593Z,1765462850.593 [keepstation:NeedComms:A](INFO): last time_fix was: 1765457448.000000 second since 1970/01/01T00:00:00Z 2025-12-11T14:20:50.593Z,1765462850.593 [keepstation:NeedComms:A] Stopped 2025-12-11T14:20:50.593Z,1765462850.593 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-11T14:20:50.593Z,1765462850.593 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-11T14:20:50.593Z,1765462850.593 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-11T14:20:50.978Z,1765462850.978 [keepstation:KeepStation:Dive] Stopped 2025-12-11T14:20:50.979Z,1765462850.979 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-11T14:20:50.979Z,1765462850.979 [keepstation:KeepStation:Dive:A] Stopped 2025-12-11T14:20:50.979Z,1765462850.979 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-11T14:20:50.979Z,1765462850.979 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-11T14:20:50.979Z,1765462850.979 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-11T14:20:50.979Z,1765462850.979 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-11T14:20:50.979Z,1765462850.979 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-11T14:20:50.979Z,1765462850.979 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-11T14:20:50.979Z,1765462850.979 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-11T14:20:50.982Z,1765462850.982 [keepstation:NeedComms:setActive:A] Stopped 2025-12-11T14:20:50.983Z,1765462850.983 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-11T14:20:51.392Z,1765462851.392 [keepstation:NeedComms:setActive:B] Stopped 2025-12-11T14:20:51.392Z,1765462851.392 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-11T14:20:51.393Z,1765462851.393 [keepstation:NeedComms:setActive] Stopped 2025-12-11T14:20:51.393Z,1765462851.393 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-11T14:23:42.788Z,1765463022.788 [keepstation:NeedComms:D] Running Loop=1 2025-12-11T14:23:43.376Z,1765463023.376 [Radio_Surface](INFO): Powering up 2025-12-11T14:23:48.772Z,1765463028.772 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-11T14:25:55.688Z,1765463155.688 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-12-11T14:26:10.240Z,1765463170.240 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002914 2025-12-11T14:27:52.037Z,1765463272.037 [NAL9602](INFO): SBD MO Status=2, MOMSN=24419, MT Status=2, MTMSN=0 2025-12-11T14:27:52.037Z,1765463272.037 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-11T14:28:40.113Z,1765463320.113 [NAL9602](INFO): SBD MO Status=1, MOMSN=24419, MT Status=0, MTMSN=0 2025-12-11T14:28:40.114Z,1765463320.114 [NAL9602](INFO): No messages in MT queue 2025-12-11T14:28:41.325Z,1765463321.325 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,142839.00,A,3643.86718,N,12210.60182,W,0.117,325.49,111225,,,D*79 2025-12-11T14:28:41.328Z,1765463321.328 [NAL9602](INFO): GPS fix at 20251211T142839: (36.731120, -122.176697) 2025-12-11T14:28:41.361Z,1765463321.361 [UniversalFixResidualReporter](INFO): Fix residual: 98.0 %DT, over the last 675.3 m. Residual distance 661.5 m at bearing -173.0 degrees. Fix at (36.7311, -122.1767) with 608.8 m made good. 2025-12-11T14:28:41.363Z,1765463321.363 [keepstation:NeedComms:D] Stopped 2025-12-11T14:28:41.368Z,1765463321.368 [keepstation:NeedComms:E] Running Loop=1 2025-12-11T14:28:50.087Z,1765463330.087 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20251211T011500/Courier0024.lzma 2025-12-11T14:28:52.095Z,1765463332.095 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Courier0024.lzma.bak 2025-12-11T14:28:52.095Z,1765463332.095 [DataOverHttps](INFO): SBD MOMSN=26488994 2025-12-11T14:29:09.097Z,1765463349.097 [DataOverHttps](INFO): Sending 864 bytes from file Logs/20251211T011500/Express0025.lzma 2025-12-11T14:29:11.102Z,1765463351.102 [DataOverHttps](INFO): Moved sent file to Logs/20251211T011500/Express0025.lzma.bak 2025-12-11T14:29:11.102Z,1765463351.102 [DataOverHttps](INFO): SBD MOMSN=26488997 2025-12-11T14:29:12.462Z,1765463352.462 [keepstation:NeedComms:E] Stopped 2025-12-11T14:29:12.462Z,1765463352.462 [keepstation:NeedComms:F] Running Loop=1 2025-12-11T14:29:14.451Z,1765463354.451 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,142912.00,A,3643.86750,N,12210.60260,W,0.583,58.60,111225,,,D*49 2025-12-11T14:29:14.461Z,1765463354.461 [NAL9602](INFO): GPS fix at 20251211T142912: (36.731125, -122.176710) 2025-12-11T14:29:14.494Z,1765463354.494 [keepstation:NeedComms:F] Stopped 2025-12-11T14:29:14.494Z,1765463354.494 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-11T14:29:14.494Z,1765463354.494 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-11T14:29:14.494Z,1765463354.494 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-11T14:29:14.893Z,1765463354.893 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-11T14:29:14.894Z,1765463354.894 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-11T14:29:14.894Z,1765463354.894 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-11T14:29:14.894Z,1765463354.894 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-11T14:29:14.894Z,1765463354.894 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-11T14:29:14.894Z,1765463354.894 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-11T14:29:14.894Z,1765463354.894 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-11T14:29:15.311Z,1765463355.311 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-11T14:29:15.311Z,1765463355.311 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-11T14:29:15.311Z,1765463355.311 [keepstation:NeedComms:setInactive] Stopped 2025-12-11T14:29:15.316Z,1765463355.316 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-11T14:29:15.317Z,1765463355.317 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-11T14:29:15.317Z,1765463355.317 [keepstation:NeedComms] Stopped 2025-12-11T14:29:15.317Z,1765463355.317 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-11T14:29:15.317Z,1765463355.317 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-11T14:29:15.317Z,1765463355.317 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-11T14:29:15.731Z,1765463355.731 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-11T14:29:15.731Z,1765463355.731 [keepstation:SurfaceComms:B] Stopped 2025-12-11T14:29:15.731Z,1765463355.731 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-11T14:29:15.731Z,1765463355.731 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-11T14:29:15.731Z,1765463355.731 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-11T14:29:15.736Z,1765463355.736 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-11T14:29:15.736Z,1765463355.736 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T14:29:15.736Z,1765463355.736 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-11T14:29:16.093Z,1765463356.093 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-11T14:29:16.094Z,1765463356.094 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-11T14:29:16.094Z,1765463356.094 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-11T14:29:16.094Z,1765463356.094 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-11T14:29:16.094Z,1765463356.094 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-11T14:29:16.094Z,1765463356.094 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-11T14:29:16.094Z,1765463356.094 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-11T14:29:16.094Z,1765463356.094 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-11T14:29:16.499Z,1765463356.499 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-11T14:29:16.499Z,1765463356.499 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-11T14:29:16.499Z,1765463356.499 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-11T14:29:16.499Z,1765463356.499 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-11T14:29:16.499Z,1765463356.499 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-11T14:29:16.504Z,1765463356.504 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-11T14:29:16.893Z,1765463356.893 [keepstation:SurfaceComms:setStation] Stopped 2025-12-11T14:29:16.893Z,1765463356.893 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-11T14:29:16.893Z,1765463356.893 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-11T14:29:16.893Z,1765463356.893 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-11T14:29:16.893Z,1765463356.893 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-11T14:29:16.893Z,1765463356.893 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-11T14:29:17.312Z,1765463357.312 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-11T14:29:17.313Z,1765463357.313 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-11T14:29:17.313Z,1765463357.313 [keepstation:SurfaceComms:setSink] Stopped 2025-12-11T14:29:17.314Z,1765463357.314 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-11T14:29:17.314Z,1765463357.314 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-11T14:29:17.315Z,1765463357.315 [keepstation:SurfaceComms] Stopped 2025-12-11T14:29:17.315Z,1765463357.315 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-11T14:29:17.704Z,1765463357.704 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-11T14:29:17.705Z,1765463357.705 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-11T14:29:17.705Z,1765463357.705 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-11T14:29:17.705Z,1765463357.705 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-11T14:29:17.705Z,1765463357.705 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-11T14:29:17.705Z,1765463357.705 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-11T14:29:17.705Z,1765463357.705 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-11T14:29:17.706Z,1765463357.706 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-11T14:29:17.706Z,1765463357.706 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-11T14:29:17.706Z,1765463357.706 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-11T14:29:17.706Z,1765463357.706 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-11T14:29:17.706Z,1765463357.706 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-11T14:29:17.706Z,1765463357.706 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-11T14:29:17.707Z,1765463357.707 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-11T14:29:17.707Z,1765463357.707 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-11T14:29:17.707Z,1765463357.707 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-11T14:29:17.707Z,1765463357.707 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-11T14:29:46.365Z,1765463386.365 [NAL9602](INFO): Not Powering down - fast GPS