2025-09-08T03:30:00.438Z,1757302200.438 [CommandExec](IMPORTANT): got command restart logs 2025-09-08T03:30:11.823Z,1757302211.823 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist 2025-09-08T03:30:11.895Z,1757302211.895 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-09-08T03:30:11.895Z,1757302211.895 [MissionManager](INFO): Uninitializing Mission profile_station 2025-09-08T03:30:11.895Z,1757302211.895 [profile_station] Stopped 2025-09-08T03:30:11.895Z,1757302211.895 [profile_station](DEBUG): Aggregate::uninitialize profile_station 2025-09-08T03:30:11.895Z,1757302211.895 [profile_station:Science] Stopped 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:Science:B] Stopped 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:Science:C] Stopped 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:Science:E] Stopped 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:Science:OceanCurrent] Stopped 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:Science:OceanCurrent:A.] Stopped 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:Science:PeakDetectChl] Stopped 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth] Stopped 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Uninitialize. 2025-09-08T03:30:11.896Z,1757302211.896 [profile_station:StandardEnvelopes] Stopped 2025-09-08T03:30:11.896Z,1757302211.897 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:BackseatDriver] Stopped 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:BackseatDriver](DEBUG): Aggregate::uninitialize profile_station:BackseatDriver 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:BackseatDriver:A.BackseatDriver] Stopped 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:PowerOnly] Stopped 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:PowerOnly](DEBUG): Aggregate::uninitialize profile_station:PowerOnly 2025-09-08T03:30:11.897Z,1757302211.897 [profile_station:PowerOnly:E.Wait] Stopped 2025-09-08T03:30:11.898Z,1757302211.898 [profile_station:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-09-08T03:30:11.898Z,1757302211.898 [profile_station:DiveCmd] Stopped 2025-09-08T03:30:11.898Z,1757302211.898 [profile_station:DiveCmd](DEBUG): Aggregate::uninitialize profile_station:DiveCmd 2025-09-08T03:30:11.898Z,1757302211.898 [profile_station:DiveCmd:SurfaceSink] Stopped 2025-09-08T03:30:11.898Z,1757302211.898 [profile_station:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:SurfaceSink 2025-09-08T03:30:11.898Z,1757302211.898 [profile_station:DiveCmd:YoYoDive] Stopped 2025-09-08T03:30:11.906Z,1757302211.906 [profile_station:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive 2025-09-08T03:30:11.906Z,1757302211.906 [profile_station:DiveCmd:YoYoDive:dive] Stopped 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch] Stopped 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed] Stopped 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize. 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2025-09-08T03:30:11.907Z,1757302211.907 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo] Stopped 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent. 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:CircleWrapper] Stopped 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:CircleWrapper:A] Stopped 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:CircleWrapper:Dive] Stopped 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:CircleWrapper:Dive](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:CircleWrapper:Dive:A] Stopped 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:CircleWrapper:Dive:A](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive:A 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:CircleWrapper:C.Circle] Stopped 2025-09-08T03:30:11.908Z,1757302211.908 [profile_station:CircleWrapper:C.Circle](DEBUG): Uninitialize. 2025-09-08T03:30:12.121Z,1757302212.121 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-09-08T03:30:12.122Z,1757302212.122 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-09-08T03:30:12.123Z,1757302212.123 [WetLabsBB2FL](INFO): Powering down 2025-09-08T03:30:12.145Z,1757302212.145 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-09-08T03:30:13.004Z,1757302213.004 [BackseatComponent](INFO): Sent LCM shutdown request. 2025-09-08T03:30:13.004Z,1757302213.004 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.). 2025-09-08T03:30:14.677Z,1757302214.677 [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-09-08T03:30:14.721Z,1757302214.721 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-09-08T03:30:14.735Z,1757302214.735 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-09-08T03:30:14.747Z,1757302214.747 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-09-08T03:30:14.749Z,1757302214.749 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-09-08T03:30:14.756Z,1757302214.756 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-09-08T03:30:14.770Z,1757302214.770 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-09-08T03:30:14.773Z,1757302214.773 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-09-08T03:30:14.783Z,1757302214.783 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-09-08T03:30:14.785Z,1757302214.785 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-09-08T03:30:14.794Z,1757302214.794 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool 2025-09-08T03:30:14.804Z,1757302214.804 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m 2025-09-08T03:30:14.815Z,1757302214.815 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min 2025-09-08T03:30:14.823Z,1757302214.823 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-09-08T03:30:14.825Z,1757302214.825 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-09-08T03:30:14.833Z,1757302214.833 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count 2025-09-08T03:30:14.837Z,1757302214.837 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count 2025-09-08T03:30:14.848Z,1757302214.848 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count 2025-09-08T03:30:14.850Z,1757302214.850 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count 2025-09-08T03:30:14.856Z,1757302214.856 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count 2025-09-08T03:30:14.863Z,1757302214.863 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool 2025-09-08T03:30:14.863Z,1757302214.863 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-09-08T03:30:14.864Z,1757302214.864 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-09-08T03:30:14.865Z,1757302214.865 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-09-08T03:30:15.333Z,1757302215.333 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2025-09-08T03:30:15.346Z,1757302215.346 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-09-08T03:30:15.349Z,1757302215.349 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-09-08T03:30:15.357Z,1757302215.357 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-09-08T03:30:15.363Z,1757302215.363 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-09-08T03:30:15.366Z,1757302215.366 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-09-08T03:30:15.372Z,1757302215.372 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s 2025-09-08T03:30:15.378Z,1757302215.378 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-09-08T03:30:15.381Z,1757302215.381 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-09-08T03:30:15.395Z,1757302215.395 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-09-08T03:30:15.428Z,1757302215.428 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-09-08T03:30:15.428Z,1757302215.428 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-09-08T03:30:15.429Z,1757302215.429 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-09-08T03:30:15.610Z,1757302215.610 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2025-09-08T03:30:15.614Z,1757302215.614 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-09-08T03:30:15.616Z,1757302215.616 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2025-09-08T03:30:15.627Z,1757302215.627 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-09-08T03:30:15.629Z,1757302215.629 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-09-08T03:30:15.630Z,1757302215.630 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-09-08T03:30:15.648Z,1757302215.648 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-09-08T03:30:15.663Z,1757302215.663 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-09-08T03:30:15.679Z,1757302215.679 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-09-08T03:30:15.680Z,1757302215.680 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-09-08T03:30:15.691Z,1757302215.691 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-09-08T03:30:15.743Z,1757302215.743 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-09-08T03:30:15.748Z,1757302215.748 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-09-08T03:30:15.750Z,1757302215.750 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-09-08T03:30:15.759Z,1757302215.759 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-09-08T03:30:15.759Z,1757302215.759 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-09-08T03:30:15.760Z,1757302215.760 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-09-08T03:30:16.136Z,1757302216.136 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-09-08T03:30:16.163Z,1757302216.163 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-09-08T03:30:16.165Z,1757302216.165 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-09-08T03:30:16.175Z,1757302216.175 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-09-08T03:30:16.177Z,1757302216.177 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-09-08T03:30:16.183Z,1757302216.183 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool 2025-09-08T03:30:16.194Z,1757302216.194 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-09-08T03:30:16.200Z,1757302216.200 [keepstation:H.Pitch](DEBUG): Construct. 2025-09-08T03:30:16.237Z,1757302216.237 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-09-08T03:30:16.248Z,1757302216.248 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct. 2025-09-08T03:30:16.253Z,1757302216.253 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct. 2025-09-08T03:30:16.279Z,1757302216.279 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-09-08T03:30:16.282Z,1757302216.282 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct. 2025-09-08T03:30:16.295Z,1757302216.295 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct. 2025-09-08T03:30:16.304Z,1757302216.304 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct. 2025-09-08T03:30:16.344Z,1757302216.344 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation. 2025-09-08T03:30:16.385Z,1757302216.385 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-09-08T03:30:16.434Z,1757302216.434 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait. 2025-09-08T03:30:16.476Z,1757302216.476 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission keepstation { """ Vehicle transits to desired waypoint (or stays put if none is commanded) and stays within the specified radius. """ arguments { MissionTimeout = 4 hour """ Maximum duration of mission """ NeedCommsTime = 45 minute """ How often to surface for communications """ Latitude = NaN degree """ Latitude of waypoint to hold. If NaN, hold the latitude at start of mission. """ Longitude = NaN degree """ Longitude of waypoint to hold. If NaN, hold the longitude at start of mission. """ Depth = 30 meter """ Depth held during drift mode """ ApproachDepth = 15 meter """ Depth for initial approach to station. """ DepthDeadband = 4 meter """ How much vertical drift from the specified depth is allowed in drift mode """ Speed = 0.75 meter_per_second """ Vehicle transit speed. """ Radius = 200 meter """ Radius of circle around waypoint to hold. """ SinkOffSurface = false """ If true, use buoyancy only to decend to SinkDepth before driving to first target depth """ SinkDepth = 10 meter """ Initial depth to decend using only buoyancy before driving """ SinkDepthTimeout = 10 minute """ Max wait time for the vehicle to reach the target depth """ MaxDepth = 45 meter """ Maximum allowable depth during the mission. """ MinOffshore = 2 kilometer """ Minimum distance from the shoreline to maintain """ } output { DiveMode = 0 count """ Mission variable (don't change). The mission sets this variable to switch between dive modes. Initialized to DoingComms. """ DoingComms = 0 count """ Mission variable (don't change). The mission will run in this mode when doing surface comms. """ SurfaceSink = 1 count """ Mission variable (don't change). The mission will run in this mode when sinking from the surface using only buoyancy. """ TransitToStation = 2 count """ Mission variable (don't change). The mission will run in this mode when performing the transit to station """ StationKeep = 3 count """ Mission variable (don't change). The mission will run in this mode when keeping station """ OnStation = false """ Mission variable (don't change). Determines last mode (Transit or StationKeep) to resume after needcomms """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Pitch { run in parallel set massPosition = Control:VerticalControl.massDefault } aggregate SurfaceComms { run when ( called or ( elapsed ( Universal:time_fix ) > NeedCommsTime ) ) assign in sequence DiveMode = DoingComms call priorityHere=false refId="NeedComms" aggregate setTransit { run in sequence break if ( SinkOffSurface or OnStation ) assign in sequence DiveMode = TransitToStation } aggregate setStation { run in sequence break if ( SinkOffSurface or not OnStation ) assign in sequence DiveMode = StationKeep } aggregate setSink { run in sequence break if ( not SinkOffSurface ) assign in sequence DiveMode = SurfaceSink } } call id="StartingMission" priorityHere=false refId="SurfaceComms" aggregate DiveCmd { run when ( called ) aggregate SurfaceSink { run while ( DiveMode == SurfaceSink ) aggregate sink { run in sequence behavior Guidance:Buoyancy id="BuoyancyHold" { run in parallel set position = NaN cc } behavior Guidance:SetSpeed { run in parallel set speed = 0 m/s } syslog info "Sinking to " + SinkDepth~meter behavior Guidance:Pitch { run in sequence timeout duration=SinkDepthTimeout { syslog important "Timed out while trying to reach target sink depth at current depth of " + Universal:depth~meter } set depth = SinkDepth } # Once we've sunk, either resume transit or station keeping depths and speeds aggregate sinkToTransit { run in sequence break if ( OnStation ) assign in sequence DiveMode = TransitToStation } aggregate sinkToStation { run in sequence break if ( not OnStation ) assign in sequence DiveMode = StationKeep } } } aggregate TransitToStation { run while ( DiveMode == TransitToStation ) aggregate dive { run in sequence behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch { run in parallel set depth = ApproachDepth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } } } aggregate StationKeep { run while ( DiveMode == StationKeep ) assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:KeepStation { run in parallel set latitude = Latitude set longitude = Longitude set radius = Radius set speed = Speed } } } aggregate TransitToStation { """ Need a separate aggregate for transit if we want to specify a different depth for the approach. """ run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude } assign in sequence DiveMode = StationKeep assign in sequence OnStation = true } call id="OnStation" priorityHere=false refId="NeedComms" aggregate KeepStation { run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Wait { """ Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. """ run in sequence set duration = MissionTimeout } } } 2025-09-08T03:30:16.477Z,1757302216.477 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-09-08T03:30:16.801Z,1757302216.801 [MissionManager](IMPORTANT): Started mission Default 2025-09-08T03:30:16.802Z,1757302216.802 [Default] Running Loop=1 2025-09-08T03:30:16.802Z,1757302216.802 [Default](DEBUG): Aggregate::initialize Default 2025-09-08T03:30:16.802Z,1757302216.802 [Default:B.GoToSurface] Running Loop=1 2025-09-08T03:30:16.802Z,1757302216.802 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-08T03:30:16.818Z,1757302216.818 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-09-08T03:30:16.819Z,1757302216.819 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-09-08T03:30:16.819Z,1757302216.819 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-09-08T03:30:16.819Z,1757302216.819 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-09-08T03:30:16.820Z,1757302216.820 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-08T03:30:16.820Z,1757302216.820 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-08T03:30:16.820Z,1757302216.820 [Default:A.Wait] Running Loop=1 2025-09-08T03:30:16.820Z,1757302216.820 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-09-08T03:30:17.475Z,1757302217.475 [Radio_Surface](INFO): Powering up 2025-09-08T03:30:22.743Z,1757302222.743 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T03:30:29.835Z,1757302229.835 [Default:A.Wait](INFO): Done Waiting. 2025-09-08T03:30:29.835Z,1757302229.835 [Default:A.Wait] Stopped 2025-09-08T03:30:29.835Z,1757302229.835 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-09-08T03:30:31.167Z,1757302231.167 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2025-09-08T03:30:31.168Z,1757302231.168 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2025-09-08T03:30:31.168Z,1757302231.168 [LcmUniversalReporter](INFO): Deactivating messaging. 2025-09-08T03:30:40.524Z,1757302240.524 [ElevatorOffsetCalculator](IMPORTANT): Completed estimation for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.83 mm. Average elevator angle=-2.894831 +/- 0.077306 deg (conf. level 99.90%, sigma: 0.463295 deg). 2025-09-08T03:30:40.534Z,1757302240.534 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm. 2025-09-08T03:30:40.541Z,1757302240.541 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 30.83 mm. 2025-09-08T03:30:40.573Z,1757302240.573 [ElevatorOffsetCalculator](IMPORTANT): Completed estimation for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 30.83 mm. Average elevator angle=2.906869 +/- 0.075957 deg (conf. level 99.90%, sigma: 0.460905 deg). 2025-09-08T03:30:40.619Z,1757302240.619 [Default:CheckIn] Running Loop=1 2025-09-08T03:30:40.619Z,1757302240.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-08T03:30:40.619Z,1757302240.619 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-08T03:30:44.919Z,1757302244.919 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 11 hour 2025-09-08T03:30:44.920Z,1757302244.920 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute 2025-09-08T03:30:44.921Z,1757302244.921 [CommandExec](IMPORTANT): got command set keepstation.Depth 20 meter 2025-09-08T03:30:44.974Z,1757302244.974 [CommandExec](IMPORTANT): got command set keepstation.Speed 1 meter_per_second 2025-09-08T03:30:44.975Z,1757302244.975 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool 2025-09-08T03:30:52.863Z,1757302252.863 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:31:00.879Z,1757302260.879 [DVL_micro](ERROR): Failed to parse: :SA,-01.90,+10.09,310.1 2025-09-08T03:31:04.759Z,1757302264.759 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 45 meter 2025-09-08T03:31:04.760Z,1757302264.760 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second 2025-09-08T03:31:04.761Z,1757302264.761 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 10 minute 2025-09-08T03:31:04.761Z,1757302264.761 [CommandExec](IMPORTANT): got command run 2025-09-08T03:31:04.763Z,1757302264.763 [CommandExec](IMPORTANT): Running 2025-09-08T03:31:04.922Z,1757302264.922 [Default] Stopped 2025-09-08T03:31:04.922Z,1757302264.922 [Default](DEBUG): Aggregate::uninitialize Default 2025-09-08T03:31:04.922Z,1757302264.922 [Default:B.GoToSurface] Stopped 2025-09-08T03:31:04.923Z,1757302264.923 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-08T03:31:04.923Z,1757302264.923 [Default:CheckIn] Stopped 2025-09-08T03:31:04.923Z,1757302264.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-08T03:31:04.923Z,1757302264.923 [Default:CheckIn:Read_GPS] Stopped 2025-09-08T03:31:04.923Z,1757302264.923 [MissionManager](IMPORTANT): Started mission keepstation 2025-09-08T03:31:04.923Z,1757302264.923 [keepstation] Running Loop=1 2025-09-08T03:31:04.923Z,1757302264.923 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-09-08T03:31:04.923Z,1757302264.923 [keepstation:StandardEnvelopes] Running Loop=1 2025-09-08T03:31:04.923Z,1757302264.923 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-09-08T03:31:04.924Z,1757302264.924 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-09-08T03:31:04.924Z,1757302264.924 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-09-08T03:31:04.924Z,1757302264.924 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-09-08T03:31:04.924Z,1757302264.924 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-09-08T03:31:04.924Z,1757302264.924 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-09-08T03:31:04.924Z,1757302264.924 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-09-08T03:31:04.924Z,1757302264.924 [keepstation:BackseatDriver] Running Loop=1 2025-09-08T03:31:04.924Z,1757302264.924 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-09-08T03:31:04.924Z,1757302264.924 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-09-08T03:31:04.925Z,1757302264.925 [keepstation:PowerOnly] Running Loop=1 2025-09-08T03:31:04.925Z,1757302264.925 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-09-08T03:31:04.925Z,1757302264.925 [keepstation:PowerOnly:A] Running Loop=1 2025-09-08T03:31:04.925Z,1757302264.925 [keepstation:PowerOnly:B] Running Loop=1 2025-09-08T03:31:04.925Z,1757302264.925 [keepstation:PowerOnly:C] Running Loop=1 2025-09-08T03:31:04.925Z,1757302264.925 [keepstation:PowerOnly:D] Running Loop=1 2025-09-08T03:31:04.925Z,1757302264.925 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-09-08T03:31:04.925Z,1757302264.925 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-09-08T03:31:04.925Z,1757302264.925 [keepstation:H.Pitch] Running Loop=1 2025-09-08T03:31:04.925Z,1757302264.925 [keepstation:H.Pitch](DEBUG): Initialize. 2025-09-08T03:31:04.926Z,1757302264.926 [keepstation:B] Running Loop=1 2025-09-08T03:31:04.927Z,1757302264.927 [keepstation:H.Pitch] Running Loop=1 2025-09-08T03:31:04.928Z,1757302264.928 [keepstation:PowerOnly] Running Loop=1 2025-09-08T03:31:04.928Z,1757302264.928 [keepstation:PowerOnly:D] Stopped 2025-09-08T03:31:04.928Z,1757302264.928 [keepstation:PowerOnly:C] Stopped 2025-09-08T03:31:04.928Z,1757302264.928 [keepstation:PowerOnly:B] Stopped 2025-09-08T03:31:04.928Z,1757302264.928 [keepstation:PowerOnly:A] Stopped 2025-09-08T03:31:04.928Z,1757302264.928 [keepstation:BackseatDriver] Running Loop=1 2025-09-08T03:31:04.929Z,1757302264.929 [keepstation:StandardEnvelopes] Running Loop=1 2025-09-08T03:31:04.929Z,1757302264.929 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-09-08T03:31:04.929Z,1757302264.929 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-09-08T03:31:04.929Z,1757302264.929 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-09-08T03:31:04.930Z,1757302264.930 [keepstation:B] Stopped 2025-09-08T03:31:04.930Z,1757302264.930 [keepstation:D] Running Loop=1 2025-09-08T03:31:05.319Z,1757302265.319 [keepstation:D] Stopped 2025-09-08T03:31:05.319Z,1757302265.319 [keepstation:E] Running Loop=1 2025-09-08T03:31:05.771Z,1757302265.771 [keepstation:E] Stopped 2025-09-08T03:31:05.771Z,1757302265.771 [keepstation:StartingMission] Running Loop=1 2025-09-08T03:31:05.771Z,1757302265.771 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-09-08T03:31:06.133Z,1757302266.133 [keepstation:SurfaceComms] Running Loop=1 2025-09-08T03:31:06.133Z,1757302266.133 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-08T03:31:06.134Z,1757302266.134 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-08T03:31:06.134Z,1757302266.134 [keepstation:SurfaceComms:A] Stopped 2025-09-08T03:31:06.134Z,1757302266.134 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-08T03:31:06.135Z,1757302266.135 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-08T03:31:06.567Z,1757302266.567 [keepstation:NeedComms] Running Loop=1 2025-09-08T03:31:06.567Z,1757302266.567 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-08T03:31:06.567Z,1757302266.567 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-08T03:31:06.567Z,1757302266.567 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-08T03:31:06.568Z,1757302266.568 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-08T03:31:06.568Z,1757302266.568 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-08T03:31:06.568Z,1757302266.568 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-08T03:31:06.569Z,1757302266.569 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-08T03:31:06.569Z,1757302266.569 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-08T03:31:06.569Z,1757302266.569 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-08T03:31:06.570Z,1757302266.570 [keepstation:NeedComms:A] Running Loop=1 2025-09-08T03:31:06.571Z,1757302266.571 [keepstation:NeedComms:A](INFO): last time_fix was: 1757299595.000000 second since 1970/01/01T00:00:00Z 2025-09-08T03:31:06.571Z,1757302266.571 [keepstation:NeedComms:A] Stopped 2025-09-08T03:31:06.937Z,1757302266.937 [keepstation:NeedComms:C] Running Loop=1 2025-09-08T03:31:07.338Z,1757302267.338 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-09-08T03:31:13.387Z,1757302273.387 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-08T03:31:13.470Z,1757302273.470 [NAL9602](ERROR): received: +CSQ:0 OK061, 2, 0, 0, 0 OK 2025-09-08T03:31:19.852Z,1757302279.852 [DVL_micro](ERROR): Failed to parse: :WI,+00000,+00000,+00000,+00000,I 2025-09-08T03:31:24.003Z,1757302284.003 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:31:55.127Z,1757302315.127 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:32:26.251Z,1757302346.251 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:32:57.391Z,1757302377.391 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:33:17.022Z,1757302397.022 [DVL_micro](ERROR): Failed to parse: :SA,-01.22,-00.44,282.8 2025-09-08T03:33:28.531Z,1757302408.531 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:34:00.379Z,1757302440.379 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-08T03:34:31.503Z,1757302471.503 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:35:02.643Z,1757302502.643 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:35:33.783Z,1757302533.783 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:35:43.275Z,1757302543.275 [DVL_micro](ERROR): Failed to parse: :SA,-02.79,+03.30,257.3 2025-09-08T03:35:45.289Z,1757302545.289 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-08T03:35:46.489Z,1757302546.489 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033545.00,A,3646.58769,N,12153.84554,W,0.408,147.84,080925,,,A*72 2025-09-08T03:35:46.491Z,1757302546.491 [NAL9602](INFO): GPS fix at 20250908T033545: (36.776462, -121.897426) 2025-09-08T03:35:46.519Z,1757302546.519 [UniversalFixResidualReporter](INFO): Fix residual: 31.2 %DT, over the last 2630.4 m. Residual distance 819.4 m at bearing -36.7 degrees. Fix at (36.7765, -121.8974) with 1359.6 m made good. 2025-09-08T03:35:46.522Z,1757302546.522 [keepstation:NeedComms:C] Stopped 2025-09-08T03:35:46.530Z,1757302546.530 [keepstation:NeedComms:D] Running Loop=1 2025-09-08T03:35:46.933Z,1757302546.933 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-09-08T03:36:04.927Z,1757302564.927 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:36:17.203Z,1757302577.203 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2025-09-08T03:36:17.286Z,1757302577.286 [NAL9602](ERROR): received: +CSQ:0 OK061, 2, 0, 0, 0 OK 2025-09-08T03:36:36.059Z,1757302596.059 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:36:39.082Z,1757302599.082 [NAL9602](INFO): SBD MO Status=2, MOMSN=23061, MT Status=2, MTMSN=0 2025-09-08T03:36:39.082Z,1757302599.082 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T03:37:07.184Z,1757302627.184 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:37:38.307Z,1757302658.307 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:38:03.388Z,1757302683.388 [NAL9602](INFO): SBD MO Status=1, MOMSN=23061, MT Status=0, MTMSN=0 2025-09-08T03:38:03.441Z,1757302683.441 [NAL9602](INFO): Sent 78 bytes from file Logs/20250907T130146/Courier0072.lzma 2025-09-08T03:38:03.441Z,1757302683.441 [NAL9602](INFO): Packets left to send: 0 2025-09-08T03:38:09.431Z,1757302689.431 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:38:17.942Z,1757302697.942 [NAL9602](INFO): SBD MO Status=2, MOMSN=23062, MT Status=2, MTMSN=0 2025-09-08T03:38:17.942Z,1757302697.942 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T03:38:28.127Z,1757302708.127 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003280 2025-09-08T03:38:37.843Z,1757302717.843 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20250908T033000/Courier0000.lzma 2025-09-08T03:38:37.993Z,1757302717.993 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T03:38:37.993Z,1757302717.993 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T03:38:37.994Z,1757302717.994 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-08T03:38:37.995Z,1757302717.995 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-08T03:38:37.998Z,1757302717.998 [BPC1](INFO): Received data from all battery sticks. 2025-09-08T03:38:46.865Z,1757302726.865 [NAL9602](INFO): SBD MO Status=2, MOMSN=23062, MT Status=2, MTMSN=0 2025-09-08T03:38:46.865Z,1757302726.865 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T03:38:48.884Z,1757302728.884 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Courier0000.lzma.bak 2025-09-08T03:38:48.884Z,1757302728.884 [DataOverHttps](INFO): SBD MOMSN=25941879 2025-09-08T03:39:17.572Z,1757302757.572 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T03:39:18.305Z,1757302758.305 [DataOverHttps](INFO): Sending 1040 bytes from file Logs/20250907T130146/Express0073.lzma 2025-09-08T03:39:29.345Z,1757302769.345 [DataOverHttps](INFO): Moved sent file to Logs/20250907T130146/Express0073.lzma.bak 2025-09-08T03:39:29.345Z,1757302769.345 [DataOverHttps](INFO): SBD MOMSN=25941886 2025-09-08T03:39:56.262Z,1757302796.262 [DataOverHttps](INFO): Sending 1311 bytes from file Logs/20250908T033000/Express0001.lzma 2025-09-08T03:40:07.312Z,1757302807.312 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Express0001.lzma.bak 2025-09-08T03:40:07.312Z,1757302807.312 [DataOverHttps](INFO): SBD MOMSN=25941898 2025-09-08T03:40:08.503Z,1757302808.503 [keepstation:NeedComms:D] Stopped 2025-09-08T03:40:08.504Z,1757302808.504 [keepstation:NeedComms:E] Running Loop=1 2025-09-08T03:40:08.919Z,1757302808.919 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-09-08T03:40:10.494Z,1757302810.494 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034009.00,A,3646.54314,N,12153.84339,W,0.622,169.48,080925,,,A*71 2025-09-08T03:40:10.497Z,1757302810.497 [NAL9602](INFO): GPS fix at 20250908T034009: (36.775719, -121.897390) 2025-09-08T03:40:10.544Z,1757302810.544 [keepstation:NeedComms:E] Stopped 2025-09-08T03:40:10.545Z,1757302810.545 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-08T03:40:10.545Z,1757302810.545 [keepstation:NeedComms] Stopped 2025-09-08T03:40:10.545Z,1757302810.545 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-08T03:40:10.545Z,1757302810.545 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-08T03:40:10.545Z,1757302810.545 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-08T03:40:10.967Z,1757302810.967 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-08T03:40:10.967Z,1757302810.967 [keepstation:SurfaceComms:B] Stopped 2025-09-08T03:40:10.967Z,1757302810.967 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-08T03:40:10.967Z,1757302810.967 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-08T03:40:10.967Z,1757302810.967 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-08T03:40:10.967Z,1757302810.967 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-08T03:40:11.331Z,1757302811.331 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-08T03:40:11.331Z,1757302811.331 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-08T03:40:11.331Z,1757302811.331 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-08T03:40:11.331Z,1757302811.331 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-08T03:40:11.331Z,1757302811.331 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-08T03:40:11.331Z,1757302811.331 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-08T03:40:11.739Z,1757302811.739 [keepstation:SurfaceComms:setStation] Stopped 2025-09-08T03:40:11.739Z,1757302811.739 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-08T03:40:11.739Z,1757302811.739 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-08T03:40:11.739Z,1757302811.739 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-08T03:40:11.739Z,1757302811.739 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-08T03:40:11.739Z,1757302811.739 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-08T03:40:12.158Z,1757302812.158 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-08T03:40:12.162Z,1757302812.162 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-08T03:40:12.162Z,1757302812.162 [keepstation:SurfaceComms:setSink] Stopped 2025-09-08T03:40:12.162Z,1757302812.162 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-08T03:40:12.163Z,1757302812.163 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-08T03:40:12.163Z,1757302812.163 [keepstation:SurfaceComms] Stopped 2025-09-08T03:40:12.163Z,1757302812.163 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-08T03:40:12.591Z,1757302812.591 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-09-08T03:40:12.591Z,1757302812.591 [keepstation:StartingMission] Stopped 2025-09-08T03:40:12.591Z,1757302812.591 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-09-08T03:40:12.592Z,1757302812.592 [keepstation:TransitToStation] Running Loop=1 2025-09-08T03:40:12.592Z,1757302812.592 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-09-08T03:40:12.592Z,1757302812.592 [keepstation:TransitToStation:Dive] Running Loop=1 2025-09-08T03:40:12.592Z,1757302812.592 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-09-08T03:40:12.592Z,1757302812.592 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-09-08T03:40:12.592Z,1757302812.592 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-09-08T03:40:12.593Z,1757302812.593 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-09-08T03:40:12.593Z,1757302812.593 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-09-08T03:40:12.961Z,1757302812.961 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-09-08T03:40:12.961Z,1757302812.961 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-09-08T03:40:12.961Z,1757302812.961 [keepstation:TransitToStation:C] Running Loop=1 2025-09-08T03:40:12.961Z,1757302812.961 [keepstation:TransitToStation:Dive] Running Loop=1 2025-09-08T03:40:12.961Z,1757302812.961 [keepstation:DiveCmd] Running Loop=1 2025-09-08T03:40:12.961Z,1757302812.961 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2025-09-08T03:40:12.961Z,1757302812.961 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-08T03:40:12.961Z,1757302812.961 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-08T03:40:12.961Z,1757302812.961 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-08T03:40:12.962Z,1757302812.962 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T03:40:12.962Z,1757302812.962 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-08T03:40:12.962Z,1757302812.962 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-08T03:40:12.962Z,1757302812.962 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-08T03:40:12.962Z,1757302812.962 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-08T03:40:12.962Z,1757302812.962 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-08T03:40:12.962Z,1757302812.962 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-09-08T03:40:12.966Z,1757302812.966 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-09-08T03:40:12.967Z,1757302812.967 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-09-08T03:40:12.967Z,1757302812.967 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-09-08T03:40:12.967Z,1757302812.967 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-09-08T03:40:12.967Z,1757302812.967 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-08T03:40:12.967Z,1757302812.967 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-09-08T03:40:12.967Z,1757302812.967 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-09-08T03:40:12.967Z,1757302812.967 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-09-08T03:40:12.967Z,1757302812.967 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-09-08T03:40:12.967Z,1757302812.967 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-08T03:40:12.968Z,1757302812.968 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-08T03:40:12.968Z,1757302812.968 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-08T03:40:12.968Z,1757302812.968 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-08T03:40:12.968Z,1757302812.968 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-08T03:40:12.968Z,1757302812.968 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-08T03:40:12.968Z,1757302812.968 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-08T03:40:12.969Z,1757302812.969 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-08T03:40:12.969Z,1757302812.969 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-08T03:40:12.969Z,1757302812.969 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-08T03:40:12.969Z,1757302812.969 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-08T03:40:12.969Z,1757302812.969 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-08T03:40:12.969Z,1757302812.969 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-08T03:40:12.969Z,1757302812.969 [keepstation:DiveCmd:TransitToStation] Stopped 2025-09-08T03:40:12.969Z,1757302812.969 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-09-08T03:40:12.969Z,1757302812.969 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-09-08T03:40:12.969Z,1757302812.969 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-09-08T03:40:12.969Z,1757302812.969 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-09-08T03:40:12.970Z,1757302812.970 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-08T03:40:12.970Z,1757302812.970 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-09-08T03:40:12.970Z,1757302812.970 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-09-08T03:40:12.970Z,1757302812.970 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-09-08T03:40:12.970Z,1757302812.970 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-08T03:40:12.991Z,1757302812.991 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-08T03:40:12.991Z,1757302812.991 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-08T03:40:12.991Z,1757302812.991 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-08T03:40:12.991Z,1757302812.991 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-08T03:40:12.991Z,1757302812.991 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-08T03:40:12.991Z,1757302812.991 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-08T03:40:13.366Z,1757302813.366 [keepstation:TransitToStation:C] Stopped 2025-09-08T03:40:13.370Z,1757302813.370 [keepstation:TransitToStation:D] Running Loop=1 2025-09-08T03:40:13.371Z,1757302813.371 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-08T03:40:13.371Z,1757302813.371 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-08T03:40:13.371Z,1757302813.371 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-08T03:40:13.371Z,1757302813.371 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-08T03:40:13.371Z,1757302813.371 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-08T03:40:13.371Z,1757302813.371 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-08T03:40:13.372Z,1757302813.372 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-08T03:40:13.372Z,1757302813.372 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-08T03:40:13.372Z,1757302813.372 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-08T03:40:13.372Z,1757302813.372 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-08T03:40:13.373Z,1757302813.373 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-08T03:40:13.373Z,1757302813.373 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-09-08T03:40:13.373Z,1757302813.373 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-09-08T03:40:13.373Z,1757302813.373 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-08T03:40:13.373Z,1757302813.373 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T03:40:13.374Z,1757302813.374 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-08T03:40:13.374Z,1757302813.374 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-08T03:40:13.374Z,1757302813.374 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-08T03:40:13.374Z,1757302813.374 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-08T03:40:13.374Z,1757302813.374 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-08T03:40:13.744Z,1757302813.744 [keepstation:TransitToStation:D] Stopped 2025-09-08T03:40:13.744Z,1757302813.744 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-09-08T03:40:13.744Z,1757302813.744 [keepstation:TransitToStation] Stopped 2025-09-08T03:40:13.744Z,1757302813.744 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-09-08T03:40:13.745Z,1757302813.745 [keepstation:TransitToStation:Dive] Stopped 2025-09-08T03:40:13.745Z,1757302813.745 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-09-08T03:40:13.745Z,1757302813.745 [keepstation:TransitToStation:Dive:A] Stopped 2025-09-08T03:40:13.745Z,1757302813.745 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-09-08T03:40:13.745Z,1757302813.745 [keepstation:OnStation] Running Loop=1 2025-09-08T03:40:13.745Z,1757302813.745 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-09-08T03:40:14.213Z,1757302814.213 [keepstation:NeedComms] Running Loop=1 2025-09-08T03:40:14.213Z,1757302814.213 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-08T03:40:14.213Z,1757302814.213 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-08T03:40:14.213Z,1757302814.213 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-08T03:40:14.214Z,1757302814.214 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-08T03:40:14.222Z,1757302814.222 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-08T03:40:14.223Z,1757302814.223 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-08T03:40:14.223Z,1757302814.223 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-08T03:40:14.223Z,1757302814.223 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-08T03:40:14.224Z,1757302814.224 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-08T03:40:14.224Z,1757302814.224 [keepstation:NeedComms:A] Running Loop=1 2025-09-08T03:40:14.225Z,1757302814.225 [keepstation:NeedComms:A](INFO): last time_fix was: 1757302809.000000 second since 1970/01/01T00:00:00Z 2025-09-08T03:40:14.225Z,1757302814.225 [keepstation:NeedComms:A] Stopped 2025-09-08T03:40:14.681Z,1757302814.681 [keepstation:NeedComms:C] Running Loop=1 2025-09-08T03:40:40.388Z,1757302840.388 [NAL9602](INFO): SBD MO Status=2, MOMSN=23062, MT Status=2, MTMSN=0 2025-09-08T03:40:40.388Z,1757302840.388 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T03:41:08.680Z,1757302868.680 [NAL9602](INFO): SBD MO Status=2, MOMSN=23062, MT Status=2, MTMSN=0 2025-09-08T03:41:08.681Z,1757302868.681 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T03:42:42.059Z,1757302962.059 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-08T03:45:25.716Z,1757303125.716 [NAL9602](INFO): SBD MO Status=2, MOMSN=23062, MT Status=2, MTMSN=0 2025-09-08T03:45:25.716Z,1757303125.716 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T03:45:26.120Z,1757303126.120 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-08T03:45:27.332Z,1757303127.332 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034526.00,A,3646.49422,N,12153.84701,W,0.194,192.15,080925,,,A*7E 2025-09-08T03:45:27.335Z,1757303127.335 [NAL9602](INFO): GPS fix at 20250908T034526: (36.774904, -121.897450) 2025-09-08T03:45:27.350Z,1757303127.350 [keepstation:NeedComms:C] Stopped 2025-09-08T03:45:27.350Z,1757303127.350 [keepstation:NeedComms:D] Running Loop=1 2025-09-08T03:45:42.707Z,1757303142.707 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20250908T033000/Courier0003.lzma 2025-09-08T03:45:53.749Z,1757303153.749 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Courier0003.lzma.bak 2025-09-08T03:45:53.749Z,1757303153.749 [DataOverHttps](INFO): SBD MOMSN=25941920 2025-09-08T03:45:59.666Z,1757303159.666 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T03:46:20.271Z,1757303180.271 [DataOverHttps](INFO): Sending 330 bytes from file Logs/20250908T033000/Express0004.lzma 2025-09-08T03:46:31.312Z,1757303191.312 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Express0004.lzma.bak 2025-09-08T03:46:31.313Z,1757303191.313 [DataOverHttps](INFO): SBD MOMSN=25941923 2025-09-08T03:46:32.464Z,1757303192.464 [keepstation:NeedComms:D] Stopped 2025-09-08T03:46:32.465Z,1757303192.465 [keepstation:NeedComms:E] Running Loop=1 2025-09-08T03:46:34.448Z,1757303194.448 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034633.00,A,3646.48240,N,12153.84843,W,0.641,158.65,080925,,,A*7D 2025-09-08T03:46:34.455Z,1757303194.455 [NAL9602](INFO): GPS fix at 20250908T034633: (36.774707, -121.897474) 2025-09-08T03:46:34.532Z,1757303194.532 [keepstation:NeedComms:E] Stopped 2025-09-08T03:46:34.533Z,1757303194.533 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-08T03:46:34.533Z,1757303194.533 [keepstation:NeedComms] Stopped 2025-09-08T03:46:34.533Z,1757303194.533 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-08T03:46:34.533Z,1757303194.533 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-08T03:46:34.533Z,1757303194.533 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-08T03:46:34.962Z,1757303194.962 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-09-08T03:46:34.962Z,1757303194.962 [keepstation:OnStation] Stopped 2025-09-08T03:46:34.962Z,1757303194.962 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-09-08T03:46:34.962Z,1757303194.962 [keepstation:KeepStation] Running Loop=1 2025-09-08T03:46:34.962Z,1757303194.962 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-09-08T03:46:34.962Z,1757303194.962 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-08T03:46:34.970Z,1757303194.970 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-08T03:46:34.971Z,1757303194.971 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-08T03:46:34.971Z,1757303194.971 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-08T03:46:34.971Z,1757303194.971 [keepstation:KeepStation:B.Wait] Running Loop=1 2025-09-08T03:46:34.971Z,1757303194.971 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component. 2025-09-08T03:46:35.286Z,1757303195.286 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-08T03:46:47.756Z,1757303207.756 [NAL9602](INFO): SBD MO Status=1, MOMSN=23062, MT Status=0, MTMSN=0 2025-09-08T03:46:47.756Z,1757303207.756 [NAL9602](INFO): No messages in MT queue 2025-09-08T03:47:18.472Z,1757303238.472 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T03:47:32.879Z,1757303252.879 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-08T03:48:34.079Z,1757303314.079 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:49:05.205Z,1757303345.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:49:17.739Z,1757303357.739 [Radio_Surface](INFO): Powering down 2025-09-08T03:49:36.335Z,1757303376.335 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T03:49:37.339Z,1757303377.339 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T03:50:52.639Z,1757303452.639 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747284 2025-09-08T04:05:22.019Z,1757304322.019 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-08T04:05:22.425Z,1757304322.425 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 30.83 mm (1 active estimators). 2025-09-08T04:09:59.260Z,1757304599.260 [DVL_micro](ERROR): Failed to parse: :SA,-05.33,-04.05,007.1 2025-09-08T04:12:05.391Z,1757304725.391 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-08T04:16:19.504Z,1757304979.504 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-08T04:25:39.762Z,1757305539.762 [DVL_micro](ERROR): Failed to parse: :RD,+0000.30,+9999.99,+0000.30,+0002.18 2025-09-08T04:26:09.672Z,1757305569.672 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-08T04:29:13.903Z,1757305753.903 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-08T04:33:25.352Z,1757306005.352 [DVL_micro](ERROR): Failed to parse: :RD,+0000.30,+9999.99,+0000.30,+0001.55 2025-09-08T04:34:50.281Z,1757306090.281 [DVL_micro](ERROR): Failed to parse: :R99.99 2025-09-08T04:39:02.458Z,1757306342.458 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-08T04:42:04.350Z,1757306524.350 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-08T04:46:15.658Z,1757306775.658 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T04:46:15.659Z,1757306775.659 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T04:46:15.659Z,1757306775.659 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-08T04:46:15.660Z,1757306775.660 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-08T04:46:15.663Z,1757306775.663 [BPC1](INFO): Received data from all battery sticks. 2025-09-08T04:46:34.679Z,1757306794.679 [keepstation:SurfaceComms] Running Loop=1 2025-09-08T04:46:34.679Z,1757306794.679 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-08T04:46:34.679Z,1757306794.679 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-08T04:46:34.680Z,1757306794.680 [keepstation:SurfaceComms:A] Stopped 2025-09-08T04:46:34.680Z,1757306794.680 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-08T04:46:34.680Z,1757306794.680 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-08T04:46:34.682Z,1757306794.682 [keepstation:NeedComms] Running Loop=1 2025-09-08T04:46:34.682Z,1757306794.682 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-08T04:46:34.682Z,1757306794.682 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-08T04:46:34.682Z,1757306794.682 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-08T04:46:34.682Z,1757306794.682 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-08T04:46:34.683Z,1757306794.683 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-08T04:46:34.683Z,1757306794.683 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-08T04:46:34.684Z,1757306794.684 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-08T04:46:34.684Z,1757306794.684 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-08T04:46:34.684Z,1757306794.684 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-08T04:46:34.684Z,1757306794.684 [keepstation:NeedComms:A] Running Loop=1 2025-09-08T04:46:34.686Z,1757306794.686 [keepstation:NeedComms:A](INFO): last time_fix was: 1757303193.000000 second since 1970/01/01T00:00:00Z 2025-09-08T04:46:34.686Z,1757306794.686 [keepstation:NeedComms:A] Stopped 2025-09-08T04:46:35.089Z,1757306795.089 [keepstation:KeepStation:Dive] Stopped 2025-09-08T04:46:35.089Z,1757306795.089 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-08T04:46:35.089Z,1757306795.089 [keepstation:KeepStation:Dive:A] Stopped 2025-09-08T04:46:35.089Z,1757306795.089 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-08T04:46:35.090Z,1757306795.090 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-08T04:46:35.090Z,1757306795.090 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-08T04:46:35.090Z,1757306795.090 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-08T04:46:35.090Z,1757306795.090 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-08T04:46:35.090Z,1757306795.090 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-08T04:46:35.090Z,1757306795.090 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-08T04:47:50.104Z,1757306870.104 [keepstation:NeedComms:C] Running Loop=1 2025-09-08T04:47:50.711Z,1757306870.711 [Radio_Surface](INFO): Powering up 2025-09-08T04:47:56.763Z,1757306876.763 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T04:48:26.883Z,1757306906.883 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T04:48:58.011Z,1757306938.011 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T04:49:29.135Z,1757306969.135 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T04:50:00.263Z,1757307000.263 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T04:50:18.911Z,1757307018.911 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003247 2025-09-08T04:51:41.176Z,1757307101.176 [DVL_micro](ERROR): Failed to parse: :WI,+00224,+00070,-00286,+00000,A 2025-09-08T04:51:43.199Z,1757307103.199 [DVL_micro](ERROR): Failed to parse: :WI,+00444,-00006,-00321,+00000,A 2025-09-08T04:51:45.210Z,1757307105.210 [DVL_micro](ERROR): Failed to parse: :BI,+00521,-00200,+00282,+00000,I 2025-09-08T04:51:47.635Z,1757307107.635 [DVL_micro](ERROR): No DVL communication! Re-initializing 2025-09-08T04:51:47.636Z,1757307107.636 [DVL_micro] Communications Fault, FailCount= 1 2025-09-08T04:51:47.636Z,1757307107.636 [DVL_micro](ERROR): Communications Fault 2025-09-08T04:51:47.696Z,1757307107.696 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-09-08T04:51:48.135Z,1757307108.135 [DVL_micro](INFO): Powering down 2025-09-08T04:51:48.887Z,1757307108.887 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-09-08T04:51:48.887Z,1757307108.887 [DVL_micro] No Fault, FailCount= 1 2025-09-08T04:52:34.901Z,1757307154.901 [NAL9602](INFO): SBD MO Status=2, MOMSN=23063, MT Status=2, MTMSN=0 2025-09-08T04:52:34.901Z,1757307154.901 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T04:52:59.950Z,1757307179.950 [NAL9602](INFO): SBD MO Status=2, MOMSN=23063, MT Status=2, MTMSN=0 2025-09-08T04:52:59.950Z,1757307179.950 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T04:53:00.357Z,1757307180.357 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-08T04:53:01.566Z,1757307181.566 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,045300.00,A,3646.15700,N,12153.64800,W,1.302,201.95,080925,,,A*7D 2025-09-08T04:53:01.568Z,1757307181.568 [NAL9602](INFO): GPS fix at 20250908T045300: (36.769283, -121.894133) 2025-09-08T04:53:01.580Z,1757307181.580 [UniversalFixResidualReporter](INFO): Fix residual: 28.1 %DT, over the last 2687.5 m. Residual distance 754.0 m at bearing -12.5 degrees. Fix at (36.7693, -121.8941) with 672.5 m made good. 2025-09-08T04:53:01.584Z,1757307181.584 [keepstation:NeedComms:C] Stopped 2025-09-08T04:53:01.584Z,1757307181.584 [keepstation:NeedComms:D] Running Loop=1 2025-09-08T04:53:10.062Z,1757307190.062 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20250908T033000/Courier0006.lzma 2025-09-08T04:53:21.104Z,1757307201.104 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Courier0006.lzma.bak 2025-09-08T04:53:21.104Z,1757307201.104 [DataOverHttps](INFO): SBD MOMSN=25942016 2025-09-08T04:53:33.504Z,1757307213.504 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T04:53:48.029Z,1757307228.029 [DataOverHttps](INFO): Sending 876 bytes from file Logs/20250908T033000/Express0007.lzma 2025-09-08T04:53:59.068Z,1757307239.068 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Express0007.lzma.bak 2025-09-08T04:53:59.068Z,1757307239.068 [DataOverHttps](INFO): SBD MOMSN=25942022 2025-09-08T04:54:00.575Z,1757307240.575 [keepstation:NeedComms:D] Stopped 2025-09-08T04:54:00.575Z,1757307240.575 [keepstation:NeedComms:E] Running Loop=1 2025-09-08T04:54:02.605Z,1757307242.605 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,045401.00,A,3646.14872,N,12153.65417,W,1.186,215.66,080925,,,A*7C 2025-09-08T04:54:02.612Z,1757307242.612 [NAL9602](INFO): GPS fix at 20250908T045401: (36.769145, -121.894236) 2025-09-08T04:54:02.661Z,1757307242.661 [keepstation:NeedComms:E] Stopped 2025-09-08T04:54:02.662Z,1757307242.662 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-08T04:54:02.662Z,1757307242.662 [keepstation:NeedComms] Stopped 2025-09-08T04:54:02.662Z,1757307242.662 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-08T04:54:02.662Z,1757307242.662 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-08T04:54:02.662Z,1757307242.662 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-08T04:54:03.032Z,1757307243.032 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-08T04:54:03.032Z,1757307243.032 [keepstation:SurfaceComms:B] Stopped 2025-09-08T04:54:03.032Z,1757307243.032 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-08T04:54:03.032Z,1757307243.032 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-08T04:54:03.032Z,1757307243.032 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-08T04:54:03.032Z,1757307243.032 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-08T04:54:03.404Z,1757307243.404 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-08T04:54:03.404Z,1757307243.404 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-08T04:54:03.404Z,1757307243.404 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-08T04:54:03.404Z,1757307243.404 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-08T04:54:03.404Z,1757307243.404 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-08T04:54:03.405Z,1757307243.405 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-08T04:54:03.827Z,1757307243.827 [keepstation:SurfaceComms:setStation] Stopped 2025-09-08T04:54:03.827Z,1757307243.827 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-08T04:54:03.827Z,1757307243.827 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-08T04:54:03.827Z,1757307243.827 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-08T04:54:03.827Z,1757307243.827 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-08T04:54:03.827Z,1757307243.827 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-08T04:54:04.221Z,1757307244.221 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-08T04:54:04.221Z,1757307244.221 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-08T04:54:04.221Z,1757307244.221 [keepstation:SurfaceComms:setSink] Stopped 2025-09-08T04:54:04.221Z,1757307244.221 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-08T04:54:04.221Z,1757307244.221 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-08T04:54:04.221Z,1757307244.221 [keepstation:SurfaceComms] Stopped 2025-09-08T04:54:04.221Z,1757307244.221 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-08T04:54:04.645Z,1757307244.645 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-08T04:54:04.645Z,1757307244.645 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-08T04:54:04.645Z,1757307244.645 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-08T04:54:04.645Z,1757307244.645 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-08T04:54:04.646Z,1757307244.646 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-08T04:54:04.646Z,1757307244.646 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-08T04:54:04.650Z,1757307244.650 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-08T04:54:04.651Z,1757307244.651 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T04:54:04.651Z,1757307244.651 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-08T04:54:04.651Z,1757307244.651 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-08T04:54:04.651Z,1757307244.651 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-08T04:54:04.651Z,1757307244.651 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-08T04:54:04.651Z,1757307244.651 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-08T04:54:04.652Z,1757307244.652 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-08T04:54:04.652Z,1757307244.652 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-08T04:54:04.652Z,1757307244.652 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-08T04:54:04.652Z,1757307244.652 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-08T04:55:08.041Z,1757307308.041 [NAL9602](INFO): SBD MO Status=2, MOMSN=23063, MT Status=2, MTMSN=0 2025-09-08T04:55:08.041Z,1757307308.041 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T04:55:23.776Z,1757307323.776 [NAL9602](INFO): SBD MO Status=2, MOMSN=23063, MT Status=2, MTMSN=0 2025-09-08T04:55:23.776Z,1757307323.776 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T04:57:35.463Z,1757307455.463 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-08T04:58:06.587Z,1757307486.587 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T04:58:37.715Z,1757307517.715 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T04:58:49.635Z,1757307529.635 [Radio_Surface](INFO): Powering down 2025-09-08T04:59:04.362Z,1757307544.362 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-08T04:59:08.839Z,1757307548.839 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T04:59:09.843Z,1757307549.843 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T04:59:35.079Z,1757307575.079 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T04:59:54.043Z,1757307594.043 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.531311 2025-09-08T04:59:57.295Z,1757307597.295 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-08T04:59:57.296Z,1757307597.296 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-08T04:59:57.296Z,1757307597.296 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T04:59:57.296Z,1757307597.296 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-08T04:59:57.340Z,1757307597.340 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-09-08T04:59:57.702Z,1757307597.702 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-08T04:59:57.702Z,1757307597.702 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T04:59:57.702Z,1757307597.702 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-08T04:59:57.702Z,1757307597.702 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-08T04:59:57.702Z,1757307597.702 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T04:59:57.703Z,1757307597.703 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-08T04:59:58.114Z,1757307598.114 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-08T04:59:58.115Z,1757307598.115 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T04:59:58.115Z,1757307598.115 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-08T04:59:58.115Z,1757307598.115 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T04:59:58.115Z,1757307598.115 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T04:59:58.115Z,1757307598.115 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-08T04:59:58.116Z,1757307598.116 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T04:59:58.116Z,1757307598.116 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-08T04:59:58.116Z,1757307598.116 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-08T04:59:58.116Z,1757307598.116 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-08T04:59:58.116Z,1757307598.116 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-08T04:59:58.116Z,1757307598.116 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-08T04:59:58.516Z,1757307598.516 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-08T04:59:58.516Z,1757307598.516 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-08T04:59:58.516Z,1757307598.516 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-08T04:59:58.516Z,1757307598.516 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-08T04:59:58.516Z,1757307598.516 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-08T04:59:58.516Z,1757307598.516 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-08T04:59:58.516Z,1757307598.516 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-08T05:00:01.782Z,1757307601.782 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.000421 CHAN A1 (24V): 0.012519 CHAN A2 (12V): -0.020570 CHAN A3 (5V): -0.018518 CHAN B0 (3.3V): -0.008667 CHAN B1 (3.15aV): -0.008585 CHAN B2 (3.15bV): -0.008367 CHAN B3 (GND): -0.010963 OPEN: 0.008217 Full Scale: +/- 1 mA 2025-09-08T05:03:46.361Z,1757307826.361 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00024,0000000.00,9999.99,000.00 2025-09-08T05:03:46.361Z,1757307826.361 [DVL_micro](ERROR): Failed to parse: :BI,-00024,0000000.00,9999.99,000.00 2025-09-08T05:06:11.858Z,1757307971.858 [DVL_micro](ERROR): DVL uart error: serial timeout 2025-09-08T05:06:11.858Z,1757307971.858 [DVL_micro] Communications Fault, FailCount= 1 2025-09-08T05:06:11.858Z,1757307971.858 [DVL_micro](ERROR): Communications Fault 2025-09-08T05:06:11.859Z,1757307971.859 [DVL_micro](ERROR): Failed to parse: 2025-09-08T05:06:11.887Z,1757307971.887 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-09-08T05:06:12.287Z,1757307972.287 [DVL_micro](INFO): Powering down 2025-09-08T05:06:13.029Z,1757307973.029 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-09-08T05:06:13.029Z,1757307973.029 [DVL_micro] No Fault, FailCount= 1 2025-09-08T05:09:06.739Z,1757308146.739 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-08T05:09:07.133Z,1757308147.133 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.83 mm (2 active estimators). 2025-09-08T05:17:38.563Z,1757308658.563 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-08T05:32:53.213Z,1757309573.213 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-08T05:43:26.289Z,1757310206.289 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-09-08T05:47:34.963Z,1757310454.963 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-08T05:50:53.112Z,1757310653.112 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-09-08T05:53:54.496Z,1757310834.496 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T05:53:54.496Z,1757310834.496 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T05:53:54.497Z,1757310834.497 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-08T05:53:54.498Z,1757310834.498 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-08T05:53:54.501Z,1757310834.501 [BPC1](INFO): Received data from all battery sticks. 2025-09-08T05:54:02.995Z,1757310842.995 [keepstation:SurfaceComms] Running Loop=1 2025-09-08T05:54:02.995Z,1757310842.995 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-08T05:54:02.995Z,1757310842.995 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-08T05:54:02.996Z,1757310842.996 [keepstation:SurfaceComms:A] Stopped 2025-09-08T05:54:02.996Z,1757310842.996 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-08T05:54:02.996Z,1757310842.996 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-08T05:54:03.412Z,1757310843.412 [keepstation:KeepStation:Dive] Stopped 2025-09-08T05:54:03.412Z,1757310843.412 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-08T05:54:03.412Z,1757310843.412 [keepstation:KeepStation:Dive:A] Stopped 2025-09-08T05:54:03.412Z,1757310843.412 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-08T05:54:03.413Z,1757310843.413 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-08T05:54:03.413Z,1757310843.413 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-08T05:54:03.413Z,1757310843.413 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-08T05:54:03.413Z,1757310843.413 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-08T05:54:03.413Z,1757310843.413 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-08T05:54:03.413Z,1757310843.413 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-08T05:54:03.415Z,1757310843.415 [keepstation:NeedComms] Running Loop=1 2025-09-08T05:54:03.415Z,1757310843.415 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-08T05:54:03.415Z,1757310843.415 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-08T05:54:03.415Z,1757310843.415 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-08T05:54:03.415Z,1757310843.415 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-08T05:54:03.416Z,1757310843.416 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-08T05:54:03.416Z,1757310843.416 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-08T05:54:03.417Z,1757310843.417 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-08T05:54:03.417Z,1757310843.417 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-08T05:54:03.417Z,1757310843.417 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-08T05:54:03.417Z,1757310843.417 [keepstation:NeedComms:A] Running Loop=1 2025-09-08T05:54:03.419Z,1757310843.419 [keepstation:NeedComms:A](INFO): last time_fix was: 1757307241.000000 second since 1970/01/01T00:00:00Z 2025-09-08T05:54:03.419Z,1757310843.419 [keepstation:NeedComms:A] Stopped 2025-09-08T05:55:48.889Z,1757310948.889 [keepstation:NeedComms:C] Running Loop=1 2025-09-08T05:55:49.523Z,1757310949.523 [Radio_Surface](INFO): Powering up 2025-09-08T05:55:54.899Z,1757310954.899 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T05:56:12.775Z,1757310972.775 [DVL_micro](ERROR): Failed to parse: :SA,-06.08,-00.48,047.1 2025-09-08T05:56:21.659Z,1757310981.659 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-08T05:56:21.742Z,1757310981.742 [NAL9602](ERROR): received: +CSQ:0 OK063, 2, 0, 0, 0 OK 2025-09-08T05:56:25.019Z,1757310985.019 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T05:56:56.147Z,1757311016.147 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T05:57:19.832Z,1757311039.832 [DVL_micro](ERROR): Failed to parse: :BI,+01977,+02159,+00951,+00000,A 2025-09-08T05:57:27.271Z,1757311047.271 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T05:57:58.395Z,1757311078.395 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T05:58:18.075Z,1757311098.075 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003263 2025-09-08T05:59:04.869Z,1757311144.869 [NAL9602](INFO): SBD MO Status=2, MOMSN=23063, MT Status=2, MTMSN=0 2025-09-08T05:59:04.870Z,1757311144.870 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T05:59:29.520Z,1757311169.520 [NAL9602](INFO): SBD MO Status=2, MOMSN=23063, MT Status=2, MTMSN=0 2025-09-08T05:59:29.520Z,1757311169.520 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T06:00:43.453Z,1757311243.453 [NAL9602](INFO): SBD MO Status=2, MOMSN=23063, MT Status=2, MTMSN=0 2025-09-08T06:00:43.453Z,1757311243.453 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T06:00:58.800Z,1757311258.800 [NAL9602](INFO): SBD MO Status=2, MOMSN=23063, MT Status=2, MTMSN=0 2025-09-08T06:00:58.800Z,1757311258.800 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T06:00:59.216Z,1757311259.216 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-08T06:01:00.414Z,1757311260.414 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,060059.00,A,3645.76610,N,12153.82313,W,1.302,301.35,080925,,,D*7C 2025-09-08T06:01:00.416Z,1757311260.416 [NAL9602](INFO): GPS fix at 20250908T060059: (36.762768, -121.897052) 2025-09-08T06:01:00.429Z,1757311260.429 [UniversalFixResidualReporter](INFO): Fix residual: 64.9 %DT, over the last 1788.8 m. Residual distance 1160.4 m at bearing 19.7 degrees. Fix at (36.7628, -121.8970) with 752.1 m made good. 2025-09-08T06:01:00.432Z,1757311260.432 [keepstation:NeedComms:C] Stopped 2025-09-08T06:01:00.432Z,1757311260.432 [keepstation:NeedComms:D] Running Loop=1 2025-09-08T06:01:20.738Z,1757311280.738 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20250908T033000/Courier0009.lzma 2025-09-08T06:01:31.780Z,1757311291.780 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Courier0009.lzma.bak 2025-09-08T06:01:31.780Z,1757311291.780 [DataOverHttps](INFO): SBD MOMSN=25942101 2025-09-08T06:01:32.738Z,1757311292.738 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T06:02:01.181Z,1757311321.181 [DataOverHttps](INFO): Sending 985 bytes from file Logs/20250908T033000/Express0010.lzma 2025-09-08T06:02:24.268Z,1757311344.268 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Express0010.lzma.bak 2025-09-08T06:02:24.268Z,1757311344.268 [DataOverHttps](INFO): SBD MOMSN=25942104 2025-09-08T06:02:25.684Z,1757311345.684 [keepstation:NeedComms:D] Stopped 2025-09-08T06:02:25.684Z,1757311345.684 [keepstation:NeedComms:E] Running Loop=1 2025-09-08T06:02:27.682Z,1757311347.682 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,060226.00,A,3645.76957,N,12153.83378,W,0.758,259.89,080925,,,D*77 2025-09-08T06:02:27.684Z,1757311347.684 [NAL9602](INFO): GPS fix at 20250908T060226: (36.762826, -121.897230) 2025-09-08T06:02:27.740Z,1757311347.740 [keepstation:NeedComms:E] Stopped 2025-09-08T06:02:27.742Z,1757311347.742 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-08T06:02:27.742Z,1757311347.742 [keepstation:NeedComms] Stopped 2025-09-08T06:02:27.742Z,1757311347.742 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-08T06:02:27.742Z,1757311347.742 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-08T06:02:27.742Z,1757311347.742 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-08T06:02:28.144Z,1757311348.144 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-08T06:02:28.144Z,1757311348.144 [keepstation:SurfaceComms:B] Stopped 2025-09-08T06:02:28.144Z,1757311348.144 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-08T06:02:28.144Z,1757311348.144 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-08T06:02:28.144Z,1757311348.144 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-08T06:02:28.144Z,1757311348.144 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-08T06:02:28.516Z,1757311348.516 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-08T06:02:28.516Z,1757311348.516 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-08T06:02:28.516Z,1757311348.516 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-08T06:02:28.516Z,1757311348.516 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-08T06:02:28.517Z,1757311348.517 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-08T06:02:28.517Z,1757311348.517 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-08T06:02:28.986Z,1757311348.986 [keepstation:SurfaceComms:setStation] Stopped 2025-09-08T06:02:28.986Z,1757311348.986 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-08T06:02:28.986Z,1757311348.986 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-08T06:02:28.986Z,1757311348.986 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-08T06:02:28.994Z,1757311348.994 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-08T06:02:28.995Z,1757311348.995 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-08T06:02:29.348Z,1757311349.348 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-08T06:02:29.348Z,1757311349.348 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-08T06:02:29.348Z,1757311349.348 [keepstation:SurfaceComms:setSink] Stopped 2025-09-08T06:02:29.348Z,1757311349.348 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-08T06:02:29.349Z,1757311349.349 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-08T06:02:29.349Z,1757311349.349 [keepstation:SurfaceComms] Stopped 2025-09-08T06:02:29.349Z,1757311349.349 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-08T06:02:29.728Z,1757311349.728 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-08T06:02:29.728Z,1757311349.728 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-08T06:02:29.728Z,1757311349.728 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-08T06:02:29.728Z,1757311349.728 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-08T06:02:29.729Z,1757311349.729 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-08T06:02:29.729Z,1757311349.729 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-08T06:02:29.729Z,1757311349.729 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-08T06:02:29.729Z,1757311349.729 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T06:02:29.729Z,1757311349.729 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-08T06:02:29.729Z,1757311349.729 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-08T06:02:29.729Z,1757311349.729 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-08T06:02:29.729Z,1757311349.729 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-08T06:02:29.729Z,1757311349.729 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-08T06:02:29.730Z,1757311349.730 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-08T06:02:29.730Z,1757311349.730 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-08T06:02:29.730Z,1757311349.730 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-08T06:02:29.735Z,1757311349.735 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-08T06:03:16.969Z,1757311396.969 [NAL9602](INFO): SBD MO Status=1, MOMSN=23063, MT Status=0, MTMSN=0 2025-09-08T06:03:16.970Z,1757311396.970 [NAL9602](INFO): No messages in MT queue 2025-09-08T06:03:47.670Z,1757311427.670 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T06:05:12.951Z,1757311512.951 [DVL_micro](ERROR): Failed to parse: :SA,+09.20,+01.85,042.6 2025-09-08T06:05:29.327Z,1757311529.327 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T06:05:44.072Z,1757311544.072 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE. Altitude:4.170000m. At depth:3.817388m. 2025-09-08T06:06:00.452Z,1757311560.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T06:06:12.519Z,1757311572.519 [Radio_Surface](INFO): Powering down 2025-09-08T06:06:31.579Z,1757311591.579 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T06:06:32.583Z,1757311592.583 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T06:07:18.612Z,1757311638.612 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-08T06:07:18.612Z,1757311638.612 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-08T06:07:18.613Z,1757311638.613 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T06:07:18.613Z,1757311638.613 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-08T06:07:18.994Z,1757311638.994 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-08T06:07:18.994Z,1757311638.994 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T06:07:18.994Z,1757311638.994 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-08T06:07:18.994Z,1757311638.994 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-08T06:07:18.994Z,1757311638.994 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T06:07:18.995Z,1757311638.995 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-08T06:07:19.400Z,1757311639.400 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-08T06:07:19.400Z,1757311639.400 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T06:07:19.400Z,1757311639.400 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-08T06:07:19.400Z,1757311639.400 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T06:07:19.401Z,1757311639.401 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T06:07:19.401Z,1757311639.401 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-08T06:07:19.401Z,1757311639.401 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T06:07:19.401Z,1757311639.401 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-08T06:07:19.401Z,1757311639.401 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-08T06:07:19.401Z,1757311639.401 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-08T06:07:19.401Z,1757311639.401 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-08T06:07:19.401Z,1757311639.401 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-08T06:07:19.822Z,1757311639.822 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-08T06:07:19.822Z,1757311639.822 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-08T06:07:19.822Z,1757311639.822 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-08T06:07:19.823Z,1757311639.823 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-08T06:07:19.823Z,1757311639.823 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-08T06:07:19.823Z,1757311639.823 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-08T06:07:19.823Z,1757311639.823 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-08T06:07:51.911Z,1757311671.911 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747116 2025-09-08T06:11:56.943Z,1757311916.943 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T06:21:57.305Z,1757312517.305 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T06:31:57.663Z,1757313117.663 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T06:33:05.926Z,1757313185.926 [DVL_micro](ERROR): Failed to parse: :BI,-00001,-00130,-00044,+00000,I 2025-09-08T06:41:57.990Z,1757313717.990 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T06:51:58.339Z,1757314318.339 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T06:58:34.667Z,1757314714.667 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-08T07:01:28.384Z,1757314888.384 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T07:01:28.384Z,1757314888.384 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T07:01:28.385Z,1757314888.385 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-08T07:01:28.386Z,1757314888.386 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-09-08T07:01:28.389Z,1757314888.389 [BPC1](INFO): Received data from all battery sticks. 2025-09-08T07:01:58.674Z,1757314918.674 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T07:02:27.801Z,1757314947.801 [keepstation:SurfaceComms] Running Loop=1 2025-09-08T07:02:27.801Z,1757314947.801 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-08T07:02:27.802Z,1757314947.802 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-08T07:02:27.802Z,1757314947.802 [keepstation:SurfaceComms:A] Stopped 2025-09-08T07:02:27.802Z,1757314947.802 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-08T07:02:27.803Z,1757314947.803 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-08T07:02:28.184Z,1757314948.184 [keepstation:KeepStation:Dive] Stopped 2025-09-08T07:02:28.184Z,1757314948.184 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-08T07:02:28.184Z,1757314948.184 [keepstation:KeepStation:Dive:A] Stopped 2025-09-08T07:02:28.184Z,1757314948.184 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-08T07:02:28.185Z,1757314948.185 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-08T07:02:28.185Z,1757314948.185 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-08T07:02:28.185Z,1757314948.185 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-08T07:02:28.185Z,1757314948.185 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-08T07:02:28.185Z,1757314948.185 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-08T07:02:28.185Z,1757314948.185 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-08T07:02:28.187Z,1757314948.187 [keepstation:NeedComms] Running Loop=1 2025-09-08T07:02:28.187Z,1757314948.187 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-08T07:02:28.187Z,1757314948.187 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-08T07:02:28.187Z,1757314948.187 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-08T07:02:28.188Z,1757314948.188 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-08T07:02:28.188Z,1757314948.188 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-08T07:02:28.188Z,1757314948.188 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-08T07:02:28.189Z,1757314948.189 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-08T07:02:28.189Z,1757314948.189 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-08T07:02:28.189Z,1757314948.189 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-08T07:02:28.190Z,1757314948.190 [keepstation:NeedComms:A] Running Loop=1 2025-09-08T07:02:28.191Z,1757314948.191 [keepstation:NeedComms:A](INFO): last time_fix was: 1757311346.000000 second since 1970/01/01T00:00:00Z 2025-09-08T07:02:28.192Z,1757314948.192 [keepstation:NeedComms:A] Stopped 2025-09-08T07:03:58.465Z,1757315038.465 [keepstation:NeedComms:C] Running Loop=1 2025-09-08T07:03:58.927Z,1757315038.927 [Radio_Surface](INFO): Powering up 2025-09-08T07:04:04.747Z,1757315044.747 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T07:04:33.600Z,1757315073.600 [NAL9602](INFO): SBD MO Status=2, MOMSN=23064, MT Status=2, MTMSN=0 2025-09-08T07:04:33.600Z,1757315073.600 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T07:04:34.867Z,1757315074.867 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T07:04:59.449Z,1757315099.449 [NAL9602](INFO): SBD MO Status=2, MOMSN=23064, MT Status=2, MTMSN=0 2025-09-08T07:04:59.449Z,1757315099.449 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T07:05:06.007Z,1757315106.007 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T07:05:29.349Z,1757315129.349 [NAL9602](INFO): SBD MO Status=2, MOMSN=23064, MT Status=2, MTMSN=0 2025-09-08T07:05:29.350Z,1757315129.350 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T07:05:37.131Z,1757315137.131 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T07:05:52.374Z,1757315152.374 [NAL9602](INFO): SBD MO Status=2, MOMSN=23064, MT Status=2, MTMSN=0 2025-09-08T07:05:52.374Z,1757315152.374 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T07:06:08.259Z,1757315168.259 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T07:06:27.163Z,1757315187.163 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003215 2025-09-08T07:06:37.220Z,1757315197.220 [NAL9602](INFO): SBD MO Status=2, MOMSN=23064, MT Status=2, MTMSN=0 2025-09-08T07:06:37.220Z,1757315197.220 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T07:07:37.825Z,1757315257.825 [NAL9602](INFO): SBD MO Status=2, MOMSN=23064, MT Status=2, MTMSN=0 2025-09-08T07:07:37.825Z,1757315257.825 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T07:08:27.108Z,1757315307.108 [NAL9602](INFO): SBD MO Status=2, MOMSN=23064, MT Status=2, MTMSN=0 2025-09-08T07:08:27.109Z,1757315307.109 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T07:09:02.257Z,1757315342.257 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-08T07:09:03.477Z,1757315343.477 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,070902.00,A,3645.64838,N,12154.17518,W,0.603,12.48,080925,,,A*40 2025-09-08T07:09:03.479Z,1757315343.479 [NAL9602](INFO): GPS fix at 20250908T070902: (36.760806, -121.902920) 2025-09-08T07:09:03.514Z,1757315343.514 [UniversalFixResidualReporter](INFO): Fix residual: 74.7 %DT, over the last 595.5 m. Residual distance 444.6 m at bearing 61.4 degrees. Fix at (36.7608, -121.9029) with 554.4 m made good. 2025-09-08T07:09:03.517Z,1757315343.517 [keepstation:NeedComms:C] Stopped 2025-09-08T07:09:03.518Z,1757315343.518 [keepstation:NeedComms:D] Running Loop=1 2025-09-08T07:09:10.754Z,1757315350.754 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20250908T033000/Courier0012.lzma 2025-09-08T07:09:21.796Z,1757315361.796 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Courier0012.lzma.bak 2025-09-08T07:09:21.796Z,1757315361.796 [DataOverHttps](INFO): SBD MOMSN=25942305 2025-09-08T07:09:35.403Z,1757315375.403 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T07:09:48.272Z,1757315388.272 [DataOverHttps](INFO): Sending 671 bytes from file Logs/20250908T033000/Express0013.lzma 2025-09-08T07:09:59.328Z,1757315399.328 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Express0013.lzma.bak 2025-09-08T07:09:59.328Z,1757315399.328 [DataOverHttps](INFO): SBD MOMSN=25942308 2025-09-08T07:10:00.460Z,1757315400.460 [keepstation:NeedComms:D] Stopped 2025-09-08T07:10:00.460Z,1757315400.460 [keepstation:NeedComms:E] Running Loop=1 2025-09-08T07:10:02.470Z,1757315402.470 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,071001.00,A,3645.65113,N,12154.17968,W,0.097,29.04,080925,,,D*4F 2025-09-08T07:10:02.479Z,1757315402.479 [NAL9602](INFO): GPS fix at 20250908T071001: (36.760852, -121.902995) 2025-09-08T07:10:02.525Z,1757315402.525 [keepstation:NeedComms:E] Stopped 2025-09-08T07:10:02.525Z,1757315402.525 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-08T07:10:02.526Z,1757315402.526 [keepstation:NeedComms] Stopped 2025-09-08T07:10:02.526Z,1757315402.526 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-08T07:10:02.526Z,1757315402.526 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-08T07:10:02.526Z,1757315402.526 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-08T07:10:02.960Z,1757315402.960 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-08T07:10:02.960Z,1757315402.960 [keepstation:SurfaceComms:B] Stopped 2025-09-08T07:10:02.960Z,1757315402.960 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-08T07:10:02.960Z,1757315402.960 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-08T07:10:02.961Z,1757315402.961 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-08T07:10:02.961Z,1757315402.961 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-08T07:10:03.304Z,1757315403.304 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-08T07:10:03.304Z,1757315403.304 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-08T07:10:03.304Z,1757315403.304 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-08T07:10:03.304Z,1757315403.304 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-08T07:10:03.304Z,1757315403.304 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-08T07:10:03.304Z,1757315403.304 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-08T07:10:03.721Z,1757315403.721 [keepstation:SurfaceComms:setStation] Stopped 2025-09-08T07:10:03.721Z,1757315403.721 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-08T07:10:03.721Z,1757315403.721 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-08T07:10:03.721Z,1757315403.721 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-08T07:10:03.721Z,1757315403.721 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-08T07:10:03.721Z,1757315403.721 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-08T07:10:04.138Z,1757315404.138 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-08T07:10:04.139Z,1757315404.139 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-08T07:10:04.139Z,1757315404.139 [keepstation:SurfaceComms:setSink] Stopped 2025-09-08T07:10:04.139Z,1757315404.139 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-08T07:10:04.139Z,1757315404.139 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-08T07:10:04.139Z,1757315404.139 [keepstation:SurfaceComms] Stopped 2025-09-08T07:10:04.139Z,1757315404.139 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-08T07:10:04.523Z,1757315404.523 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-08T07:10:04.523Z,1757315404.523 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-08T07:10:04.524Z,1757315404.524 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-08T07:10:04.524Z,1757315404.524 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-08T07:10:04.524Z,1757315404.524 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-08T07:10:04.524Z,1757315404.524 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-08T07:10:04.524Z,1757315404.524 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-08T07:10:04.524Z,1757315404.524 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T07:10:04.525Z,1757315404.525 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-08T07:10:04.525Z,1757315404.525 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-08T07:10:04.525Z,1757315404.525 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-08T07:10:04.525Z,1757315404.525 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-08T07:10:04.525Z,1757315404.525 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-08T07:10:04.525Z,1757315404.525 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-08T07:10:04.526Z,1757315404.526 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-08T07:10:04.526Z,1757315404.526 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-08T07:10:04.526Z,1757315404.526 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-08T07:10:33.173Z,1757315433.173 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-08T07:10:33.255Z,1757315433.255 [NAL9602](ERROR): received: +CSQ:0 OK064, 2, 0, 0, 0 OK 2025-09-08T07:11:31.503Z,1757315491.503 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-08T07:11:58.827Z,1757315518.827 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T07:12:02.635Z,1757315522.635 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T07:12:32.919Z,1757315552.919 [Radio_Surface](INFO): Powering down 2025-09-08T07:12:33.323Z,1757315553.323 [Radio_Surface](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2025-09-08T07:12:33.323Z,1757315553.323 [Radio_Surface] Hardware Fault, FailCount= 1 2025-09-08T07:12:33.323Z,1757315553.323 [Radio_Surface](ERROR): Hardware Fault 2025-09-08T07:12:33.612Z,1757315553.612 [CBIT](ERROR): Hardware Fault in component: Radio_Surface 2025-09-08T07:12:33.759Z,1757315553.759 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T07:12:34.119Z,1757315554.119 [CBIT](INFO): Clearing failed state for component Radio_Surface 2025-09-08T07:12:34.119Z,1757315554.119 [Radio_Surface] No Fault, FailCount= 1 2025-09-08T07:12:34.131Z,1757315554.131 [Radio_Surface](INFO): Powering up 2025-09-08T07:12:34.763Z,1757315554.763 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T07:12:39.783Z,1757315559.783 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T07:12:40.191Z,1757315560.191 [Radio_Surface](INFO): Powering down 2025-09-08T07:13:09.923Z,1757315589.923 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T07:13:10.927Z,1757315590.927 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T07:13:49.952Z,1757315629.952 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-08T07:13:49.952Z,1757315629.952 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-08T07:13:49.952Z,1757315629.952 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T07:13:49.952Z,1757315629.952 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-08T07:13:50.363Z,1757315630.363 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-08T07:13:50.363Z,1757315630.363 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T07:13:50.364Z,1757315630.364 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-08T07:13:50.364Z,1757315630.364 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-08T07:13:50.364Z,1757315630.364 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T07:13:50.364Z,1757315630.364 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-08T07:13:50.799Z,1757315630.799 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-08T07:13:50.799Z,1757315630.799 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T07:13:50.799Z,1757315630.799 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-08T07:13:50.799Z,1757315630.799 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T07:13:50.800Z,1757315630.800 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T07:13:50.800Z,1757315630.800 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-08T07:13:50.800Z,1757315630.800 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T07:13:50.800Z,1757315630.800 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-08T07:13:50.800Z,1757315630.800 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-08T07:13:50.800Z,1757315630.800 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-08T07:13:50.800Z,1757315630.800 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-08T07:13:50.800Z,1757315630.800 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-08T07:13:51.091Z,1757315631.091 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.715256 2025-09-08T07:13:51.180Z,1757315631.180 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-08T07:13:51.180Z,1757315631.180 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-08T07:13:51.180Z,1757315631.180 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-08T07:13:51.180Z,1757315631.180 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-08T07:13:51.180Z,1757315631.180 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-08T07:13:51.180Z,1757315631.180 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-08T07:13:51.180Z,1757315631.180 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-08T07:15:04.270Z,1757315704.270 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-08T07:15:34.962Z,1757315734.962 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T07:20:25.457Z,1757316025.457 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,000000000000000000.00,9999.99,000.00 2025-09-08T07:20:25.458Z,1757316025.458 [DVL_micro](ERROR): Failed to parse: :TS,000000000000000000.00,9999.99,000.00 2025-09-08T07:21:59.176Z,1757316119.176 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T07:31:59.511Z,1757316719.511 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T07:41:59.858Z,1757317319.858 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T07:45:08.148Z,1757317508.148 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+12.5,0000.0,1489.0,000 2025-09-08T07:49:53.370Z,1757317793.370 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+12.5,-00164,+00000,I 2025-09-08T07:49:53.370Z,1757317793.370 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+12.5,-00164,+00000,I 2025-09-08T07:52:00.207Z,1757317920.207 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T07:55:07.263Z,1757318107.263 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00 2025-09-08T08:02:00.565Z,1757318520.565 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T08:09:07.189Z,1757318947.189 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T08:09:07.190Z,1757318947.190 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T08:09:07.191Z,1757318947.191 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-08T08:09:07.192Z,1757318947.192 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-08T08:09:07.196Z,1757318947.196 [BPC1](INFO): Received data from all battery sticks. 2025-09-08T08:10:02.541Z,1757319002.541 [keepstation:SurfaceComms] Running Loop=1 2025-09-08T08:10:02.541Z,1757319002.541 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-08T08:10:02.542Z,1757319002.542 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-08T08:10:02.544Z,1757319002.544 [keepstation:SurfaceComms:A] Stopped 2025-09-08T08:10:02.544Z,1757319002.544 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-08T08:10:02.544Z,1757319002.544 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-08T08:10:02.960Z,1757319002.960 [keepstation:KeepStation:Dive] Stopped 2025-09-08T08:10:02.961Z,1757319002.961 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-08T08:10:02.961Z,1757319002.961 [keepstation:KeepStation:Dive:A] Stopped 2025-09-08T08:10:02.961Z,1757319002.961 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-08T08:10:02.961Z,1757319002.961 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-08T08:10:02.962Z,1757319002.962 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-08T08:10:02.962Z,1757319002.962 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-08T08:10:02.962Z,1757319002.962 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-08T08:10:02.962Z,1757319002.962 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-08T08:10:02.962Z,1757319002.962 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-08T08:10:02.965Z,1757319002.965 [keepstation:NeedComms] Running Loop=1 2025-09-08T08:10:02.965Z,1757319002.965 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-08T08:10:02.965Z,1757319002.965 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-08T08:10:02.965Z,1757319002.965 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-08T08:10:02.966Z,1757319002.966 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-08T08:10:02.967Z,1757319002.967 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-08T08:10:02.967Z,1757319002.967 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-08T08:10:02.968Z,1757319002.968 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-08T08:10:02.968Z,1757319002.968 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-08T08:10:02.969Z,1757319002.969 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-08T08:10:02.969Z,1757319002.969 [keepstation:NeedComms:A] Running Loop=1 2025-09-08T08:10:02.972Z,1757319002.972 [keepstation:NeedComms:A](INFO): last time_fix was: 1757315401.000000 second since 1970/01/01T00:00:00Z 2025-09-08T08:10:02.972Z,1757319002.972 [keepstation:NeedComms:A] Stopped 2025-09-08T08:11:16.223Z,1757319076.223 [keepstation:NeedComms:C] Running Loop=1 2025-09-08T08:11:16.659Z,1757319076.659 [Radio_Surface](INFO): Powering up 2025-09-08T08:11:22.488Z,1757319082.488 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T08:11:48.892Z,1757319108.892 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-08T08:11:48.974Z,1757319108.974 [NAL9602](ERROR): received: +CSQ:0 OK064, 2, 0, 0, 0 OK 2025-09-08T08:11:52.607Z,1757319112.607 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:12:00.610Z,1757319120.610 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T08:12:11.518Z,1757319131.518 [NAL9602](INFO): SBD MO Status=2, MOMSN=23064, MT Status=2, MTMSN=0 2025-09-08T08:12:11.519Z,1757319131.519 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T08:12:23.739Z,1757319143.739 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:12:37.372Z,1757319157.372 [NAL9602](INFO): SBD MO Status=1, MOMSN=23064, MT Status=0, MTMSN=0 2025-09-08T08:12:37.373Z,1757319157.373 [NAL9602](INFO): No messages in MT queue 2025-09-08T08:12:38.586Z,1757319158.586 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081237.00,A,3645.65822,N,12154.49735,W,0.350,336.99,080925,,,D*70 2025-09-08T08:12:38.588Z,1757319158.588 [NAL9602](INFO): GPS fix at 20250908T081237: (36.760970, -121.908289) 2025-09-08T08:12:38.625Z,1757319158.625 [keepstation:NeedComms:C] Stopped 2025-09-08T08:12:38.625Z,1757319158.625 [keepstation:NeedComms:D] Running Loop=1 2025-09-08T08:12:54.867Z,1757319174.867 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:13:04.124Z,1757319184.124 [NAL9602](INFO): SBD MO Status=1, MOMSN=23065, MT Status=0, MTMSN=0 2025-09-08T08:13:04.175Z,1757319184.175 [NAL9602](INFO): Sent 90 bytes from file Logs/20250908T033000/Courier0015.lzma 2025-09-08T08:13:04.175Z,1757319184.175 [NAL9602](INFO): Packets left to send: 0 2025-09-08T08:13:21.961Z,1757319201.961 [NAL9602](INFO): SBD MO Status=2, MOMSN=23066, MT Status=2, MTMSN=0 2025-09-08T08:13:21.962Z,1757319201.962 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T08:13:26.007Z,1757319206.007 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:13:44.367Z,1757319224.367 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003242 2025-09-08T08:13:52.048Z,1757319232.048 [DataOverHttps](INFO): Sending 514 bytes from file Logs/20250908T033000/Express0016.lzma 2025-09-08T08:13:54.364Z,1757319234.364 [NAL9602](INFO): SBD MO Status=1, MOMSN=23066, MT Status=0, MTMSN=0 2025-09-08T08:13:54.416Z,1757319234.416 [NAL9602](INFO): Sent 332 bytes from file Logs/20250908T033000/Express0016.lzma 2025-09-08T08:13:54.416Z,1757319234.416 [NAL9602](INFO): Packets left to send: 1 2025-09-08T08:14:03.095Z,1757319243.095 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Express0016.lzma.bak 2025-09-08T08:14:03.095Z,1757319243.095 [DataOverHttps](INFO): SBD MOMSN=25942385 2025-09-08T08:14:04.496Z,1757319244.496 [keepstation:NeedComms:D] Stopped 2025-09-08T08:14:04.497Z,1757319244.497 [keepstation:NeedComms:E] Running Loop=1 2025-09-08T08:14:06.499Z,1757319246.499 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081405.00,A,3645.66415,N,12154.49876,W,0.233,330.76,080925,,,D*77 2025-09-08T08:14:06.507Z,1757319246.507 [NAL9602](INFO): GPS fix at 20250908T081405: (36.761069, -121.908313) 2025-09-08T08:14:06.580Z,1757319246.580 [keepstation:NeedComms:E] Stopped 2025-09-08T08:14:06.581Z,1757319246.581 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-08T08:14:06.581Z,1757319246.581 [keepstation:NeedComms] Stopped 2025-09-08T08:14:06.581Z,1757319246.581 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-08T08:14:06.581Z,1757319246.581 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-08T08:14:06.581Z,1757319246.581 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-08T08:14:06.928Z,1757319246.928 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-08T08:14:06.928Z,1757319246.928 [keepstation:SurfaceComms:B] Stopped 2025-09-08T08:14:06.928Z,1757319246.928 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-08T08:14:06.928Z,1757319246.928 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-08T08:14:06.928Z,1757319246.928 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-08T08:14:06.928Z,1757319246.928 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-08T08:14:07.320Z,1757319247.320 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-08T08:14:07.321Z,1757319247.321 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-08T08:14:07.321Z,1757319247.321 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-08T08:14:07.321Z,1757319247.321 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-08T08:14:07.321Z,1757319247.321 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-08T08:14:07.321Z,1757319247.321 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-08T08:14:07.737Z,1757319247.737 [keepstation:SurfaceComms:setStation] Stopped 2025-09-08T08:14:07.737Z,1757319247.737 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-08T08:14:07.737Z,1757319247.737 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-08T08:14:07.737Z,1757319247.737 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-08T08:14:07.737Z,1757319247.737 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-08T08:14:07.737Z,1757319247.737 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-08T08:14:08.116Z,1757319248.116 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-08T08:14:08.116Z,1757319248.116 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-08T08:14:08.117Z,1757319248.117 [keepstation:SurfaceComms:setSink] Stopped 2025-09-08T08:14:08.117Z,1757319248.117 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-08T08:14:08.117Z,1757319248.117 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-08T08:14:08.117Z,1757319248.117 [keepstation:SurfaceComms] Stopped 2025-09-08T08:14:08.117Z,1757319248.117 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-08T08:14:08.573Z,1757319248.573 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-08T08:14:08.573Z,1757319248.573 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-08T08:14:08.573Z,1757319248.573 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-08T08:14:08.573Z,1757319248.573 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-08T08:14:08.573Z,1757319248.573 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-08T08:14:08.574Z,1757319248.574 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-08T08:14:08.574Z,1757319248.574 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-08T08:14:08.574Z,1757319248.574 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T08:14:08.574Z,1757319248.574 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-08T08:14:08.574Z,1757319248.574 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-08T08:14:08.574Z,1757319248.574 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-08T08:14:08.578Z,1757319248.578 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-08T08:14:08.579Z,1757319248.579 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-08T08:14:08.579Z,1757319248.579 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-08T08:14:08.579Z,1757319248.579 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-08T08:14:08.579Z,1757319248.579 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-08T08:14:08.579Z,1757319248.579 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-08T08:14:38.400Z,1757319278.400 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T08:16:05.443Z,1757319365.443 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:16:36.567Z,1757319396.567 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:17:02.515Z,1757319422.515 [Radio_Surface](INFO): Powering down 2025-09-08T08:17:07.695Z,1757319427.695 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:17:08.699Z,1757319428.699 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T08:18:12.932Z,1757319492.932 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-08T08:18:12.932Z,1757319492.932 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-08T08:18:12.933Z,1757319492.933 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T08:18:12.933Z,1757319492.933 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-08T08:18:13.328Z,1757319493.328 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-08T08:18:13.328Z,1757319493.328 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T08:18:13.328Z,1757319493.328 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-08T08:18:13.328Z,1757319493.328 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-08T08:18:13.328Z,1757319493.328 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T08:18:13.328Z,1757319493.328 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-08T08:18:13.739Z,1757319493.739 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-08T08:18:13.739Z,1757319493.739 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T08:18:13.739Z,1757319493.739 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-08T08:18:13.739Z,1757319493.739 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T08:18:13.740Z,1757319493.740 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T08:18:13.740Z,1757319493.740 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-08T08:18:13.740Z,1757319493.740 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T08:18:13.740Z,1757319493.740 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-08T08:18:13.740Z,1757319493.740 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-08T08:18:13.740Z,1757319493.740 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-08T08:18:13.740Z,1757319493.740 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-08T08:18:13.740Z,1757319493.740 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-08T08:18:14.153Z,1757319494.153 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-08T08:18:14.153Z,1757319494.153 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-08T08:18:14.154Z,1757319494.154 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-08T08:18:14.154Z,1757319494.154 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-08T08:18:14.154Z,1757319494.154 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-08T08:18:14.154Z,1757319494.154 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-08T08:18:14.154Z,1757319494.154 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-08T08:18:24.004Z,1757319504.004 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.727737 2025-09-08T08:21:20.267Z,1757319680.267 [Radio_Surface](INFO): Powering up 2025-09-08T08:21:25.767Z,1757319685.767 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T08:21:55.895Z,1757319715.895 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:22:00.773Z,1757319720.773 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T08:22:27.019Z,1757319747.019 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:22:58.143Z,1757319778.143 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:23:29.267Z,1757319809.267 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:24:00.403Z,1757319840.403 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:24:31.527Z,1757319871.527 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:24:49.135Z,1757319889.135 [Radio_Surface](INFO): Powering down 2025-09-08T08:25:02.651Z,1757319902.651 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T08:25:03.655Z,1757319903.655 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T08:26:39.942Z,1757319999.942 [DVL_micro](ERROR): Failed to parse: :BI,+00009,-00138,+00032,+00000,I 2025-09-08T08:32:01.118Z,1757320321.118 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T08:38:43.103Z,1757320723.103 [DVL_micro](ERROR): Failed to parse: :WI,-00141,+00105,-00052,+00000,A 2025-09-08T08:42:01.484Z,1757320921.484 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T08:52:01.830Z,1757321521.830 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T09:02:02.183Z,1757322122.183 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T09:12:02.533Z,1757322722.533 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T09:14:06.568Z,1757322846.568 [keepstation:SurfaceComms] Running Loop=1 2025-09-08T09:14:06.568Z,1757322846.568 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-08T09:14:06.569Z,1757322846.569 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-08T09:14:06.569Z,1757322846.569 [keepstation:SurfaceComms:A] Stopped 2025-09-08T09:14:06.569Z,1757322846.569 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-08T09:14:06.569Z,1757322846.569 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-08T09:14:06.571Z,1757322846.571 [keepstation:NeedComms] Running Loop=1 2025-09-08T09:14:06.571Z,1757322846.571 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-08T09:14:06.571Z,1757322846.571 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-08T09:14:06.571Z,1757322846.571 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-08T09:14:06.572Z,1757322846.572 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-08T09:14:06.572Z,1757322846.572 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-08T09:14:06.573Z,1757322846.573 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-08T09:14:06.573Z,1757322846.573 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-08T09:14:06.573Z,1757322846.573 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-08T09:14:06.574Z,1757322846.574 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-08T09:14:06.574Z,1757322846.574 [keepstation:NeedComms:A] Running Loop=1 2025-09-08T09:14:06.576Z,1757322846.576 [keepstation:NeedComms:A](INFO): last time_fix was: 1757319245.000000 second since 1970/01/01T00:00:00Z 2025-09-08T09:14:06.576Z,1757322846.576 [keepstation:NeedComms:A] Stopped 2025-09-08T09:14:06.973Z,1757322846.973 [keepstation:KeepStation:Dive] Stopped 2025-09-08T09:14:06.973Z,1757322846.973 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-08T09:14:06.973Z,1757322846.973 [keepstation:KeepStation:Dive:A] Stopped 2025-09-08T09:14:06.973Z,1757322846.973 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-08T09:14:06.973Z,1757322846.973 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-08T09:14:06.973Z,1757322846.973 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-08T09:14:06.974Z,1757322846.974 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-08T09:14:06.974Z,1757322846.974 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-08T09:14:06.974Z,1757322846.974 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-08T09:14:06.974Z,1757322846.974 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-08T09:16:02.201Z,1757322962.201 [keepstation:NeedComms:C] Running Loop=1 2025-09-08T09:16:02.767Z,1757322962.767 [Radio_Surface](INFO): Powering up 2025-09-08T09:16:08.335Z,1757322968.335 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T09:16:38.455Z,1757322998.455 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T09:16:46.694Z,1757323006.694 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T09:16:46.696Z,1757323006.696 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T09:16:46.696Z,1757323006.696 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-08T09:16:46.697Z,1757323006.697 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-08T09:16:46.700Z,1757323006.700 [BPC1](INFO): Received data from all battery sticks. 2025-09-08T09:17:09.579Z,1757323029.579 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T09:17:40.711Z,1757323060.711 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T09:17:52.139Z,1757323072.139 [DVL_micro](ERROR): Failed to parse: :SA,-01.57,-03.40,324.1 2025-09-08T09:18:11.835Z,1757323091.835 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T09:18:30.619Z,1757323110.619 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003244 2025-09-08T09:21:00.021Z,1757323260.021 [NAL9602](INFO): SBD MO Status=2, MOMSN=23067, MT Status=2, MTMSN=0 2025-09-08T09:21:00.021Z,1757323260.021 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T09:21:06.500Z,1757323266.500 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-08T09:21:10.933Z,1757323270.933 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,092110.00,A,3645.83180,N,12154.54137,W,0.408,164.48,080925,,,D*76 2025-09-08T09:21:10.936Z,1757323270.936 [NAL9602](INFO): GPS fix at 20250908T092110: (36.763863, -121.909023) 2025-09-08T09:21:10.948Z,1757323270.948 [UniversalFixResidualReporter](INFO): Fix residual: 39.7 %DT, over the last 674.0 m. Residual distance 267.4 m at bearing -150.7 degrees. Fix at (36.7639, -121.9090) with 317.1 m made good. 2025-09-08T09:21:10.951Z,1757323270.951 [keepstation:NeedComms:C] Stopped 2025-09-08T09:21:10.952Z,1757323270.952 [keepstation:NeedComms:D] Running Loop=1 2025-09-08T09:21:18.998Z,1757323278.998 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20250908T033000/Courier0018.lzma 2025-09-08T09:21:30.040Z,1757323290.040 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Courier0018.lzma.bak 2025-09-08T09:21:30.041Z,1757323290.041 [DataOverHttps](INFO): SBD MOMSN=25942482 2025-09-08T09:21:43.652Z,1757323303.652 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T09:21:56.349Z,1757323316.349 [DataOverHttps](INFO): Sending 542 bytes from file Logs/20250908T033000/Express0019.lzma 2025-09-08T09:22:02.645Z,1757323322.645 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T09:22:07.388Z,1757323327.388 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Express0019.lzma.bak 2025-09-08T09:22:07.388Z,1757323327.388 [DataOverHttps](INFO): SBD MOMSN=25942485 2025-09-08T09:22:08.751Z,1757323328.751 [keepstation:NeedComms:D] Stopped 2025-09-08T09:22:08.751Z,1757323328.751 [keepstation:NeedComms:E] Running Loop=1 2025-09-08T09:22:10.750Z,1757323330.750 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,092209.00,A,3645.83079,N,12154.53954,W,0.156,107.66,080925,,,D*77 2025-09-08T09:22:10.752Z,1757323330.752 [NAL9602](INFO): GPS fix at 20250908T092209: (36.763846, -121.908992) 2025-09-08T09:22:10.801Z,1757323330.801 [keepstation:NeedComms:E] Stopped 2025-09-08T09:22:10.802Z,1757323330.802 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-08T09:22:10.802Z,1757323330.802 [keepstation:NeedComms] Stopped 2025-09-08T09:22:10.802Z,1757323330.802 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-08T09:22:10.802Z,1757323330.802 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-08T09:22:10.802Z,1757323330.802 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-08T09:22:11.205Z,1757323331.205 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-08T09:22:11.205Z,1757323331.205 [keepstation:SurfaceComms:B] Stopped 2025-09-08T09:22:11.205Z,1757323331.205 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-08T09:22:11.205Z,1757323331.205 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-08T09:22:11.205Z,1757323331.205 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-08T09:22:11.205Z,1757323331.205 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-08T09:22:11.600Z,1757323331.600 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-08T09:22:11.600Z,1757323331.600 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-08T09:22:11.600Z,1757323331.600 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-08T09:22:11.600Z,1757323331.600 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-08T09:22:11.600Z,1757323331.600 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-08T09:22:11.600Z,1757323331.600 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-08T09:22:12.007Z,1757323332.007 [keepstation:SurfaceComms:setStation] Stopped 2025-09-08T09:22:12.007Z,1757323332.007 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-08T09:22:12.008Z,1757323332.008 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-08T09:22:12.008Z,1757323332.008 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-08T09:22:12.008Z,1757323332.008 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-08T09:22:12.008Z,1757323332.008 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-08T09:22:12.438Z,1757323332.438 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-08T09:22:12.438Z,1757323332.438 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-08T09:22:12.445Z,1757323332.445 [keepstation:SurfaceComms:setSink] Stopped 2025-09-08T09:22:12.445Z,1757323332.445 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-08T09:22:12.445Z,1757323332.445 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-08T09:22:12.445Z,1757323332.445 [keepstation:SurfaceComms] Stopped 2025-09-08T09:22:12.445Z,1757323332.445 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-08T09:22:12.855Z,1757323332.855 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-08T09:22:12.855Z,1757323332.855 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-08T09:22:12.855Z,1757323332.855 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-08T09:22:12.855Z,1757323332.855 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-08T09:22:12.856Z,1757323332.856 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-08T09:22:12.856Z,1757323332.856 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-08T09:22:12.856Z,1757323332.856 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-08T09:22:12.856Z,1757323332.856 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T09:22:12.856Z,1757323332.856 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-08T09:22:12.856Z,1757323332.856 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-08T09:22:12.856Z,1757323332.856 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-08T09:22:12.856Z,1757323332.856 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-08T09:22:12.856Z,1757323332.856 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-08T09:22:12.857Z,1757323332.857 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-08T09:22:12.857Z,1757323332.857 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-08T09:22:12.857Z,1757323332.857 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-08T09:22:12.857Z,1757323332.857 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-08T09:22:41.480Z,1757323361.480 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-08T09:22:41.558Z,1757323361.558 [NAL9602](ERROR): received: +CSQ:0 OK067, 2, 0, 0, 0 OK 2025-09-08T09:23:20.651Z,1757323400.651 [NAL9602](INFO): SBD MO Status=2, MOMSN=23067, MT Status=2, MTMSN=0 2025-09-08T09:23:20.651Z,1757323400.651 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T09:23:40.795Z,1757323420.795 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-08T09:24:11.947Z,1757323451.947 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T09:24:37.919Z,1757323477.918 [Radio_Surface](INFO): Powering down 2025-09-08T09:24:43.071Z,1757323483.071 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T09:24:44.075Z,1757323484.075 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T09:25:27.128Z,1757323527.128 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-08T09:25:27.128Z,1757323527.128 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-08T09:25:27.129Z,1757323527.129 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T09:25:27.129Z,1757323527.129 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-08T09:25:27.511Z,1757323527.511 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-08T09:25:27.511Z,1757323527.511 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T09:25:27.511Z,1757323527.511 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-08T09:25:27.511Z,1757323527.511 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-08T09:25:27.512Z,1757323527.512 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T09:25:27.512Z,1757323527.512 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-08T09:25:27.923Z,1757323527.923 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-08T09:25:27.924Z,1757323527.924 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T09:25:27.924Z,1757323527.924 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-08T09:25:27.924Z,1757323527.924 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T09:25:27.924Z,1757323527.924 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T09:25:27.924Z,1757323527.924 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-08T09:25:27.925Z,1757323527.925 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T09:25:27.925Z,1757323527.925 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-08T09:25:27.925Z,1757323527.925 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-08T09:25:27.925Z,1757323527.925 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-08T09:25:27.925Z,1757323527.925 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-08T09:25:27.925Z,1757323527.925 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-08T09:25:28.327Z,1757323528.327 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-08T09:25:28.327Z,1757323528.327 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-08T09:25:28.327Z,1757323528.327 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-08T09:25:28.327Z,1757323528.327 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-08T09:25:28.327Z,1757323528.327 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-08T09:25:28.327Z,1757323528.327 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-08T09:25:28.327Z,1757323528.327 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-08T09:25:58.376Z,1757323558.376 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.544128 2025-09-08T09:27:13.357Z,1757323633.357 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-08T09:27:44.062Z,1757323664.062 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T09:30:17.574Z,1757323817.574 [DVL_micro](ERROR): Failed to parse: :WI,+00057,+00204,-00046,+00000,A 2025-09-08T09:32:03.029Z,1757323923.029 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T09:42:03.366Z,1757324523.366 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T09:52:03.707Z,1757325123.707 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T09:53:53.595Z,1757325233.595 [DVL_micro](ERROR): Failed to parse: :WI,+00013,-00026,-00006,+00000,A 2025-09-08T10:02:04.062Z,1757325724.062 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-08T10:19:09.813Z,1757326749.813 [DVL_micro](ERROR): Failed to parse: :WI,+00137,-00209,+00041,+00000,A 2025-09-08T10:19:11.832Z,1757326751.832 [DVL_micro](ERROR): Failed to parse: :WI,+00092,+00080,-00064,+00000,A 2025-09-08T10:19:13.934Z,1757326753.934 [DVL_micro](ERROR): Failed to parse: :BI,+00056,-00208,-00020,+00000,I 2025-09-08T10:22:10.813Z,1757326930.813 [keepstation:SurfaceComms] Running Loop=1 2025-09-08T10:22:10.813Z,1757326930.813 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-08T10:22:10.814Z,1757326930.814 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-08T10:22:10.814Z,1757326930.814 [keepstation:SurfaceComms:A] Stopped 2025-09-08T10:22:10.814Z,1757326930.814 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-08T10:22:10.814Z,1757326930.814 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-08T10:22:11.271Z,1757326931.271 [keepstation:KeepStation:Dive] Stopped 2025-09-08T10:22:11.272Z,1757326931.272 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-08T10:22:11.272Z,1757326931.272 [keepstation:KeepStation:Dive:A] Stopped 2025-09-08T10:22:11.272Z,1757326931.272 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-08T10:22:11.272Z,1757326931.272 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-08T10:22:11.272Z,1757326931.272 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-08T10:22:11.272Z,1757326931.272 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-08T10:22:11.272Z,1757326931.272 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-08T10:22:11.272Z,1757326931.272 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-08T10:22:11.273Z,1757326931.273 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-08T10:22:11.278Z,1757326931.278 [keepstation:NeedComms] Running Loop=1 2025-09-08T10:22:11.279Z,1757326931.279 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-08T10:22:11.279Z,1757326931.279 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-08T10:22:11.279Z,1757326931.279 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-08T10:22:11.279Z,1757326931.279 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-08T10:22:11.280Z,1757326931.280 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-08T10:22:11.280Z,1757326931.280 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-08T10:22:11.280Z,1757326931.280 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-08T10:22:11.280Z,1757326931.280 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-08T10:22:11.281Z,1757326931.281 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-08T10:22:11.281Z,1757326931.281 [keepstation:NeedComms:A] Running Loop=1 2025-09-08T10:22:11.287Z,1757326931.287 [keepstation:NeedComms:A](INFO): last time_fix was: 1757323329.000000 second since 1970/01/01T00:00:00Z 2025-09-08T10:22:11.287Z,1757326931.287 [keepstation:NeedComms:A] Stopped 2025-09-08T10:23:34.203Z,1757327014.203 [keepstation:NeedComms:C] Running Loop=1 2025-09-08T10:23:34.611Z,1757327014.611 [Radio_Surface](INFO): Powering up 2025-09-08T10:23:40.835Z,1757327020.835 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T10:24:07.048Z,1757327047.048 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-08T10:24:07.130Z,1757327047.130 [NAL9602](ERROR): received: +CSQ:0 OK067, 2, 0, 0, 0 OK 2025-09-08T10:24:10.963Z,1757327050.963 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T10:24:21.604Z,1757327061.604 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T10:24:21.605Z,1757327061.605 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T10:24:21.606Z,1757327061.606 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-08T10:24:21.607Z,1757327061.607 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-08T10:24:21.610Z,1757327061.610 [BPC1](INFO): Received data from all battery sticks. 2025-09-08T10:24:42.087Z,1757327082.087 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T10:25:03.615Z,1757327103.615 [NAL9602](INFO): SBD MO Status=0, MOMSN=23067, MT Status=0, MTMSN=0 2025-09-08T10:25:03.615Z,1757327103.615 [NAL9602](INFO): No messages in MT queue 2025-09-08T10:25:04.822Z,1757327104.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102504.00,A,3645.98658,N,12154.35132,W,0.272,29.07,080925,,,D*4D 2025-09-08T10:25:04.825Z,1757327104.825 [NAL9602](INFO): GPS fix at 20250908T102504: (36.766443, -121.905855) 2025-09-08T10:25:04.843Z,1757327104.843 [keepstation:NeedComms:C] Stopped 2025-09-08T10:25:04.844Z,1757327104.844 [keepstation:NeedComms:D] Running Loop=1 2025-09-08T10:25:13.227Z,1757327113.227 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T10:25:13.397Z,1757327113.397 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-08T10:25:15.415Z,1757327115.415 [DVL_micro](ERROR): Failed to parse::WI,+00057,+00197,+00027,+00000,A 2025-09-08T10:25:17.432Z,1757327117.432 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-08T10:25:21.886Z,1757327121.886 [NAL9602](INFO): SBD MO Status=1, MOMSN=23068, MT Status=0, MTMSN=0 2025-09-08T10:25:21.951Z,1757327121.951 [NAL9602](INFO): Sent 90 bytes from file Logs/20250908T033000/Courier0021.lzma 2025-09-08T10:25:21.951Z,1757327121.951 [NAL9602](INFO): Packets left to send: 0 2025-09-08T10:25:32.445Z,1757327132.445 [NAL9602](INFO): SBD MO Status=1, MOMSN=23069, MT Status=0, MTMSN=0 2025-09-08T10:25:32.503Z,1757327132.503 [NAL9602](INFO): Sent 332 bytes from file Logs/20250908T033000/Express0022.lzma 2025-09-08T10:25:32.503Z,1757327132.503 [NAL9602](INFO): Packets left to send: 1 2025-09-08T10:25:41.394Z,1757327141.394 [NAL9602](INFO): SBD MO Status=1, MOMSN=23070, MT Status=0, MTMSN=0 2025-09-08T10:25:41.454Z,1757327141.454 [NAL9602](INFO): Sent 78 bytes from file Logs/20250908T033000/Express0022.lzma 2025-09-08T10:25:41.455Z,1757327141.455 [NAL9602](INFO): Packets left to send: 0 2025-09-08T10:25:44.367Z,1757327144.367 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T10:25:59.978Z,1757327159.978 [NAL9602](INFO): SBD MO Status=2, MOMSN=23071, MT Status=2, MTMSN=0 2025-09-08T10:25:59.978Z,1757327159.978 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T10:26:03.099Z,1757327163.099 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003213 2025-09-08T10:26:04.617Z,1757327164.617 [keepstation:NeedComms:D] Stopped 2025-09-08T10:26:04.617Z,1757327164.617 [keepstation:NeedComms:E] Running Loop=1 2025-09-08T10:26:16.947Z,1757327176.947 [NAL9602](INFO): SBD MO Status=2, MOMSN=23071, MT Status=2, MTMSN=0 2025-09-08T10:26:16.948Z,1757327176.948 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T10:26:18.157Z,1757327178.157 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102617.00,A,3645.98526,N,12154.34586,W,0.039,104.97,080925,,,D*76 2025-09-08T10:26:18.160Z,1757327178.160 [NAL9602](INFO): GPS fix at 20250908T102617: (36.766421, -121.905764) 2025-09-08T10:26:18.193Z,1757327178.193 [keepstation:NeedComms:E] Stopped 2025-09-08T10:26:18.194Z,1757327178.194 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-08T10:26:18.194Z,1757327178.194 [keepstation:NeedComms] Stopped 2025-09-08T10:26:18.194Z,1757327178.194 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-08T10:26:18.194Z,1757327178.194 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-08T10:26:18.194Z,1757327178.194 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-08T10:26:18.588Z,1757327178.588 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-08T10:26:18.588Z,1757327178.588 [keepstation:SurfaceComms:B] Stopped 2025-09-08T10:26:18.588Z,1757327178.588 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-08T10:26:18.588Z,1757327178.588 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-08T10:26:18.588Z,1757327178.588 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-08T10:26:18.589Z,1757327178.589 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-08T10:26:18.994Z,1757327178.994 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-08T10:26:18.994Z,1757327178.994 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-08T10:26:18.994Z,1757327178.994 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-08T10:26:18.995Z,1757327178.995 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-08T10:26:18.995Z,1757327178.995 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-08T10:26:18.995Z,1757327178.995 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-08T10:26:19.398Z,1757327179.398 [keepstation:SurfaceComms:setStation] Stopped 2025-09-08T10:26:19.398Z,1757327179.398 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-08T10:26:19.398Z,1757327179.398 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-08T10:26:19.399Z,1757327179.399 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-08T10:26:19.399Z,1757327179.399 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-08T10:26:19.399Z,1757327179.399 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-08T10:26:19.807Z,1757327179.807 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-08T10:26:19.807Z,1757327179.807 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-08T10:26:19.807Z,1757327179.807 [keepstation:SurfaceComms:setSink] Stopped 2025-09-08T10:26:19.807Z,1757327179.807 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-08T10:26:19.808Z,1757327179.808 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-08T10:26:19.808Z,1757327179.808 [keepstation:SurfaceComms] Stopped 2025-09-08T10:26:19.808Z,1757327179.808 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-08T10:26:20.201Z,1757327180.201 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-08T10:26:20.201Z,1757327180.201 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-08T10:26:20.201Z,1757327180.201 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-08T10:26:20.202Z,1757327180.202 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-08T10:26:20.202Z,1757327180.202 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-08T10:26:20.202Z,1757327180.202 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-08T10:26:20.203Z,1757327180.203 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-08T10:26:20.203Z,1757327180.203 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T10:26:20.203Z,1757327180.203 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-08T10:26:20.203Z,1757327180.203 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-08T10:26:20.203Z,1757327180.203 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-08T10:26:20.203Z,1757327180.203 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-08T10:26:20.203Z,1757327180.203 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-08T10:26:20.204Z,1757327180.204 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-08T10:26:20.204Z,1757327180.204 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-08T10:26:20.204Z,1757327180.204 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-08T10:26:20.204Z,1757327180.204 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-08T10:26:50.490Z,1757327210.490 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T10:27:36.111Z,1757327256.111 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-08T10:28:07.267Z,1757327287.267 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T10:28:34.003Z,1757327314.003 [Radio_Surface](INFO): Powering down 2025-09-08T10:28:38.391Z,1757327318.391 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T10:28:39.395Z,1757327319.395 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T10:29:18.765Z,1757327358.765 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-08T10:29:18.765Z,1757327358.765 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-08T10:29:18.765Z,1757327358.765 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T10:29:18.765Z,1757327358.765 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-08T10:29:19.188Z,1757327359.188 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-08T10:29:19.188Z,1757327359.188 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T10:29:19.188Z,1757327359.188 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-08T10:29:19.188Z,1757327359.188 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-08T10:29:19.188Z,1757327359.188 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T10:29:19.188Z,1757327359.188 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-08T10:29:19.572Z,1757327359.572 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-08T10:29:19.572Z,1757327359.572 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T10:29:19.572Z,1757327359.572 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-08T10:29:19.572Z,1757327359.572 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T10:29:19.573Z,1757327359.573 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T10:29:19.573Z,1757327359.573 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-08T10:29:19.573Z,1757327359.573 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T10:29:19.573Z,1757327359.573 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-08T10:29:19.573Z,1757327359.573 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-08T10:29:19.573Z,1757327359.573 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-08T10:29:19.573Z,1757327359.573 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-08T10:29:19.573Z,1757327359.573 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-08T10:29:20.080Z,1757327360.080 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-08T10:29:20.080Z,1757327360.080 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-08T10:29:20.080Z,1757327360.080 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-08T10:29:20.080Z,1757327360.080 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-08T10:29:20.080Z,1757327360.080 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-08T10:29:20.080Z,1757327360.080 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-08T10:29:20.080Z,1757327360.080 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-08T10:29:53.699Z,1757327393.699 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.531006 2025-09-08T10:37:51.443Z,1757327871.443 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-09-08T10:40:39.894Z,1757328039.894 [DVL_micro](ERROR): Failed to parse: :RD,+0000.32,+9999.99,+0000.30,+0002.19 2025-09-08T10:50:47.178Z,1757328647.178 [DVL_micro](ERROR): DVL uart error: serial timeout 2025-09-08T10:50:47.178Z,1757328647.178 [DVL_micro] Communications Fault, FailCount= 1 2025-09-08T10:50:47.178Z,1757328647.178 [DVL_micro](ERROR): Communications Fault 2025-09-08T10:50:47.179Z,1757328647.179 [DVL_micro](ERROR): Failed to parse: 2025-09-08T10:50:47.237Z,1757328647.237 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-09-08T10:50:47.603Z,1757328647.603 [DVL_micro](INFO): Powering down 2025-09-08T10:50:48.376Z,1757328648.376 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-09-08T10:50:48.376Z,1757328648.376 [DVL_micro] No Fault, FailCount= 1 2025-09-08T10:59:57.377Z,1757329197.377 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-09-08T11:00:01.989Z,1757329201.989 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.001913 CHAN A1 (24V): 0.020897 CHAN A2 (12V): -0.006841 CHAN A3 (5V): -0.009546 CHAN B0 (3.3V): -0.009962 CHAN B1 (3.15aV): -0.009059 CHAN B2 (3.15bV): -0.008706 CHAN B3 (GND): -0.009311 OPEN: 0.007319 Full Scale: +/- 1 mA 2025-09-08T11:00:03.816Z,1757329203.816 [DVL_micro](ERROR): Failed to parse: :SA,-00.52,-00.34,164.2 2025-09-08T11:02:15.518Z,1757329335.518 [DVL_micro](ERROR): Failed to parse::RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-09-08T11:10:19.523Z,1757329819.523 [DVL_micro](ERROR): Failed to parse: :WI,-00014,-00097,-00028,+00000,A 2025-09-08T11:10:21.546Z,1757329821.546 [DVL_micro](ERROR): Failed to parse: :WI,-00108,-00044,-00029,+00000,A 2025-09-08T11:10:23.574Z,1757329823.574 [DVL_micro](ERROR): Failed to parse: :BI,-00020,-00088,-00038,+00000,I 2025-09-08T11:10:25.577Z,1757329825.577 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-09-08T11:10:26.378Z,1757329826.378 [DVL_micro](ERROR): No DVL communication! Re-initializing 2025-09-08T11:10:26.378Z,1757329826.378 [DVL_micro] Communications Fault, FailCount= 1 2025-09-08T11:10:26.378Z,1757329826.378 [DVL_micro](ERROR): Communications Fault 2025-09-08T11:10:26.425Z,1757329826.425 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-09-08T11:10:26.871Z,1757329826.871 [DVL_micro](INFO): Powering down 2025-09-08T11:10:27.621Z,1757329827.621 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-09-08T11:10:27.621Z,1757329827.621 [DVL_micro] No Fault, FailCount= 1 2025-09-08T11:10:44.980Z,1757329844.980 [DVL_micro](ERROR): Failed to parse: :SA,+00.89,-00.83,084.0 2025-09-08T11:10:47.009Z,1757329847.009 [DVL_micro](ERROR): Failed to parse: :SA,+00.88,-00.80,083.5 2025-09-08T11:26:18.290Z,1757330778.290 [keepstation:SurfaceComms] Running Loop=1 2025-09-08T11:26:18.290Z,1757330778.290 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-08T11:26:18.290Z,1757330778.290 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-08T11:26:18.291Z,1757330778.291 [keepstation:SurfaceComms:A] Stopped 2025-09-08T11:26:18.291Z,1757330778.291 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-08T11:26:18.291Z,1757330778.291 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-08T11:26:18.655Z,1757330778.655 [keepstation:KeepStation:Dive] Stopped 2025-09-08T11:26:18.655Z,1757330778.655 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-08T11:26:18.655Z,1757330778.655 [keepstation:KeepStation:Dive:A] Stopped 2025-09-08T11:26:18.655Z,1757330778.655 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-08T11:26:18.656Z,1757330778.656 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-08T11:26:18.656Z,1757330778.656 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-08T11:26:18.656Z,1757330778.656 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-08T11:26:18.656Z,1757330778.656 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-08T11:26:18.656Z,1757330778.656 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-08T11:26:18.657Z,1757330778.657 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-08T11:26:18.659Z,1757330778.659 [keepstation:NeedComms] Running Loop=1 2025-09-08T11:26:18.660Z,1757330778.660 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-08T11:26:18.660Z,1757330778.660 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-08T11:26:18.660Z,1757330778.660 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-08T11:26:18.660Z,1757330778.660 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-08T11:26:18.661Z,1757330778.661 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-08T11:26:18.662Z,1757330778.662 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-08T11:26:18.662Z,1757330778.662 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-08T11:26:18.663Z,1757330778.663 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-08T11:26:18.663Z,1757330778.663 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-08T11:26:18.664Z,1757330778.664 [keepstation:NeedComms:A] Running Loop=1 2025-09-08T11:26:18.666Z,1757330778.666 [keepstation:NeedComms:A](INFO): last time_fix was: 1757327177.000000 second since 1970/01/01T00:00:00Z 2025-09-08T11:26:18.667Z,1757330778.667 [keepstation:NeedComms:A] Stopped 2025-09-08T11:27:32.415Z,1757330852.415 [keepstation:NeedComms:C] Running Loop=1 2025-09-08T11:27:33.140Z,1757330853.140 [Radio_Surface](INFO): Powering up 2025-09-08T11:27:39.111Z,1757330859.111 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T11:29:47.151Z,1757330987.151 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003228 2025-09-08T11:31:57.367Z,1757331117.367 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T11:31:57.367Z,1757331117.367 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T11:31:57.368Z,1757331117.368 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-08T11:31:57.369Z,1757331117.369 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-08T11:31:57.376Z,1757331117.376 [BPC1](INFO): Received data from all battery sticks. 2025-09-08T11:32:36.536Z,1757331156.536 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-08T11:32:44.216Z,1757331164.216 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113243.00,A,3646.05224,N,12153.86621,W,0.583,66.62,080925,,,D*4A 2025-09-08T11:32:44.219Z,1757331164.219 [NAL9602](INFO): GPS fix at 20250908T113243: (36.767537, -121.897770) 2025-09-08T11:32:44.232Z,1757331164.232 [keepstation:NeedComms:C] Stopped 2025-09-08T11:32:44.232Z,1757331164.232 [keepstation:NeedComms:D] Running Loop=1 2025-09-08T11:32:52.179Z,1757331172.179 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20250908T033000/Courier0024.lzma 2025-09-08T11:33:03.224Z,1757331183.224 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Courier0024.lzma.bak 2025-09-08T11:33:03.225Z,1757331183.225 [DataOverHttps](INFO): SBD MOMSN=25942837 2025-09-08T11:33:16.538Z,1757331196.538 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T11:33:29.596Z,1757331209.596 [DataOverHttps](INFO): Sending 671 bytes from file Logs/20250908T033000/Express0025.lzma 2025-09-08T11:33:40.636Z,1757331220.636 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Express0025.lzma.bak 2025-09-08T11:33:40.637Z,1757331220.637 [DataOverHttps](INFO): SBD MOMSN=25942840 2025-09-08T11:33:42.005Z,1757331222.005 [keepstation:NeedComms:D] Stopped 2025-09-08T11:33:42.005Z,1757331222.005 [keepstation:NeedComms:E] Running Loop=1 2025-09-08T11:33:44.020Z,1757331224.020 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113343.00,A,3646.05305,N,12153.86034,W,0.350,79.37,080925,,,D*4D 2025-09-08T11:33:44.027Z,1757331224.027 [NAL9602](INFO): GPS fix at 20250908T113343: (36.767551, -121.897672) 2025-09-08T11:33:44.049Z,1757331224.049 [keepstation:NeedComms:E] Stopped 2025-09-08T11:33:44.050Z,1757331224.050 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-08T11:33:44.050Z,1757331224.050 [keepstation:NeedComms] Stopped 2025-09-08T11:33:44.050Z,1757331224.050 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-08T11:33:44.050Z,1757331224.050 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-08T11:33:44.050Z,1757331224.050 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-08T11:33:44.459Z,1757331224.459 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-08T11:33:44.459Z,1757331224.459 [keepstation:SurfaceComms:B] Stopped 2025-09-08T11:33:44.459Z,1757331224.459 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-08T11:33:44.459Z,1757331224.459 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-08T11:33:44.460Z,1757331224.460 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-08T11:33:44.460Z,1757331224.460 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-08T11:33:44.836Z,1757331224.836 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-08T11:33:44.836Z,1757331224.836 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-08T11:33:44.836Z,1757331224.836 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-08T11:33:44.836Z,1757331224.836 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-08T11:33:44.836Z,1757331224.836 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-08T11:33:44.836Z,1757331224.836 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-08T11:33:45.311Z,1757331225.311 [keepstation:SurfaceComms:setStation] Stopped 2025-09-08T11:33:45.311Z,1757331225.311 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-08T11:33:45.312Z,1757331225.312 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-08T11:33:45.312Z,1757331225.312 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-08T11:33:45.312Z,1757331225.312 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-08T11:33:45.312Z,1757331225.312 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-08T11:33:45.656Z,1757331225.656 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-08T11:33:45.656Z,1757331225.656 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-08T11:33:45.656Z,1757331225.656 [keepstation:SurfaceComms:setSink] Stopped 2025-09-08T11:33:45.656Z,1757331225.656 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-08T11:33:45.656Z,1757331225.656 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-08T11:33:45.657Z,1757331225.657 [keepstation:SurfaceComms] Stopped 2025-09-08T11:33:45.657Z,1757331225.657 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-08T11:33:46.077Z,1757331226.077 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-08T11:33:46.077Z,1757331226.077 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-08T11:33:46.077Z,1757331226.077 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-08T11:33:46.077Z,1757331226.077 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-08T11:33:46.078Z,1757331226.078 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-08T11:33:46.078Z,1757331226.078 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-08T11:33:46.078Z,1757331226.078 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-08T11:33:46.078Z,1757331226.078 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T11:33:46.082Z,1757331226.082 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-08T11:33:46.082Z,1757331226.082 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-08T11:33:46.082Z,1757331226.082 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-08T11:33:46.083Z,1757331226.083 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-08T11:33:46.083Z,1757331226.083 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-08T11:33:46.083Z,1757331226.083 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-08T11:33:46.083Z,1757331226.083 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-08T11:33:46.083Z,1757331226.083 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-08T11:33:46.084Z,1757331226.084 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-08T11:34:14.741Z,1757331254.741 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-08T11:34:14.818Z,1757331254.818 [NAL9602](ERROR): received: +CSQ:0 OK071, 2, 0, 0, 0 OK 2025-09-08T11:35:13.615Z,1757331313.615 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-08T11:35:18.959Z,1757331318.959 [DVL_micro](ERROR): Failed to parse: :SA,+01.43,-00.65,256.1 2025-09-08T11:35:44.739Z,1757331344.739 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T11:36:03.523Z,1757331363.523 [Radio_Surface](INFO): Powering down 2025-09-08T11:36:15.863Z,1757331375.863 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T11:36:16.867Z,1757331376.867 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T11:37:16.553Z,1757331436.553 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-08T11:37:16.553Z,1757331436.553 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-08T11:37:16.553Z,1757331436.553 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T11:37:16.553Z,1757331436.553 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-08T11:37:16.944Z,1757331436.944 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-08T11:37:16.944Z,1757331436.944 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T11:37:16.944Z,1757331436.944 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-08T11:37:16.944Z,1757331436.944 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-08T11:37:16.944Z,1757331436.944 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T11:37:16.944Z,1757331436.944 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-08T11:37:17.360Z,1757331437.360 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-08T11:37:17.360Z,1757331437.360 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T11:37:17.360Z,1757331437.360 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-08T11:37:17.360Z,1757331437.360 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T11:37:17.361Z,1757331437.361 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T11:37:17.361Z,1757331437.361 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-08T11:37:17.361Z,1757331437.361 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T11:37:17.361Z,1757331437.361 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-08T11:37:17.361Z,1757331437.361 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-08T11:37:17.361Z,1757331437.361 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-08T11:37:17.361Z,1757331437.361 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-08T11:37:17.362Z,1757331437.362 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-08T11:37:17.751Z,1757331437.751 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-08T11:37:17.751Z,1757331437.751 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-08T11:37:17.751Z,1757331437.751 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-08T11:37:17.751Z,1757331437.751 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-08T11:37:17.751Z,1757331437.751 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-08T11:37:17.752Z,1757331437.752 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-08T11:37:17.752Z,1757331437.752 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-08T11:37:31.167Z,1757331451.167 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.531647 2025-09-08T11:38:46.603Z,1757331526.603 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-08T11:39:17.311Z,1757331557.311 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T11:40:55.215Z,1757331655.215 [Radio_Surface](INFO): Powering up 2025-09-08T11:41:01.007Z,1757331661.007 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T11:41:31.128Z,1757331691.128 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T11:42:02.252Z,1757331722.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T11:42:33.375Z,1757331753.375 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T11:42:39.446Z,1757331759.446 [Radio_Surface](INFO): Powering down 2025-09-08T11:43:04.499Z,1757331784.499 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T11:43:05.503Z,1757331785.503 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T12:05:45.450Z,1757333145.450 [DVL_micro](ERROR): Failed to parse: :SA,-00.53,-00.29,113.7 2025-09-08T12:05:47.463Z,1757333147.463 [DVL_micro](ERROR): Failed to parse: :SA,-00.64,-00.25,113.3 2025-09-08T12:11:45.805Z,1757333505.805 [DVL_micro](ERROR): Failed to parse: :WI,-00008,+00113,+00079,+00000,A 2025-09-08T12:25:25.932Z,1757334325.932 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-08T12:25:27.963Z,1757334327.963 [DVL_micro](ERROR): Failed to parse: :WI,-00038,+00106,-00027,+00000,A 2025-09-08T12:25:29.995Z,1757334329.995 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-09-08T12:25:32.007Z,1757334332.007 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-09-08T12:25:32.386Z,1757334332.386 [DVL_micro](ERROR): No DVL communication! Re-initializing 2025-09-08T12:25:32.386Z,1757334332.386 [DVL_micro] Communications Fault, FailCount= 1 2025-09-08T12:25:32.386Z,1757334332.386 [DVL_micro](ERROR): Communications Fault 2025-09-08T12:25:32.409Z,1757334332.409 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-09-08T12:25:32.891Z,1757334332.891 [DVL_micro](INFO): Powering down 2025-09-08T12:25:33.618Z,1757334333.618 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-09-08T12:25:33.618Z,1757334333.618 [DVL_micro] No Fault, FailCount= 1 2025-09-08T12:33:44.074Z,1757334824.074 [keepstation:SurfaceComms] Running Loop=1 2025-09-08T12:33:44.074Z,1757334824.074 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-09-08T12:33:44.091Z,1757334824.091 [keepstation:SurfaceComms:A] Running Loop=1 2025-09-08T12:33:44.094Z,1757334824.094 [keepstation:SurfaceComms:A] Stopped 2025-09-08T12:33:44.094Z,1757334824.094 [keepstation:SurfaceComms:B] Running Loop=1 2025-09-08T12:33:44.102Z,1757334824.102 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-09-08T12:33:44.104Z,1757334824.104 [keepstation:NeedComms] Running Loop=1 2025-09-08T12:33:44.104Z,1757334824.104 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-09-08T12:33:44.104Z,1757334824.104 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-09-08T12:33:44.104Z,1757334824.104 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-08T12:33:44.105Z,1757334824.105 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-09-08T12:33:44.105Z,1757334824.105 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-09-08T12:33:44.105Z,1757334824.105 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-09-08T12:33:44.106Z,1757334824.106 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-09-08T12:33:44.110Z,1757334824.110 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-08T12:33:44.111Z,1757334824.111 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-08T12:33:44.111Z,1757334824.111 [keepstation:NeedComms:A] Running Loop=1 2025-09-08T12:33:44.113Z,1757334824.113 [keepstation:NeedComms:A](INFO): last time_fix was: 1757331223.000000 second since 1970/01/01T00:00:00Z 2025-09-08T12:33:44.113Z,1757334824.113 [keepstation:NeedComms:A] Stopped 2025-09-08T12:33:44.476Z,1757334824.476 [keepstation:KeepStation:Dive] Stopped 2025-09-08T12:33:44.476Z,1757334824.476 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-09-08T12:33:44.476Z,1757334824.476 [keepstation:KeepStation:Dive:A] Stopped 2025-09-08T12:33:44.476Z,1757334824.476 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-09-08T12:33:44.477Z,1757334824.477 [keepstation:DiveCmd:StationKeep] Stopped 2025-09-08T12:33:44.477Z,1757334824.477 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-09-08T12:33:44.477Z,1757334824.477 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-09-08T12:33:44.477Z,1757334824.477 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-09-08T12:33:44.477Z,1757334824.477 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-09-08T12:33:44.477Z,1757334824.477 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-09-08T12:35:07.405Z,1757334907.405 [keepstation:NeedComms:C] Running Loop=1 2025-09-08T12:35:07.858Z,1757334907.858 [Radio_Surface](INFO): Powering up 2025-09-08T12:35:13.383Z,1757334913.383 [DataOverHttps](INFO): Radio surface powered ON. 2025-09-08T12:35:36.074Z,1757334936.074 [NAL9602](INFO): SBD MO Status=2, MOMSN=23071, MT Status=2, MTMSN=0 2025-09-08T12:35:36.074Z,1757334936.074 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T12:35:43.507Z,1757334943.507 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T12:35:49.410Z,1757334949.410 [NAL9602](INFO): SBD MO Status=2, MOMSN=23071, MT Status=2, MTMSN=0 2025-09-08T12:35:49.411Z,1757334949.411 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T12:35:58.699Z,1757334958.699 [NAL9602](INFO): SBD MO Status=0, MOMSN=23071, MT Status=0, MTMSN=0 2025-09-08T12:35:58.699Z,1757334958.699 [NAL9602](INFO): No messages in MT queue 2025-09-08T12:35:59.914Z,1757334959.914 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,123559.00,A,3645.95713,N,12153.30936,W,0.156,98.38,080925,,,A*4D 2025-09-08T12:35:59.916Z,1757334959.916 [NAL9602](INFO): GPS fix at 20250908T123559: (36.765952, -121.888489) 2025-09-08T12:35:59.929Z,1757334959.929 [keepstation:NeedComms:C] Stopped 2025-09-08T12:35:59.930Z,1757334959.930 [keepstation:NeedComms:D] Running Loop=1 2025-09-08T12:36:14.631Z,1757334974.631 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T12:36:24.741Z,1757334984.741 [NAL9602](INFO): SBD MO Status=2, MOMSN=23072, MT Status=2, MTMSN=0 2025-09-08T12:36:24.741Z,1757334984.741 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T12:36:41.434Z,1757335001.434 [NAL9602](INFO): SBD MO Status=2, MOMSN=23072, MT Status=2, MTMSN=0 2025-09-08T12:36:41.435Z,1757335001.435 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T12:36:45.755Z,1757335005.755 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T12:37:07.433Z,1757335027.433 [NAL9602](INFO): SBD MO Status=2, MOMSN=23072, MT Status=2, MTMSN=0 2025-09-08T12:37:07.433Z,1757335027.433 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-08T12:37:16.892Z,1757335036.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T12:37:27.352Z,1757335047.352 [NAL9602](INFO): SBD MO Status=1, MOMSN=23072, MT Status=0, MTMSN=0 2025-09-08T12:37:27.403Z,1757335047.403 [NAL9602](INFO): Sent 77 bytes from file Logs/20250908T033000/Courier0027.lzma 2025-09-08T12:37:27.403Z,1757335047.403 [NAL9602](INFO): Packets left to send: 0 2025-09-08T12:37:35.855Z,1757335055.855 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003242 2025-09-08T12:37:36.337Z,1757335056.337 [NAL9602](INFO): SBD MO Status=1, MOMSN=23073, MT Status=0, MTMSN=0 2025-09-08T12:37:36.388Z,1757335056.388 [NAL9602](INFO): Sent 332 bytes from file Logs/20250908T033000/Express0028.lzma 2025-09-08T12:37:36.388Z,1757335056.388 [NAL9602](INFO): Packets left to send: 1 2025-09-08T12:37:43.613Z,1757335063.613 [DataOverHttps](INFO): Sending 505 bytes from file Logs/20250908T033000/Express0028.lzma 2025-09-08T12:37:54.656Z,1757335074.656 [DataOverHttps](INFO): Moved sent file to Logs/20250908T033000/Express0028.lzma.bak 2025-09-08T12:37:54.657Z,1757335074.657 [DataOverHttps](INFO): SBD MOMSN=25942923 2025-09-08T12:37:56.188Z,1757335076.188 [keepstation:NeedComms:D] Stopped 2025-09-08T12:37:56.189Z,1757335076.189 [keepstation:NeedComms:E] Running Loop=1 2025-09-08T12:37:58.207Z,1757335078.207 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,123757.00,A,3645.95789,N,12153.29706,W,0.272,84.80,080925,,,A*4C 2025-09-08T12:37:58.209Z,1757335078.209 [NAL9602](INFO): GPS fix at 20250908T123757: (36.765965, -121.888284) 2025-09-08T12:37:58.228Z,1757335078.228 [keepstation:NeedComms:E] Stopped 2025-09-08T12:37:58.228Z,1757335078.228 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-09-08T12:37:58.228Z,1757335078.228 [keepstation:NeedComms] Stopped 2025-09-08T12:37:58.228Z,1757335078.228 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-09-08T12:37:58.229Z,1757335078.229 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-09-08T12:37:58.229Z,1757335078.229 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-08T12:37:58.600Z,1757335078.600 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-09-08T12:37:58.600Z,1757335078.600 [keepstation:SurfaceComms:B] Stopped 2025-09-08T12:37:58.600Z,1757335078.600 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-09-08T12:37:58.600Z,1757335078.600 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-09-08T12:37:58.600Z,1757335078.600 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-09-08T12:37:58.600Z,1757335078.600 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-09-08T12:37:59.004Z,1757335079.004 [keepstation:SurfaceComms:setTransit] Stopped 2025-09-08T12:37:59.004Z,1757335079.004 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-09-08T12:37:59.004Z,1757335079.004 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-09-08T12:37:59.004Z,1757335079.004 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-09-08T12:37:59.004Z,1757335079.004 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-09-08T12:37:59.004Z,1757335079.004 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-09-08T12:37:59.428Z,1757335079.428 [keepstation:SurfaceComms:setStation] Stopped 2025-09-08T12:37:59.428Z,1757335079.428 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-09-08T12:37:59.428Z,1757335079.428 [keepstation:SurfaceComms:setStation:A] Stopped 2025-09-08T12:37:59.428Z,1757335079.428 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-09-08T12:37:59.428Z,1757335079.428 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-09-08T12:37:59.428Z,1757335079.428 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-09-08T12:37:59.849Z,1757335079.849 [keepstation:SurfaceComms:setSink:A] Stopped 2025-09-08T12:37:59.849Z,1757335079.849 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-09-08T12:37:59.849Z,1757335079.849 [keepstation:SurfaceComms:setSink] Stopped 2025-09-08T12:37:59.849Z,1757335079.849 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-09-08T12:37:59.850Z,1757335079.850 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-09-08T12:37:59.850Z,1757335079.850 [keepstation:SurfaceComms] Stopped 2025-09-08T12:37:59.850Z,1757335079.850 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-09-08T12:38:00.228Z,1757335080.228 [keepstation:KeepStation:Dive] Running Loop=1 2025-09-08T12:38:00.228Z,1757335080.228 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-09-08T12:38:00.228Z,1757335080.228 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-09-08T12:38:00.228Z,1757335080.228 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-09-08T12:38:00.229Z,1757335080.229 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-09-08T12:38:00.229Z,1757335080.229 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-09-08T12:38:00.229Z,1757335080.229 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-09-08T12:38:00.229Z,1757335080.229 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T12:38:00.229Z,1757335080.229 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-09-08T12:38:00.229Z,1757335080.229 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-09-08T12:38:00.229Z,1757335080.229 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-09-08T12:38:00.229Z,1757335080.229 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-09-08T12:38:00.230Z,1757335080.230 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-09-08T12:38:00.230Z,1757335080.230 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-09-08T12:38:00.235Z,1757335080.235 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-09-08T12:38:00.235Z,1757335080.235 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-09-08T12:38:00.236Z,1757335080.236 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-09-08T12:38:30.493Z,1757335110.493 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-08T12:39:26.711Z,1757335166.711 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T12:39:35.536Z,1757335175.536 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T12:39:35.537Z,1757335175.537 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-08T12:39:35.538Z,1757335175.538 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-08T12:39:35.539Z,1757335175.539 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-09-08T12:39:35.542Z,1757335175.542 [BPC1](INFO): Received data from all battery sticks. 2025-09-08T12:39:57.835Z,1757335197.835 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T12:40:03.659Z,1757335203.659 [Radio_Surface](INFO): Powering down 2025-09-08T12:40:28.959Z,1757335228.959 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-08T12:40:29.963Z,1757335229.963 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-08T12:41:04.430Z,1757335264.430 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-09-08T12:41:04.431Z,1757335264.431 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-09-08T12:41:04.431Z,1757335264.431 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T12:41:04.431Z,1757335264.431 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-09-08T12:41:04.821Z,1757335264.821 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-09-08T12:41:04.822Z,1757335264.822 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-09-08T12:41:04.822Z,1757335264.822 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-09-08T12:41:04.822Z,1757335264.822 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-09-08T12:41:04.822Z,1757335264.822 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T12:41:04.822Z,1757335264.822 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-09-08T12:41:05.234Z,1757335265.234 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-09-08T12:41:05.234Z,1757335265.234 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T12:41:05.234Z,1757335265.234 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-09-08T12:41:05.234Z,1757335265.234 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-09-08T12:41:05.235Z,1757335265.235 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T12:41:05.235Z,1757335265.235 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-09-08T12:41:05.235Z,1757335265.235 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-09-08T12:41:05.235Z,1757335265.235 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-09-08T12:41:05.235Z,1757335265.235 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-09-08T12:41:05.235Z,1757335265.235 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-09-08T12:41:05.235Z,1757335265.235 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-09-08T12:41:05.235Z,1757335265.235 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-09-08T12:41:05.633Z,1757335265.633 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-09-08T12:41:05.633Z,1757335265.633 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-09-08T12:41:05.633Z,1757335265.633 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-09-08T12:41:05.633Z,1757335265.633 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-09-08T12:41:05.633Z,1757335265.633 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-09-08T12:41:05.633Z,1757335265.633 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-09-08T12:41:05.633Z,1757335265.633 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-09-08T12:41:46.285Z,1757335306.285 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.760818 2025-09-08T12:41:46.831Z,1757335306.831 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-09-08T12:41:50.898Z,1757335310.898 [DVL_micro](ERROR): Failed to parse: :BI,-00254,+00005,-00098,+00000,I 2025-09-08T12:41:52.913Z,1757335312.913 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-09-08T12:41:54.925Z,1757335314.925 [DVL_micro](ERROR): Failed to parse: :SA,+00.59,-01.12,090.5 2025-09-08T12:41:56.928Z,1757335316.928 [DVL_micro](ERROR): Failed to parse: :SA,+00.43,-01.06,089.9 2025-09-08T12:41:58.942Z,1757335318.942 [DVL_micro](ERROR): No DVL communication! Re-initializing 2025-09-08T12:41:58.942Z,1757335318.942 [DVL_micro] Communications Fault, FailCount= 1 2025-09-08T12:41:58.942Z,1757335318.942 [DVL_micro](ERROR): Communications Fault 2025-09-08T12:41:59.107Z,1757335319.107 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-09-08T12:41:59.447Z,1757335319.447 [DVL_micro](INFO): Powering down 2025-09-08T12:42:00.351Z,1757335320.351 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-09-08T12:42:00.351Z,1757335320.351 [DVL_micro] No Fault, FailCount= 1