2026-03-12T23:00:54.131Z,1773356454.131 [DataOverHttps](INFO): Received command: restart logs 2026-03-12T23:00:54.145Z,1773356454.145 [CommandExec](IMPORTANT): got command restart logs 2026-03-12T23:01:05.476Z,1773356465.476 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T23:05:45.612Z,1773356745.612 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2026-03-12T23:05:45.612Z,1773356745.612 [Default:CheckIn:C.Wait] Stopped 2026-03-12T23:05:45.612Z,1773356745.612 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-03-12T23:05:45.612Z,1773356745.612 [Default:CheckIn:D] Running Loop=1 2026-03-12T23:05:46.024Z,1773356746.024 [Default:CheckIn:D] Stopped 2026-03-12T23:05:46.025Z,1773356746.025 [Default:CheckIn:E] Running Loop=1 2026-03-12T23:05:46.418Z,1773356746.418 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.557694 min 2026-03-12T23:05:46.418Z,1773356746.418 [Default:CheckIn:E] Stopped 2026-03-12T23:05:46.419Z,1773356746.419 [Default:CheckIn](INFO): Completed Default:CheckIn 2026-03-12T23:05:46.419Z,1773356746.419 [Default:CheckIn] Stopped 2026-03-12T23:05:46.419Z,1773356746.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-03-12T23:05:46.419Z,1773356746.419 [Default:CheckIn](INFO): Running loop #3 2026-03-12T23:05:46.419Z,1773356746.419 [Default:CheckIn] Running Loop=3 2026-03-12T23:05:46.419Z,1773356746.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-03-12T23:05:46.419Z,1773356746.419 [Default:CheckIn:Read_GPS] Running Loop=1 2026-03-12T23:05:48.429Z,1773356748.429 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230547.00,A,3648.26636,N,12151.04475,W,1.497,197.93,120326,,,D*7E 2026-03-12T23:05:48.432Z,1773356748.432 [NAL9602](INFO): GPS fix at 20260312T230547: (36.804439, -121.850746) 2026-03-12T23:05:48.442Z,1773356748.442 [Default:CheckIn:Read_GPS] Stopped 2026-03-12T23:05:48.442Z,1773356748.442 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-03-12T23:05:55.739Z,1773356755.739 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20260312T221930/Courier0015.lzma 2026-03-12T23:05:56.741Z,1773356756.741 [DataOverHttps](INFO): Moved sent file to Logs/20260312T221930/Courier0015.lzma.bak 2026-03-12T23:05:56.742Z,1773356756.742 [DataOverHttps](IMPORTANT): SBD MOMSN=26726932, MTMSN=20260312T230555 2026-03-12T23:06:04.139Z,1773356764.139 [DataOverHttps](INFO): Received command: load Transport/keepstation.tl;set keepstation.NeedCommsTime 15 min;run 2026-03-12T23:06:04.221Z,1773356764.221 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2026-03-12T23:06:04.222Z,1773356764.222 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2026-03-12T23:06:04.222Z,1773356764.222 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2026-03-12T23:06:04.245Z,1773356764.245 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2026-03-12T23:06:05.699Z,1773356765.699 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into: 4 45 NaN NaN 30 15 4 0.75 200 10 10 45 2 0 0 1 2 3 NaN 0 Sinking to Timed out while trying to reach target sink depth at current depth of 2026-03-12T23:06:05.708Z,1773356765.708 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2026-03-12T23:06:05.710Z,1773356765.710 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2026-03-12T23:06:05.712Z,1773356765.712 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2026-03-12T23:06:05.715Z,1773356765.715 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2026-03-12T23:06:05.717Z,1773356765.717 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2026-03-12T23:06:05.719Z,1773356765.719 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2026-03-12T23:06:05.721Z,1773356765.721 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2026-03-12T23:06:05.724Z,1773356765.724 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2026-03-12T23:06:05.726Z,1773356765.726 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2026-03-12T23:06:05.728Z,1773356765.728 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool 2026-03-12T23:06:05.731Z,1773356765.731 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m 2026-03-12T23:06:05.733Z,1773356765.733 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min 2026-03-12T23:06:05.767Z,1773356765.767 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2026-03-12T23:06:05.770Z,1773356765.770 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2026-03-12T23:06:05.776Z,1773356765.776 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count 2026-03-12T23:06:05.779Z,1773356765.779 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count 2026-03-12T23:06:05.781Z,1773356765.781 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count 2026-03-12T23:06:05.824Z,1773356765.824 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count 2026-03-12T23:06:05.826Z,1773356765.826 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count 2026-03-12T23:06:05.833Z,1773356765.833 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool 2026-03-12T23:06:05.833Z,1773356765.833 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2026-03-12T23:06:05.833Z,1773356765.833 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2026-03-12T23:06:05.835Z,1773356765.835 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2026-03-12T23:06:06.186Z,1773356766.186 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1500 7 30 0 0 0 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2026-03-12T23:06:06.220Z,1773356766.220 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2026-03-12T23:06:06.222Z,1773356766.222 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2026-03-12T23:06:06.258Z,1773356766.258 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2026-03-12T23:06:06.268Z,1773356766.268 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2026-03-12T23:06:06.271Z,1773356766.271 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2026-03-12T23:06:06.281Z,1773356766.281 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1500.000000 s 2026-03-12T23:06:06.287Z,1773356766.287 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2026-03-12T23:06:06.290Z,1773356766.290 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2026-03-12T23:06:06.295Z,1773356766.295 [MissionManager](INFO): DefineArg keepstation:NeedComms.DoingCommsMode = 0.000000 count 2026-03-12T23:06:06.305Z,1773356766.305 [MissionManager](INFO): DefineArg keepstation:NeedComms.RestoreMode = 0.000000 count 2026-03-12T23:06:06.312Z,1773356766.312 [MissionManager](INFO): DefineOutput keepstation:NeedComms.IsActive = 0 bool 2026-03-12T23:06:06.314Z,1773356766.314 [MissionManager](INFO): DefineOutput keepstation:NeedComms.RequiredDiveMode = 0.000000 count 2026-03-12T23:06:06.348Z,1773356766.348 [keepstation:NeedComms:C.GoToSurface](DEBUG): Construct GoToSurface. 2026-03-12T23:06:06.382Z,1773356766.382 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2026-03-12T23:06:06.383Z,1773356766.383 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2026-03-12T23:06:06.400Z,1773356766.400 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2026-03-12T23:06:06.495Z,1773356766.495 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2026-03-12T23:06:06.499Z,1773356766.499 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2026-03-12T23:06:06.501Z,1773356766.501 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2026-03-12T23:06:06.503Z,1773356766.503 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2026-03-12T23:06:06.505Z,1773356766.505 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2026-03-12T23:06:06.506Z,1773356766.506 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2026-03-12T23:06:06.512Z,1773356766.512 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2026-03-12T23:06:06.519Z,1773356766.519 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2026-03-12T23:06:06.526Z,1773356766.526 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2026-03-12T23:06:06.526Z,1773356766.526 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2026-03-12T23:06:06.527Z,1773356766.527 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2026-03-12T23:06:06.580Z,1773356766.580 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2026-03-12T23:06:06.583Z,1773356766.583 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2026-03-12T23:06:06.585Z,1773356766.585 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2026-03-12T23:06:06.588Z,1773356766.588 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2026-03-12T23:06:06.588Z,1773356766.588 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2026-03-12T23:06:06.589Z,1773356766.589 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2026-03-12T23:06:06.758Z,1773356766.758 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2026-03-12T23:06:06.762Z,1773356766.762 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2026-03-12T23:06:06.764Z,1773356766.764 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2026-03-12T23:06:06.766Z,1773356766.766 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2026-03-12T23:06:06.768Z,1773356766.768 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2026-03-12T23:06:06.771Z,1773356766.771 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool 2026-03-12T23:06:06.782Z,1773356766.782 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2026-03-12T23:06:06.784Z,1773356766.784 [keepstation:H.Pitch](DEBUG): Construct. 2026-03-12T23:06:06.873Z,1773356766.873 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2026-03-12T23:06:06.876Z,1773356766.876 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct. 2026-03-12T23:06:06.879Z,1773356766.879 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct. 2026-03-12T23:06:06.892Z,1773356766.892 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy. 2026-03-12T23:06:06.895Z,1773356766.895 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct. 2026-03-12T23:06:06.899Z,1773356766.899 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct. 2026-03-12T23:06:06.904Z,1773356766.904 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct. 2026-03-12T23:06:06.909Z,1773356766.909 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation. 2026-03-12T23:06:06.927Z,1773356766.927 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2026-03-12T23:06:06.938Z,1773356766.938 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait. 2026-03-12T23:06:06.958Z,1773356766.958 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission keepstation { """ Vehicle transits to desired waypoint (or stays put if none is commanded) and stays within the specified radius. """ arguments { MissionTimeout = 4 hour """ Maximum duration of mission """ NeedCommsTime = 45 minute """ How often to surface for communications """ Latitude = NaN degree """ Latitude of waypoint to hold. If NaN, hold the latitude at start of mission. """ Longitude = NaN degree """ Longitude of waypoint to hold. If NaN, hold the longitude at start of mission. """ Depth = 30 meter """ Depth held during drift mode """ ApproachDepth = 15 meter """ Depth for initial approach to station. """ DepthDeadband = 4 meter """ How much vertical drift from the specified depth is allowed in drift mode """ Speed = 0.75 meter_per_second """ Vehicle transit speed. """ Radius = 200 meter """ Radius of circle around waypoint to hold. """ SinkOffSurface = false """ If true, use buoyancy only to decend to SinkDepth before driving to first target depth """ SinkDepth = 10 meter """ Initial depth to decend using only buoyancy before driving """ SinkDepthTimeout = 10 minute """ Max wait time for the vehicle to reach the target depth """ MaxDepth = 45 meter """ Maximum allowable depth during the mission. """ MinOffshore = 2 kilometer """ Minimum distance from the shoreline to maintain """ } output { DiveMode = 0 count """ Mission variable (don't change). The mission sets this variable to switch between dive modes. Initialized to DoingComms. """ DoingComms = 0 count """ Mission variable (don't change). The mission will run in this mode when doing surface comms. """ SurfaceSink = 1 count """ Mission variable (don't change). The mission will run in this mode when sinking from the surface using only buoyancy. """ TransitToStation = 2 count """ Mission variable (don't change). The mission will run in this mode when performing the transit to station """ StationKeep = 3 count """ Mission variable (don't change). The mission will run in this mode when keeping station """ OnStation = false """ Mission variable (don't change). Determines last mode (Transit or StationKeep) to resume after needcomms """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Pitch { run in parallel set massPosition = Control:VerticalControl.massDefault } aggregate SurfaceComms { run when ( called or ( elapsed ( Universal:time_fix ) > NeedCommsTime ) ) assign in sequence DiveMode = DoingComms call priorityHere=false refId="NeedComms" aggregate HandleNan { run in sequence break if ( not isNaN ( Latitude ) and not isNaN ( Longitude ) ) # If both lat and lon are nan, we are implicitly on station and should skip initial transit, so force OnStation to true aggregate SetOnStation { run in sequence break if ( not isNaN ( Latitude ) or not isNaN ( Longitude ) ) assign in sequence OnStation = true } # The "assigns" here mean we should only ever run these aggregates once # An initial NaN lat/lon should be set here during the initial surface NeedComms call # Use the most recent fix -- if there isn't one, the keepstation behavior will use Universal:lat/lon aggregate SetLat { run in sequence break if ( not isNaN ( Latitude ) ) assign in sequence Latitude = Universal:latitude_fix } aggregate SetLon { run in sequence break if ( not isNaN ( Longitude ) ) assign in sequence Longitude = Universal:longitude_fix } } aggregate setTransit { run in sequence break if ( SinkOffSurface or OnStation ) assign in sequence DiveMode = TransitToStation } aggregate setStation { run in sequence break if ( SinkOffSurface or not OnStation ) assign in sequence DiveMode = StationKeep } aggregate setSink { run in sequence break if ( not SinkOffSurface ) assign in sequence DiveMode = SurfaceSink } } call id="StartingMission" priorityHere=false refId="SurfaceComms" aggregate DiveCmd { run when ( called ) aggregate SurfaceSink { run while ( DiveMode == SurfaceSink ) aggregate sink { run in sequence behavior Guidance:Buoyancy id="BuoyancyHold" { run in parallel set position = NaN cc } behavior Guidance:SetSpeed { run in parallel set speed = 0 m/s } syslog info "Sinking to " + SinkDepth~meter behavior Guidance:Pitch { run in sequence timeout duration=SinkDepthTimeout { syslog important "Timed out while trying to reach target sink depth at current depth of " + Universal:depth~meter } set depth = SinkDepth } # Once we've sunk, either resume transit or station keeping depths and speeds aggregate sinkToTransit { run in sequence break if ( OnStation ) assign in sequence DiveMode = TransitToStation } aggregate sinkToStation { run in sequence break if ( not OnStation ) assign in sequence DiveMode = StationKeep } } } aggregate TransitToStation { run while ( DiveMode == TransitToStation ) aggregate dive { run in sequence behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch { run in parallel set depth = ApproachDepth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } } } aggregate StationKeep { run while ( DiveMode == StationKeep ) assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:KeepStation { run in parallel set latitude = Latitude set longitude = Longitude set radius = Radius set speed = Speed } } } aggregate TransitToStation { """ Need a separate aggregate for transit if we want to specify a different depth for the approach. """ run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude } assign in sequence DiveMode = StationKeep assign in sequence OnStation = true } call id="OnStation" priorityHere=false refId="NeedComms" aggregate KeepStation { run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Wait { """ Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. """ run in sequence set duration = MissionTimeout } } } 2026-03-12T23:06:06.959Z,1773356766.959 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2026-03-12T23:06:09.438Z,1773356769.438 [NAL9602](INFO): SBD MO Status=2, MOMSN=32656, MT Status=2, MTMSN=0 2026-03-12T23:06:09.438Z,1773356769.438 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-12T23:06:13.682Z,1773356773.682 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 15 minute 2026-03-12T23:06:13.683Z,1773356773.683 [CommandExec](IMPORTANT): got command run 2026-03-12T23:06:13.689Z,1773356773.689 [CommandExec](IMPORTANT): Running 2026-03-12T23:06:13.915Z,1773356773.915 [Default] Stopped 2026-03-12T23:06:13.915Z,1773356773.915 [Default](DEBUG): Aggregate::uninitialize Default 2026-03-12T23:06:13.919Z,1773356773.919 [Default:B.GoToSurface] Stopped 2026-03-12T23:06:13.919Z,1773356773.919 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T23:06:13.919Z,1773356773.919 [Default:CheckIn] Stopped 2026-03-12T23:06:13.920Z,1773356773.920 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-03-12T23:06:13.920Z,1773356773.920 [Default:CheckIn:Read_Iridium] Stopped 2026-03-12T23:06:13.920Z,1773356773.920 [MissionManager](IMPORTANT): Started mission keepstation 2026-03-12T23:06:13.920Z,1773356773.920 [keepstation] Running Loop=1 2026-03-12T23:06:13.920Z,1773356773.920 [keepstation](DEBUG): Aggregate::initialize keepstation 2026-03-12T23:06:13.920Z,1773356773.920 [keepstation:StandardEnvelopes] Running Loop=1 2026-03-12T23:06:13.920Z,1773356773.920 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2026-03-12T23:06:13.920Z,1773356773.920 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2026-03-12T23:06:13.921Z,1773356773.921 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-03-12T23:06:13.921Z,1773356773.921 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2026-03-12T23:06:13.921Z,1773356773.921 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-03-12T23:06:13.921Z,1773356773.921 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2026-03-12T23:06:13.921Z,1773356773.921 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2026-03-12T23:06:13.921Z,1773356773.921 [keepstation:BackseatDriver] Running Loop=1 2026-03-12T23:06:13.921Z,1773356773.921 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2026-03-12T23:06:13.921Z,1773356773.921 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2026-03-12T23:06:13.921Z,1773356773.921 [keepstation:PowerOnly] Running Loop=1 2026-03-12T23:06:13.922Z,1773356773.922 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2026-03-12T23:06:13.922Z,1773356773.922 [keepstation:PowerOnly:A] Running Loop=1 2026-03-12T23:06:13.922Z,1773356773.922 [keepstation:PowerOnly:B] Running Loop=1 2026-03-12T23:06:13.922Z,1773356773.922 [keepstation:PowerOnly:C] Running Loop=1 2026-03-12T23:06:13.922Z,1773356773.922 [keepstation:PowerOnly:D] Running Loop=1 2026-03-12T23:06:13.922Z,1773356773.922 [keepstation:PowerOnly:E.Wait] Running Loop=1 2026-03-12T23:06:13.922Z,1773356773.922 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2026-03-12T23:06:13.922Z,1773356773.922 [keepstation:H.Pitch] Running Loop=1 2026-03-12T23:06:13.922Z,1773356773.922 [keepstation:H.Pitch](DEBUG): Initialize. 2026-03-12T23:06:13.922Z,1773356773.922 [keepstation:B] Running Loop=1 2026-03-12T23:06:13.928Z,1773356773.928 [keepstation:H.Pitch] Running Loop=1 2026-03-12T23:06:13.928Z,1773356773.928 [keepstation:PowerOnly] Running Loop=1 2026-03-12T23:06:13.929Z,1773356773.929 [keepstation:PowerOnly:D] Stopped 2026-03-12T23:06:13.929Z,1773356773.929 [keepstation:PowerOnly:C] Stopped 2026-03-12T23:06:13.929Z,1773356773.929 [keepstation:PowerOnly:B] Stopped 2026-03-12T23:06:13.929Z,1773356773.929 [keepstation:PowerOnly:A] Stopped 2026-03-12T23:06:13.929Z,1773356773.929 [keepstation:BackseatDriver] Running Loop=1 2026-03-12T23:06:13.929Z,1773356773.929 [keepstation:StandardEnvelopes] Running Loop=1 2026-03-12T23:06:13.929Z,1773356773.929 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2026-03-12T23:06:13.929Z,1773356773.929 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2026-03-12T23:06:13.930Z,1773356773.930 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2026-03-12T23:06:13.930Z,1773356773.930 [keepstation:B] Stopped 2026-03-12T23:06:13.930Z,1773356773.930 [keepstation:D] Running Loop=1 2026-03-12T23:06:14.329Z,1773356774.329 [keepstation:D] Stopped 2026-03-12T23:06:14.329Z,1773356774.329 [keepstation:E] Running Loop=1 2026-03-12T23:06:14.713Z,1773356774.713 [keepstation:E] Stopped 2026-03-12T23:06:14.713Z,1773356774.713 [keepstation:StartingMission] Running Loop=1 2026-03-12T23:06:14.713Z,1773356774.713 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2026-03-12T23:06:15.112Z,1773356775.112 [keepstation:SurfaceComms] Running Loop=1 2026-03-12T23:06:15.112Z,1773356775.112 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2026-03-12T23:06:15.112Z,1773356775.112 [keepstation:SurfaceComms:A] Running Loop=1 2026-03-12T23:06:15.112Z,1773356775.112 [keepstation:SurfaceComms:A] Stopped 2026-03-12T23:06:15.112Z,1773356775.112 [keepstation:SurfaceComms:B] Running Loop=1 2026-03-12T23:06:15.112Z,1773356775.112 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2026-03-12T23:06:15.467Z,1773356775.467 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20260312T230054/Courier0000.lzma 2026-03-12T23:06:15.524Z,1773356775.524 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2026-03-12T23:06:15.524Z,1773356775.524 [keepstation:NeedComms] Running Loop=1 2026-03-12T23:06:15.524Z,1773356775.524 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2026-03-12T23:06:15.524Z,1773356775.524 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2026-03-12T23:06:15.524Z,1773356775.524 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T23:06:15.524Z,1773356775.524 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-03-12T23:06:15.525Z,1773356775.525 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-03-12T23:06:15.525Z,1773356775.525 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-03-12T23:06:15.526Z,1773356775.526 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-03-12T23:06:15.526Z,1773356775.526 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T23:06:15.526Z,1773356775.526 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T23:06:15.526Z,1773356775.526 [keepstation:NeedComms:A] Running Loop=1 2026-03-12T23:06:15.528Z,1773356775.528 [keepstation:NeedComms:A](INFO): last time_fix was: 1773356747.000000 second since 1970/01/01T00:00:00Z 2026-03-12T23:06:15.528Z,1773356775.528 [keepstation:NeedComms:A] Stopped 2026-03-12T23:06:15.528Z,1773356775.528 [keepstation:NeedComms:setActive] Running Loop=1 2026-03-12T23:06:15.528Z,1773356775.528 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2026-03-12T23:06:15.528Z,1773356775.528 [keepstation:NeedComms:setActive:A] Running Loop=1 2026-03-12T23:06:15.918Z,1773356775.918 [keepstation:NeedComms:setActive:A] Stopped 2026-03-12T23:06:15.919Z,1773356775.919 [keepstation:NeedComms:setActive:B] Running Loop=1 2026-03-12T23:06:16.317Z,1773356776.317 [keepstation:NeedComms:setActive:B] Stopped 2026-03-12T23:06:16.317Z,1773356776.317 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2026-03-12T23:06:16.317Z,1773356776.317 [keepstation:NeedComms:setActive] Stopped 2026-03-12T23:06:16.317Z,1773356776.317 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2026-03-12T23:06:16.470Z,1773356776.470 [DataOverHttps](INFO): Moved sent file to Logs/20260312T230054/Courier0000.lzma.bak 2026-03-12T23:06:16.471Z,1773356776.471 [DataOverHttps](INFO): SBD MOMSN=26726939 2026-03-12T23:06:16.722Z,1773356776.722 [keepstation:NeedComms:D] Running Loop=1 2026-03-12T23:06:17.136Z,1773356777.136 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-03-12T23:06:26.405Z,1773356786.405 [NAL9602](INFO): SBD MO Status=1, MOMSN=32656, MT Status=0, MTMSN=0 2026-03-12T23:06:26.405Z,1773356786.405 [NAL9602](INFO): No messages in MT queue 2026-03-12T23:06:27.622Z,1773356787.622 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230626.00,A,3648.26420,N,12151.04577,W,0.855,263.66,120326,,,D*7D 2026-03-12T23:06:27.625Z,1773356787.625 [NAL9602](INFO): GPS fix at 20260312T230626: (36.804403, -121.850763) 2026-03-12T23:06:27.657Z,1773356787.657 [keepstation:NeedComms:D] Stopped 2026-03-12T23:06:27.658Z,1773356787.658 [keepstation:NeedComms:E] Running Loop=1 2026-03-12T23:06:28.066Z,1773356788.066 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-03-12T23:06:34.651Z,1773356794.651 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20260312T230054/Courier0006.lzma 2026-03-12T23:06:35.653Z,1773356795.653 [DataOverHttps](INFO): Moved sent file to Logs/20260312T230054/Courier0006.lzma.bak 2026-03-12T23:06:35.653Z,1773356795.653 [DataOverHttps](INFO): SBD MOMSN=26726946 2026-03-12T23:06:54.359Z,1773356814.359 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20260312T230054/Courier0009.lzma 2026-03-12T23:06:55.361Z,1773356815.361 [DataOverHttps](INFO): Moved sent file to Logs/20260312T230054/Courier0009.lzma.bak 2026-03-12T23:06:55.361Z,1773356815.361 [DataOverHttps](INFO): SBD MOMSN=26726951 2026-03-12T23:06:59.632Z,1773356819.632 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T23:07:11.815Z,1773356831.815 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20260312T221930/Express0016.lzma 2026-03-12T23:07:12.817Z,1773356832.817 [DataOverHttps](INFO): Moved sent file to Logs/20260312T221930/Express0016.lzma.bak 2026-03-12T23:07:12.817Z,1773356832.817 [DataOverHttps](INFO): SBD MOMSN=26726960 2026-03-12T23:07:28.780Z,1773356848.780 [DataOverHttps](INFO): Sending 492 bytes from file Logs/20260312T230054/Express0001.lzma 2026-03-12T23:07:29.781Z,1773356849.781 [DataOverHttps](INFO): Moved sent file to Logs/20260312T230054/Express0001.lzma.bak 2026-03-12T23:07:29.782Z,1773356849.782 [DataOverHttps](INFO): SBD MOMSN=26726965 2026-03-12T23:07:45.623Z,1773356865.623 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20260312T230054/Express0004.lzma 2026-03-12T23:07:46.626Z,1773356866.626 [DataOverHttps](INFO): Moved sent file to Logs/20260312T230054/Express0004.lzma.bak 2026-03-12T23:07:46.626Z,1773356866.626 [DataOverHttps](INFO): SBD MOMSN=26726969 2026-03-12T23:08:02.567Z,1773356882.567 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20260312T230054/Express0007.lzma 2026-03-12T23:08:03.569Z,1773356883.569 [DataOverHttps](INFO): Moved sent file to Logs/20260312T230054/Express0007.lzma.bak 2026-03-12T23:08:03.569Z,1773356883.569 [DataOverHttps](INFO): SBD MOMSN=26726974 2026-03-12T23:08:19.611Z,1773356899.611 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20260312T230054/Express0010.lzma 2026-03-12T23:08:20.613Z,1773356900.613 [DataOverHttps](INFO): Moved sent file to Logs/20260312T230054/Express0010.lzma.bak 2026-03-12T23:08:20.613Z,1773356900.613 [DataOverHttps](INFO): SBD MOMSN=26726978 2026-03-12T23:08:22.066Z,1773356902.066 [keepstation:NeedComms:E] Stopped 2026-03-12T23:08:22.066Z,1773356902.066 [keepstation:NeedComms:F] Running Loop=1 2026-03-12T23:08:22.465Z,1773356902.465 [keepstation:NeedComms:F](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-03-12T23:08:24.070Z,1773356904.070 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230823.00,A,3648.25556,N,12151.04999,W,0.836,228.87,120326,,,D*7C 2026-03-12T23:08:24.072Z,1773356904.072 [NAL9602](INFO): GPS fix at 20260312T230823: (36.804259, -121.850833) 2026-03-12T23:08:24.098Z,1773356904.098 [keepstation:NeedComms:F] Stopped 2026-03-12T23:08:24.098Z,1773356904.098 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2026-03-12T23:08:24.098Z,1773356904.098 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2026-03-12T23:08:24.098Z,1773356904.098 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2026-03-12T23:08:24.517Z,1773356904.517 [keepstation:NeedComms:setRestoreMode:A] Stopped 2026-03-12T23:08:24.517Z,1773356904.517 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2026-03-12T23:08:24.517Z,1773356904.517 [keepstation:NeedComms:setRestoreMode] Stopped 2026-03-12T23:08:24.517Z,1773356904.517 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2026-03-12T23:08:24.518Z,1773356904.518 [keepstation:NeedComms:setInactive] Running Loop=1 2026-03-12T23:08:24.518Z,1773356904.518 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2026-03-12T23:08:24.518Z,1773356904.518 [keepstation:NeedComms:setInactive:A] Running Loop=1 2026-03-12T23:08:24.910Z,1773356904.910 [keepstation:NeedComms:setInactive:A] Stopped 2026-03-12T23:08:24.911Z,1773356904.911 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2026-03-12T23:08:24.911Z,1773356904.911 [keepstation:NeedComms:setInactive] Stopped 2026-03-12T23:08:24.911Z,1773356904.911 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2026-03-12T23:08:24.916Z,1773356904.916 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2026-03-12T23:08:24.916Z,1773356904.916 [keepstation:NeedComms] Stopped 2026-03-12T23:08:24.916Z,1773356904.916 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2026-03-12T23:08:24.916Z,1773356904.916 [keepstation:NeedComms:C.GoToSurface] Stopped 2026-03-12T23:08:24.916Z,1773356904.916 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T23:08:25.300Z,1773356905.300 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2026-03-12T23:08:25.300Z,1773356905.300 [keepstation:SurfaceComms:B] Stopped 2026-03-12T23:08:25.300Z,1773356905.300 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2026-03-12T23:08:25.300Z,1773356905.300 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2026-03-12T23:08:25.300Z,1773356905.300 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2026-03-12T23:08:25.300Z,1773356905.300 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2026-03-12T23:08:25.301Z,1773356905.301 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T23:08:25.301Z,1773356905.301 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2026-03-12T23:08:25.744Z,1773356905.744 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2026-03-12T23:08:25.744Z,1773356905.744 [keepstation:SurfaceComms:HandleNan:SetOnStation](INFO): Completed keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T23:08:25.744Z,1773356905.744 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2026-03-12T23:08:25.744Z,1773356905.744 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T23:08:25.744Z,1773356905.744 [keepstation:SurfaceComms:HandleNan:SetLat] Running Loop=1 2026-03-12T23:08:25.744Z,1773356905.744 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLat 2026-03-12T23:08:25.744Z,1773356905.744 [keepstation:SurfaceComms:HandleNan:SetLat:A] Running Loop=1 2026-03-12T23:08:26.120Z,1773356906.120 [keepstation:SurfaceComms:HandleNan:SetLat:A] Stopped 2026-03-12T23:08:26.120Z,1773356906.120 [keepstation:SurfaceComms:HandleNan:SetLat](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLat 2026-03-12T23:08:26.120Z,1773356906.120 [keepstation:SurfaceComms:HandleNan:SetLat] Stopped 2026-03-12T23:08:26.121Z,1773356906.121 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLat 2026-03-12T23:08:26.121Z,1773356906.121 [keepstation:SurfaceComms:HandleNan:SetLon] Running Loop=1 2026-03-12T23:08:26.121Z,1773356906.121 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLon 2026-03-12T23:08:26.121Z,1773356906.121 [keepstation:SurfaceComms:HandleNan:SetLon:A] Running Loop=1 2026-03-12T23:08:26.531Z,1773356906.531 [keepstation:SurfaceComms:HandleNan:SetLon:A] Stopped 2026-03-12T23:08:26.532Z,1773356906.532 [keepstation:SurfaceComms:HandleNan:SetLon](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLon 2026-03-12T23:08:26.532Z,1773356906.532 [keepstation:SurfaceComms:HandleNan:SetLon] Stopped 2026-03-12T23:08:26.532Z,1773356906.532 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLon 2026-03-12T23:08:26.532Z,1773356906.532 [keepstation:SurfaceComms:HandleNan](INFO): Completed keepstation:SurfaceComms:HandleNan 2026-03-12T23:08:26.532Z,1773356906.532 [keepstation:SurfaceComms:HandleNan] Stopped 2026-03-12T23:08:26.532Z,1773356906.532 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2026-03-12T23:08:26.532Z,1773356906.532 [keepstation:SurfaceComms:setTransit] Running Loop=1 2026-03-12T23:08:26.532Z,1773356906.532 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2026-03-12T23:08:26.532Z,1773356906.532 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2026-03-12T23:08:26.920Z,1773356906.920 [keepstation:SurfaceComms:setTransit] Stopped 2026-03-12T23:08:26.920Z,1773356906.920 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2026-03-12T23:08:26.920Z,1773356906.920 [keepstation:SurfaceComms:setTransit:A] Stopped 2026-03-12T23:08:26.920Z,1773356906.920 [keepstation:SurfaceComms:setStation] Running Loop=1 2026-03-12T23:08:26.920Z,1773356906.920 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2026-03-12T23:08:26.920Z,1773356906.920 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2026-03-12T23:08:27.317Z,1773356907.317 [keepstation:SurfaceComms:setStation:A] Stopped 2026-03-12T23:08:27.317Z,1773356907.317 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation 2026-03-12T23:08:27.317Z,1773356907.317 [keepstation:SurfaceComms:setStation] Stopped 2026-03-12T23:08:27.317Z,1773356907.317 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2026-03-12T23:08:27.317Z,1773356907.317 [keepstation:SurfaceComms:setSink] Running Loop=1 2026-03-12T23:08:27.317Z,1773356907.317 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2026-03-12T23:08:27.318Z,1773356907.318 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2026-03-12T23:08:27.728Z,1773356907.728 [keepstation:SurfaceComms:setSink] Stopped 2026-03-12T23:08:27.728Z,1773356907.728 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2026-03-12T23:08:27.729Z,1773356907.729 [keepstation:SurfaceComms:setSink:A] Stopped 2026-03-12T23:08:27.729Z,1773356907.729 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2026-03-12T23:08:27.729Z,1773356907.729 [keepstation:SurfaceComms] Stopped 2026-03-12T23:08:27.729Z,1773356907.729 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2026-03-12T23:08:28.116Z,1773356908.116 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2026-03-12T23:08:28.116Z,1773356908.116 [keepstation:StartingMission] Stopped 2026-03-12T23:08:28.116Z,1773356908.116 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2026-03-12T23:08:28.116Z,1773356908.116 [keepstation:TransitToStation] Running Loop=1 2026-03-12T23:08:28.116Z,1773356908.116 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2026-03-12T23:08:28.116Z,1773356908.116 [keepstation:TransitToStation:Dive] Running Loop=1 2026-03-12T23:08:28.116Z,1773356908.116 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2026-03-12T23:08:28.116Z,1773356908.116 [keepstation:TransitToStation:Dive:A] Running Loop=1 2026-03-12T23:08:28.117Z,1773356908.117 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2026-03-12T23:08:28.117Z,1773356908.117 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2026-03-12T23:08:28.117Z,1773356908.117 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2026-03-12T23:08:28.118Z,1773356908.118 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.804259,-121.850833 2026-03-12T23:08:28.529Z,1773356908.529 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.804259,-121.850833 2026-03-12T23:08:28.530Z,1773356908.530 [keepstation:TransitToStation:Dive] Running Loop=1 2026-03-12T23:08:28.530Z,1773356908.530 [keepstation:DiveCmd] Running Loop=1 2026-03-12T23:08:28.530Z,1773356908.530 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2026-03-12T23:08:28.530Z,1773356908.530 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2026-03-12T23:08:28.530Z,1773356908.530 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2026-03-12T23:08:28.530Z,1773356908.530 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2026-03-12T23:08:28.531Z,1773356908.531 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2026-03-12T23:08:28.531Z,1773356908.531 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-03-12T23:08:28.531Z,1773356908.531 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-03-12T23:08:28.531Z,1773356908.531 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-03-12T23:08:28.531Z,1773356908.531 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-03-12T23:08:28.531Z,1773356908.531 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2026-03-12T23:08:28.531Z,1773356908.531 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2026-03-12T23:08:28.531Z,1773356908.531 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2026-03-12T23:08:28.532Z,1773356908.532 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2026-03-12T23:08:28.532Z,1773356908.532 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2026-03-12T23:08:28.532Z,1773356908.532 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2026-03-12T23:08:28.532Z,1773356908.532 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-03-12T23:08:28.532Z,1773356908.532 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2026-03-12T23:08:28.532Z,1773356908.532 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2026-03-12T23:08:28.532Z,1773356908.532 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2026-03-12T23:08:28.532Z,1773356908.532 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2026-03-12T23:08:28.532Z,1773356908.532 [keepstation:DiveCmd:StationKeep] Running Loop=1 2026-03-12T23:08:28.533Z,1773356908.533 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2026-03-12T23:08:28.533Z,1773356908.533 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2026-03-12T23:08:28.533Z,1773356908.533 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2026-03-12T23:08:28.533Z,1773356908.533 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2026-03-12T23:08:28.533Z,1773356908.533 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2026-03-12T23:08:28.533Z,1773356908.533 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2026-03-12T23:08:28.534Z,1773356908.534 [keepstation:DiveCmd:StationKeep] Running Loop=1 2026-03-12T23:08:28.534Z,1773356908.534 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2026-03-12T23:08:28.534Z,1773356908.534 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2026-03-12T23:08:28.535Z,1773356908.535 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2026-03-12T23:08:28.535Z,1773356908.535 [keepstation:DiveCmd:TransitToStation] Stopped 2026-03-12T23:08:28.535Z,1773356908.535 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2026-03-12T23:08:28.535Z,1773356908.535 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2026-03-12T23:08:28.535Z,1773356908.535 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:SurfaceSink] Stopped 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2026-03-12T23:08:28.536Z,1773356908.536 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-03-12T23:08:28.537Z,1773356908.537 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2026-03-12T23:08:36.589Z,1773356916.589 [NAL9602](INFO): SBD MO Status=1, MOMSN=32657, MT Status=0, MTMSN=0 2026-03-12T23:08:36.589Z,1773356916.589 [NAL9602](INFO): No messages in MT queue 2026-03-12T23:09:07.291Z,1773356947.291 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T23:11:55.461Z,1773357115.461 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:12:26.617Z,1773357146.617 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:12:36.667Z,1773357156.667 [Radio_Surface](INFO): Powering down 2026-03-12T23:12:57.744Z,1773357177.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:12:58.748Z,1773357178.748 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-03-12T23:14:03.012Z,1773357243.012 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.783234 2026-03-12T23:23:24.209Z,1773357804.209 [keepstation:SurfaceComms] Running Loop=1 2026-03-12T23:23:24.209Z,1773357804.209 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2026-03-12T23:23:24.209Z,1773357804.209 [keepstation:SurfaceComms:A] Running Loop=1 2026-03-12T23:23:24.210Z,1773357804.210 [keepstation:SurfaceComms:A] Stopped 2026-03-12T23:23:24.210Z,1773357804.210 [keepstation:SurfaceComms:B] Running Loop=1 2026-03-12T23:23:24.210Z,1773357804.210 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2026-03-12T23:23:24.212Z,1773357804.212 [keepstation:NeedComms] Running Loop=1 2026-03-12T23:23:24.212Z,1773357804.212 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2026-03-12T23:23:24.212Z,1773357804.212 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2026-03-12T23:23:24.212Z,1773357804.212 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T23:23:24.212Z,1773357804.212 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-03-12T23:23:24.213Z,1773357804.213 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-03-12T23:23:24.213Z,1773357804.213 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-03-12T23:23:24.213Z,1773357804.213 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-03-12T23:23:24.214Z,1773357804.214 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T23:23:24.214Z,1773357804.214 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T23:23:24.214Z,1773357804.214 [keepstation:NeedComms:A] Running Loop=1 2026-03-12T23:23:24.216Z,1773357804.216 [keepstation:NeedComms:A](INFO): last time_fix was: 1773356903.000000 second since 1970/01/01T00:00:00Z 2026-03-12T23:23:24.216Z,1773357804.216 [keepstation:NeedComms:A] Stopped 2026-03-12T23:23:24.216Z,1773357804.216 [keepstation:NeedComms:setActive] Running Loop=1 2026-03-12T23:23:24.216Z,1773357804.216 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2026-03-12T23:23:24.217Z,1773357804.217 [keepstation:NeedComms:setActive:A] Running Loop=1 2026-03-12T23:23:24.603Z,1773357804.603 [keepstation:TransitToStation:Dive] Stopped 2026-03-12T23:23:24.603Z,1773357804.603 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2026-03-12T23:23:24.603Z,1773357804.603 [keepstation:TransitToStation:Dive:A] Stopped 2026-03-12T23:23:24.603Z,1773357804.603 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2026-03-12T23:23:24.604Z,1773357804.604 [keepstation:DiveCmd:StationKeep] Stopped 2026-03-12T23:23:24.604Z,1773357804.604 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2026-03-12T23:23:24.604Z,1773357804.604 [keepstation:DiveCmd:StationKeep:A] Stopped 2026-03-12T23:23:24.604Z,1773357804.604 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2026-03-12T23:23:24.604Z,1773357804.604 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2026-03-12T23:23:24.604Z,1773357804.604 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2026-03-12T23:23:24.623Z,1773357804.623 [keepstation:NeedComms:setActive:A] Stopped 2026-03-12T23:23:24.623Z,1773357804.623 [keepstation:NeedComms:setActive:B] Running Loop=1 2026-03-12T23:23:25.013Z,1773357805.013 [keepstation:NeedComms:setActive:B] Stopped 2026-03-12T23:23:25.013Z,1773357805.013 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2026-03-12T23:23:25.013Z,1773357805.013 [keepstation:NeedComms:setActive] Stopped 2026-03-12T23:23:25.013Z,1773357805.013 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2026-03-12T23:24:38.952Z,1773357878.952 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.804259,-121.850833 2026-03-12T23:24:38.952Z,1773357878.952 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2026-03-12T23:24:38.952Z,1773357878.952 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2026-03-12T23:24:38.952Z,1773357878.952 [keepstation:TransitToStation:C] Running Loop=1 2026-03-12T23:24:39.365Z,1773357879.365 [keepstation:TransitToStation:C] Stopped 2026-03-12T23:24:39.365Z,1773357879.365 [keepstation:TransitToStation:D] Running Loop=1 2026-03-12T23:24:39.365Z,1773357879.365 [keepstation:DiveCmd:StationKeep] Running Loop=1 2026-03-12T23:24:39.365Z,1773357879.365 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2026-03-12T23:24:39.365Z,1773357879.365 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2026-03-12T23:24:39.365Z,1773357879.365 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2026-03-12T23:24:39.365Z,1773357879.365 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2026-03-12T23:24:39.366Z,1773357879.366 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2026-03-12T23:24:39.366Z,1773357879.366 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2026-03-12T23:24:39.775Z,1773357879.775 [keepstation:TransitToStation:D] Stopped 2026-03-12T23:24:39.775Z,1773357879.775 [keepstation:TransitToStation:Dive] Running Loop=1 2026-03-12T23:24:39.775Z,1773357879.775 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2026-03-12T23:24:39.775Z,1773357879.775 [keepstation:TransitToStation:Dive:A] Running Loop=1 2026-03-12T23:24:39.776Z,1773357879.776 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2026-03-12T23:24:39.776Z,1773357879.776 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2026-03-12T23:24:39.776Z,1773357879.776 [keepstation:TransitToStation] Stopped 2026-03-12T23:24:39.776Z,1773357879.776 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2026-03-12T23:24:39.776Z,1773357879.776 [keepstation:TransitToStation:Dive] Stopped 2026-03-12T23:24:39.776Z,1773357879.776 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2026-03-12T23:24:39.776Z,1773357879.776 [keepstation:TransitToStation:Dive:A] Stopped 2026-03-12T23:24:39.776Z,1773357879.776 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2026-03-12T23:24:39.776Z,1773357879.776 [keepstation:OnStation] Running Loop=1 2026-03-12T23:24:39.776Z,1773357879.776 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2026-03-12T23:25:16.071Z,1773357916.071 [keepstation:NeedComms:D] Running Loop=1 2026-03-12T23:25:16.627Z,1773357916.627 [Radio_Surface](INFO): Powering up 2026-03-12T23:25:22.829Z,1773357922.829 [DataOverHttps](INFO): Radio surface powered ON. 2026-03-12T23:25:35.429Z,1773357935.429 [NAL9602](INFO): SBD MO Status=1, MOMSN=32658, MT Status=0, MTMSN=0 2026-03-12T23:25:35.429Z,1773357935.429 [NAL9602](INFO): No messages in MT queue 2026-03-12T23:25:36.650Z,1773357936.650 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232535.00,A,3648.28158,N,12151.10853,W,0.447,4.08,120326,,,A*75 2026-03-12T23:25:36.652Z,1773357936.652 [NAL9602](INFO): GPS fix at 20260312T232535: (36.804693, -121.851809) 2026-03-12T23:25:36.667Z,1773357936.667 [keepstation:NeedComms:D] Stopped 2026-03-12T23:25:36.667Z,1773357936.667 [keepstation:NeedComms:E] Running Loop=1 2026-03-12T23:25:52.956Z,1773357952.956 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:25:56.105Z,1773357956.105 [NAL9602](INFO): SBD MO Status=1, MOMSN=32659, MT Status=0, MTMSN=0 2026-03-12T23:25:56.156Z,1773357956.156 [NAL9602](INFO): Sent 74 bytes from file Logs/20260312T230054/Courier0012.lzma 2026-03-12T23:25:56.156Z,1773357956.156 [NAL9602](INFO): Packets left to send: 0 2026-03-12T23:26:04.753Z,1773357964.753 [NAL9602](INFO): SBD MO Status=1, MOMSN=32660, MT Status=0, MTMSN=0 2026-03-12T23:26:04.812Z,1773357964.812 [NAL9602](INFO): Sent 321 bytes from file Logs/20260312T230054/Express0013.lzma 2026-03-12T23:26:04.812Z,1773357964.812 [NAL9602](INFO): Packets left to send: 0 2026-03-12T23:26:16.816Z,1773357976.816 [NAL9602](INFO): SBD MO Status=0, MOMSN=32661, MT Status=0, MTMSN=0 2026-03-12T23:26:16.936Z,1773357976.936 [keepstation:NeedComms:E] Stopped 2026-03-12T23:26:16.936Z,1773357976.936 [keepstation:NeedComms:F] Running Loop=1 2026-03-12T23:26:19.238Z,1773357979.238 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232618.00,A,3648.28134,N,12151.11453,W,0.544,281.34,120326,,,A*7C 2026-03-12T23:26:19.241Z,1773357979.241 [NAL9602](INFO): GPS fix at 20260312T232618: (36.804689, -121.851909) 2026-03-12T23:26:19.256Z,1773357979.256 [keepstation:NeedComms:F] Stopped 2026-03-12T23:26:19.257Z,1773357979.257 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2026-03-12T23:26:19.257Z,1773357979.257 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2026-03-12T23:26:19.257Z,1773357979.257 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2026-03-12T23:26:19.672Z,1773357979.672 [keepstation:NeedComms:setRestoreMode:A] Stopped 2026-03-12T23:26:19.672Z,1773357979.672 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2026-03-12T23:26:19.672Z,1773357979.672 [keepstation:NeedComms:setRestoreMode] Stopped 2026-03-12T23:26:19.672Z,1773357979.672 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2026-03-12T23:26:19.672Z,1773357979.672 [keepstation:NeedComms:setInactive] Running Loop=1 2026-03-12T23:26:19.672Z,1773357979.672 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2026-03-12T23:26:19.673Z,1773357979.673 [keepstation:NeedComms:setInactive:A] Running Loop=1 2026-03-12T23:26:20.065Z,1773357980.065 [keepstation:NeedComms:setInactive:A] Stopped 2026-03-12T23:26:20.065Z,1773357980.065 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2026-03-12T23:26:20.065Z,1773357980.065 [keepstation:NeedComms:setInactive] Stopped 2026-03-12T23:26:20.065Z,1773357980.065 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2026-03-12T23:26:20.066Z,1773357980.066 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2026-03-12T23:26:20.066Z,1773357980.066 [keepstation:NeedComms] Stopped 2026-03-12T23:26:20.066Z,1773357980.066 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2026-03-12T23:26:20.066Z,1773357980.066 [keepstation:NeedComms:C.GoToSurface] Stopped 2026-03-12T23:26:20.066Z,1773357980.066 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T23:26:20.460Z,1773357980.460 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2026-03-12T23:26:20.460Z,1773357980.460 [keepstation:OnStation] Stopped 2026-03-12T23:26:20.460Z,1773357980.460 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2026-03-12T23:26:20.460Z,1773357980.460 [keepstation:KeepStation] Running Loop=1 2026-03-12T23:26:20.460Z,1773357980.460 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2026-03-12T23:26:20.460Z,1773357980.460 [keepstation:KeepStation:Dive] Running Loop=1 2026-03-12T23:26:20.461Z,1773357980.461 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2026-03-12T23:26:20.461Z,1773357980.461 [keepstation:KeepStation:Dive:A] Running Loop=1 2026-03-12T23:26:20.461Z,1773357980.461 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2026-03-12T23:26:20.461Z,1773357980.461 [keepstation:KeepStation:B.Wait] Running Loop=1 2026-03-12T23:26:20.461Z,1773357980.461 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component. 2026-03-12T23:26:20.463Z,1773357980.463 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2026-03-12T23:26:20.463Z,1773357980.463 [keepstation:SurfaceComms:B] Stopped 2026-03-12T23:26:20.463Z,1773357980.463 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2026-03-12T23:26:20.463Z,1773357980.463 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2026-03-12T23:26:20.463Z,1773357980.463 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2026-03-12T23:26:20.463Z,1773357980.463 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2026-03-12T23:26:20.464Z,1773357980.464 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T23:26:20.464Z,1773357980.464 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2026-03-12T23:26:20.869Z,1773357980.869 [keepstation:KeepStation:Dive] Running Loop=1 2026-03-12T23:26:20.871Z,1773357980.871 [keepstation:SurfaceComms:HandleNan] Stopped 2026-03-12T23:26:20.871Z,1773357980.871 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2026-03-12T23:26:20.871Z,1773357980.871 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2026-03-12T23:26:20.872Z,1773357980.872 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T23:26:20.872Z,1773357980.872 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2026-03-12T23:26:20.872Z,1773357980.872 [keepstation:SurfaceComms:setTransit] Running Loop=1 2026-03-12T23:26:20.872Z,1773357980.872 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2026-03-12T23:26:20.872Z,1773357980.872 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2026-03-12T23:26:21.272Z,1773357981.272 [keepstation:SurfaceComms:setTransit] Stopped 2026-03-12T23:26:21.272Z,1773357981.272 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2026-03-12T23:26:21.272Z,1773357981.272 [keepstation:SurfaceComms:setTransit:A] Stopped 2026-03-12T23:26:21.273Z,1773357981.273 [keepstation:SurfaceComms:setStation] Running Loop=1 2026-03-12T23:26:21.273Z,1773357981.273 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2026-03-12T23:26:21.273Z,1773357981.273 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2026-03-12T23:26:21.698Z,1773357981.698 [keepstation:SurfaceComms:setStation:A] Stopped 2026-03-12T23:26:21.698Z,1773357981.698 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation 2026-03-12T23:26:21.698Z,1773357981.698 [keepstation:SurfaceComms:setStation] Stopped 2026-03-12T23:26:21.698Z,1773357981.698 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2026-03-12T23:26:21.698Z,1773357981.698 [keepstation:SurfaceComms:setSink] Running Loop=1 2026-03-12T23:26:21.698Z,1773357981.698 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2026-03-12T23:26:21.698Z,1773357981.698 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2026-03-12T23:26:22.078Z,1773357982.078 [keepstation:SurfaceComms:setSink] Stopped 2026-03-12T23:26:22.078Z,1773357982.078 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2026-03-12T23:26:22.078Z,1773357982.078 [keepstation:SurfaceComms:setSink:A] Stopped 2026-03-12T23:26:22.078Z,1773357982.078 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2026-03-12T23:26:22.078Z,1773357982.078 [keepstation:SurfaceComms] Stopped 2026-03-12T23:26:22.078Z,1773357982.078 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2026-03-12T23:26:24.080Z,1773357984.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:26:51.618Z,1773358011.618 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T23:26:55.204Z,1773358015.204 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:27:04.132Z,1773358024.132 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-03-12T23:27:26.328Z,1773358046.328 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:27:57.452Z,1773358077.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:28:28.584Z,1773358108.584 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:28:59.708Z,1773358139.708 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:29:11.168Z,1773358151.168 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003085 2026-03-12T23:30:47.084Z,1773358247.084 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-03-12T23:33:04.940Z,1773358384.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:33:36.072Z,1773358416.072 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:33:55.879Z,1773358435.879 [Radio_Surface](INFO): Powering down 2026-03-12T23:34:07.196Z,1773358447.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:34:08.200Z,1773358448.200 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-03-12T23:35:12.476Z,1773358512.476 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747345 2026-03-12T23:41:19.388Z,1773358879.388 [keepstation:SurfaceComms] Running Loop=1 2026-03-12T23:41:19.388Z,1773358879.388 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2026-03-12T23:41:19.388Z,1773358879.388 [keepstation:SurfaceComms:A] Running Loop=1 2026-03-12T23:41:19.389Z,1773358879.389 [keepstation:SurfaceComms:A] Stopped 2026-03-12T23:41:19.389Z,1773358879.389 [keepstation:SurfaceComms:B] Running Loop=1 2026-03-12T23:41:19.389Z,1773358879.389 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2026-03-12T23:41:19.391Z,1773358879.391 [keepstation:NeedComms] Running Loop=1 2026-03-12T23:41:19.391Z,1773358879.391 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2026-03-12T23:41:19.391Z,1773358879.391 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2026-03-12T23:41:19.391Z,1773358879.391 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-12T23:41:19.391Z,1773358879.391 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-03-12T23:41:19.392Z,1773358879.392 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-03-12T23:41:19.392Z,1773358879.392 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-03-12T23:41:19.393Z,1773358879.393 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-03-12T23:41:19.393Z,1773358879.393 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-12T23:41:19.393Z,1773358879.393 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-12T23:41:19.393Z,1773358879.393 [keepstation:NeedComms:A] Running Loop=1 2026-03-12T23:41:19.395Z,1773358879.395 [keepstation:NeedComms:A](INFO): last time_fix was: 1773357978.000000 second since 1970/01/01T00:00:00Z 2026-03-12T23:41:19.395Z,1773358879.395 [keepstation:NeedComms:A] Stopped 2026-03-12T23:41:19.395Z,1773358879.395 [keepstation:NeedComms:setActive] Running Loop=1 2026-03-12T23:41:19.395Z,1773358879.395 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2026-03-12T23:41:19.396Z,1773358879.396 [keepstation:NeedComms:setActive:A] Running Loop=1 2026-03-12T23:41:19.766Z,1773358879.766 [keepstation:KeepStation:Dive] Stopped 2026-03-12T23:41:19.767Z,1773358879.767 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2026-03-12T23:41:19.767Z,1773358879.767 [keepstation:KeepStation:Dive:A] Stopped 2026-03-12T23:41:19.767Z,1773358879.767 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2026-03-12T23:41:19.767Z,1773358879.767 [keepstation:DiveCmd:StationKeep] Stopped 2026-03-12T23:41:19.768Z,1773358879.768 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2026-03-12T23:41:19.768Z,1773358879.768 [keepstation:DiveCmd:StationKeep:A] Stopped 2026-03-12T23:41:19.768Z,1773358879.768 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2026-03-12T23:41:19.768Z,1773358879.768 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2026-03-12T23:41:19.768Z,1773358879.768 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2026-03-12T23:41:19.771Z,1773358879.771 [keepstation:NeedComms:setActive:A] Stopped 2026-03-12T23:41:19.771Z,1773358879.771 [keepstation:NeedComms:setActive:B] Running Loop=1 2026-03-12T23:41:20.181Z,1773358880.181 [keepstation:NeedComms:setActive:B] Stopped 2026-03-12T23:41:20.181Z,1773358880.181 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2026-03-12T23:41:20.181Z,1773358880.181 [keepstation:NeedComms:setActive] Stopped 2026-03-12T23:41:20.181Z,1773358880.181 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2026-03-12T23:42:50.444Z,1773358970.444 [keepstation:NeedComms:D] Running Loop=1 2026-03-12T23:42:50.915Z,1773358970.915 [Radio_Surface](INFO): Powering up 2026-03-12T23:42:56.412Z,1773358976.412 [DataOverHttps](INFO): Radio surface powered ON. 2026-03-12T23:43:05.787Z,1773358985.787 [BPC1](ERROR): Battery stick #17 (s/n: 0084) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2026-03-12T23:43:05.788Z,1773358985.788 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.788Z,1773358985.788 [BPC1](FAULT): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2026-03-12T23:43:05.789Z,1773358985.789 [BPC1](FAULT): Battery stick #30 (s/n: 015F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.790Z,1773358985.790 [BPC1](ERROR): Battery stick #37 (s/n: 0181) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.791Z,1773358985.791 [BPC1](ERROR): Battery stick #38 (s/n: 01BC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.808Z,1773358985.808 [BPC1](ERROR): Battery stick #39 (s/n: 0189) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.808Z,1773358985.808 [BPC1](ERROR): Battery stick #40 (s/n: 01A4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.809Z,1773358985.809 [BPC1](ERROR): Battery stick #44 (s/n: 01BB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.810Z,1773358985.810 [BPC1](ERROR): Battery stick #50 (s/n: 019C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.810Z,1773358985.810 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.815Z,1773358985.815 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.816Z,1773358985.816 [BPC1](ERROR): Battery stick #53 (s/n: 01EE) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.817Z,1773358985.817 [BPC1](FAULT): Battery stick #56 (s/n: 0183) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.818Z,1773358985.818 [BPC1](FAULT): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2026-03-12T23:43:05.818Z,1773358985.818 [BPC1](FAULT): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x5AD0. 2026-03-12T23:43:05.819Z,1773358985.819 [BPC1](INFO): Calculating totals. Valid battery stick count: 28. Valid reserve battery stick count: 2. 2026-03-12T23:43:05.827Z,1773358985.827 [BPC1](FAULT): Failed to receive data from 11 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 17, 24, 37, 38, 39, 40, 44, 50, 51, 52, 53. 2026-03-12T23:43:21.554Z,1773359001.554 [NAL9602](INFO): SBD MO Status=2, MOMSN=32662, MT Status=2, MTMSN=0 2026-03-12T23:43:21.554Z,1773359001.554 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-12T23:43:26.532Z,1773359006.532 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:43:36.091Z,1773359016.091 [NAL9602](INFO): SBD MO Status=0, MOMSN=32662, MT Status=0, MTMSN=0 2026-03-12T23:43:36.091Z,1773359016.091 [NAL9602](INFO): No messages in MT queue 2026-03-12T23:43:37.301Z,1773359017.301 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234336.00,A,3648.26969,N,12151.26543,W,0.952,248.26,120326,,,D*71 2026-03-12T23:43:37.304Z,1773359017.304 [NAL9602](INFO): GPS fix at 20260312T234336: (36.804495, -121.854424) 2026-03-12T23:43:37.318Z,1773359017.318 [keepstation:NeedComms:D] Stopped 2026-03-12T23:43:37.318Z,1773359017.318 [keepstation:NeedComms:E] Running Loop=1 2026-03-12T23:43:53.140Z,1773359033.140 [NAL9602](INFO): SBD MO Status=1, MOMSN=32663, MT Status=0, MTMSN=0 2026-03-12T23:43:53.188Z,1773359033.188 [NAL9602](INFO): Sent 201 bytes from file Logs/20260312T230054/Courier0015.lzma 2026-03-12T23:43:53.188Z,1773359033.188 [NAL9602](INFO): Packets left to send: 0 2026-03-12T23:43:57.660Z,1773359037.660 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:44:03.770Z,1773359043.770 [NAL9602](INFO): SBD MO Status=1, MOMSN=32664, MT Status=0, MTMSN=0 2026-03-12T23:44:03.820Z,1773359043.820 [NAL9602](INFO): Sent 332 bytes from file Logs/20260312T230054/Express0016.lzma 2026-03-12T23:44:03.820Z,1773359043.820 [NAL9602](INFO): Packets left to send: 1 2026-03-12T23:44:16.798Z,1773359056.798 [NAL9602](INFO): SBD MO Status=2, MOMSN=32665, MT Status=2, MTMSN=0 2026-03-12T23:44:16.799Z,1773359056.799 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-12T23:44:28.784Z,1773359068.784 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:44:41.130Z,1773359081.130 [NAL9602](INFO): SBD MO Status=1, MOMSN=32665, MT Status=0, MTMSN=0 2026-03-12T23:44:41.192Z,1773359081.192 [NAL9602](INFO): Sent 152 bytes from file Logs/20260312T230054/Express0016.lzma 2026-03-12T23:44:41.192Z,1773359081.192 [NAL9602](INFO): Packets left to send: 0 2026-03-12T23:44:59.709Z,1773359099.709 [NAL9602](INFO): SBD MO Status=2, MOMSN=32666, MT Status=2, MTMSN=0 2026-03-12T23:44:59.709Z,1773359099.709 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-12T23:44:59.908Z,1773359099.908 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:45:04.707Z,1773359104.707 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2026-03-12T23:45:07.129Z,1773359107.129 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2026-03-12T23:45:21.121Z,1773359121.121 [NAL9602](INFO): SBD MO Status=0, MOMSN=32666, MT Status=0, MTMSN=0 2026-03-12T23:45:21.205Z,1773359121.205 [keepstation:NeedComms:E] Stopped 2026-03-12T23:45:21.205Z,1773359121.205 [keepstation:NeedComms:F] Running Loop=1 2026-03-12T23:45:23.554Z,1773359123.554 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234522.00,A,3648.26512,N,12151.27644,W,0.933,219.11,120326,,,D*70 2026-03-12T23:45:23.556Z,1773359123.556 [NAL9602](INFO): GPS fix at 20260312T234522: (36.804419, -121.854607) 2026-03-12T23:45:23.570Z,1773359123.570 [keepstation:NeedComms:F] Stopped 2026-03-12T23:45:23.570Z,1773359123.570 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2026-03-12T23:45:23.570Z,1773359123.570 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2026-03-12T23:45:23.570Z,1773359123.570 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2026-03-12T23:45:23.979Z,1773359123.979 [keepstation:NeedComms:setRestoreMode:A] Stopped 2026-03-12T23:45:23.979Z,1773359123.979 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2026-03-12T23:45:23.979Z,1773359123.979 [keepstation:NeedComms:setRestoreMode] Stopped 2026-03-12T23:45:23.979Z,1773359123.979 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2026-03-12T23:45:23.979Z,1773359123.979 [keepstation:NeedComms:setInactive] Running Loop=1 2026-03-12T23:45:23.979Z,1773359123.979 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2026-03-12T23:45:23.979Z,1773359123.979 [keepstation:NeedComms:setInactive:A] Running Loop=1 2026-03-12T23:45:24.386Z,1773359124.386 [keepstation:NeedComms:setInactive:A] Stopped 2026-03-12T23:45:24.386Z,1773359124.386 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2026-03-12T23:45:24.386Z,1773359124.386 [keepstation:NeedComms:setInactive] Stopped 2026-03-12T23:45:24.386Z,1773359124.386 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2026-03-12T23:45:24.387Z,1773359124.387 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2026-03-12T23:45:24.387Z,1773359124.387 [keepstation:NeedComms] Stopped 2026-03-12T23:45:24.387Z,1773359124.387 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2026-03-12T23:45:24.387Z,1773359124.387 [keepstation:NeedComms:C.GoToSurface] Stopped 2026-03-12T23:45:24.387Z,1773359124.387 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-12T23:45:24.778Z,1773359124.778 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2026-03-12T23:45:24.778Z,1773359124.778 [keepstation:SurfaceComms:B] Stopped 2026-03-12T23:45:24.778Z,1773359124.778 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2026-03-12T23:45:24.778Z,1773359124.778 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2026-03-12T23:45:24.778Z,1773359124.778 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2026-03-12T23:45:24.778Z,1773359124.778 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2026-03-12T23:45:24.778Z,1773359124.778 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T23:45:24.778Z,1773359124.778 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2026-03-12T23:45:25.173Z,1773359125.173 [keepstation:SurfaceComms:HandleNan] Stopped 2026-03-12T23:45:25.173Z,1773359125.173 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2026-03-12T23:45:25.173Z,1773359125.173 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2026-03-12T23:45:25.173Z,1773359125.173 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-12T23:45:25.174Z,1773359125.174 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2026-03-12T23:45:25.174Z,1773359125.174 [keepstation:SurfaceComms:setTransit] Running Loop=1 2026-03-12T23:45:25.174Z,1773359125.174 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2026-03-12T23:45:25.174Z,1773359125.174 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2026-03-12T23:45:25.586Z,1773359125.586 [keepstation:SurfaceComms:setTransit] Stopped 2026-03-12T23:45:25.587Z,1773359125.587 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2026-03-12T23:45:25.587Z,1773359125.587 [keepstation:SurfaceComms:setTransit:A] Stopped 2026-03-12T23:45:25.587Z,1773359125.587 [keepstation:SurfaceComms:setStation] Running Loop=1 2026-03-12T23:45:25.587Z,1773359125.587 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2026-03-12T23:45:25.587Z,1773359125.587 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2026-03-12T23:45:25.979Z,1773359125.979 [keepstation:SurfaceComms:setStation:A] Stopped 2026-03-12T23:45:25.979Z,1773359125.979 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation 2026-03-12T23:45:25.979Z,1773359125.979 [keepstation:SurfaceComms:setStation] Stopped 2026-03-12T23:45:25.980Z,1773359125.980 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2026-03-12T23:45:25.980Z,1773359125.980 [keepstation:SurfaceComms:setSink] Running Loop=1 2026-03-12T23:45:25.980Z,1773359125.980 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2026-03-12T23:45:25.980Z,1773359125.980 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2026-03-12T23:45:26.382Z,1773359126.382 [keepstation:KeepStation:Dive] Running Loop=1 2026-03-12T23:45:26.382Z,1773359126.382 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2026-03-12T23:45:26.382Z,1773359126.382 [keepstation:KeepStation:Dive:A] Running Loop=1 2026-03-12T23:45:26.382Z,1773359126.382 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2026-03-12T23:45:26.383Z,1773359126.383 [keepstation:DiveCmd:StationKeep] Running Loop=1 2026-03-12T23:45:26.383Z,1773359126.383 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2026-03-12T23:45:26.383Z,1773359126.383 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2026-03-12T23:45:26.383Z,1773359126.383 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2026-03-12T23:45:26.383Z,1773359126.383 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2026-03-12T23:45:26.384Z,1773359126.384 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2026-03-12T23:45:26.384Z,1773359126.384 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2026-03-12T23:45:26.385Z,1773359126.385 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2026-03-12T23:45:26.386Z,1773359126.386 [keepstation:SurfaceComms:setSink] Stopped 2026-03-12T23:45:26.386Z,1773359126.386 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2026-03-12T23:45:26.387Z,1773359126.387 [keepstation:SurfaceComms:setSink:A] Stopped 2026-03-12T23:45:26.387Z,1773359126.387 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2026-03-12T23:45:26.387Z,1773359126.387 [keepstation:SurfaceComms] Stopped 2026-03-12T23:45:26.387Z,1773359126.387 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2026-03-12T23:45:31.044Z,1773359131.044 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:45:55.481Z,1773359155.481 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-12T23:46:02.168Z,1773359162.168 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:46:33.300Z,1773359193.300 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:47:04.424Z,1773359224.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:47:11.147Z,1773359231.147 [Radio_Surface](INFO): Powering down 2026-03-12T23:47:35.560Z,1773359255.560 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-12T23:47:36.564Z,1773359256.564 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-03-13T00:00:23.725Z,1773360023.725 [keepstation:SurfaceComms] Running Loop=1 2026-03-13T00:00:23.725Z,1773360023.725 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2026-03-13T00:00:23.725Z,1773360023.725 [keepstation:SurfaceComms:A] Running Loop=1 2026-03-13T00:00:23.725Z,1773360023.725 [keepstation:SurfaceComms:A] Stopped 2026-03-13T00:00:23.726Z,1773360023.726 [keepstation:SurfaceComms:B] Running Loop=1 2026-03-13T00:00:23.726Z,1773360023.726 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2026-03-13T00:00:23.728Z,1773360023.728 [keepstation:NeedComms] Running Loop=1 2026-03-13T00:00:23.728Z,1773360023.728 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2026-03-13T00:00:23.728Z,1773360023.728 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2026-03-13T00:00:23.728Z,1773360023.728 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-03-13T00:00:23.728Z,1773360023.728 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-03-13T00:00:23.729Z,1773360023.729 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-03-13T00:00:23.729Z,1773360023.729 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-03-13T00:00:23.729Z,1773360023.729 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-03-13T00:00:23.730Z,1773360023.730 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-03-13T00:00:23.730Z,1773360023.730 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-03-13T00:00:23.730Z,1773360023.730 [keepstation:NeedComms:A] Running Loop=1 2026-03-13T00:00:23.732Z,1773360023.732 [keepstation:NeedComms:A](INFO): last time_fix was: 1773359122.000000 second since 1970/01/01T00:00:00Z 2026-03-13T00:00:23.732Z,1773360023.732 [keepstation:NeedComms:A] Stopped 2026-03-13T00:00:23.732Z,1773360023.732 [keepstation:NeedComms:setActive] Running Loop=1 2026-03-13T00:00:23.732Z,1773360023.732 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2026-03-13T00:00:23.732Z,1773360023.732 [keepstation:NeedComms:setActive:A] Running Loop=1 2026-03-13T00:00:24.141Z,1773360024.141 [keepstation:KeepStation:Dive] Stopped 2026-03-13T00:00:24.141Z,1773360024.141 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2026-03-13T00:00:24.141Z,1773360024.141 [keepstation:KeepStation:Dive:A] Stopped 2026-03-13T00:00:24.141Z,1773360024.141 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2026-03-13T00:00:24.142Z,1773360024.142 [keepstation:DiveCmd:StationKeep] Stopped 2026-03-13T00:00:24.142Z,1773360024.142 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2026-03-13T00:00:24.142Z,1773360024.142 [keepstation:DiveCmd:StationKeep:A] Stopped 2026-03-13T00:00:24.142Z,1773360024.142 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2026-03-13T00:00:24.142Z,1773360024.142 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2026-03-13T00:00:24.142Z,1773360024.142 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2026-03-13T00:00:24.145Z,1773360024.145 [keepstation:NeedComms:setActive:A] Stopped 2026-03-13T00:00:24.145Z,1773360024.145 [keepstation:NeedComms:setActive:B] Running Loop=1 2026-03-13T00:00:24.614Z,1773360024.614 [keepstation:NeedComms:setActive:B] Stopped 2026-03-13T00:00:24.614Z,1773360024.614 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2026-03-13T00:00:24.614Z,1773360024.614 [keepstation:NeedComms:setActive] Stopped 2026-03-13T00:00:24.614Z,1773360024.614 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2026-03-13T00:02:15.493Z,1773360135.493 [keepstation:NeedComms:D] Running Loop=1 2026-03-13T00:02:16.259Z,1773360136.259 [Radio_Surface](INFO): Powering up 2026-03-13T00:02:22.312Z,1773360142.312 [DataOverHttps](INFO): Radio surface powered ON. 2026-03-13T00:02:42.154Z,1773360162.154 [NAL9602](INFO): SBD MO Status=2, MOMSN=32667, MT Status=2, MTMSN=0 2026-03-13T00:02:42.154Z,1773360162.154 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-13T00:02:51.452Z,1773360171.452 [NAL9602](INFO): SBD MO Status=0, MOMSN=32667, MT Status=0, MTMSN=0 2026-03-13T00:02:51.452Z,1773360171.452 [NAL9602](INFO): No messages in MT queue 2026-03-13T00:02:52.444Z,1773360172.444 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-13T00:02:52.652Z,1773360172.652 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000251.00,A,3648.25178,N,12150.94491,W,0.564,173.60,130326,,,A*7D 2026-03-13T00:02:52.659Z,1773360172.659 [NAL9602](INFO): GPS fix at 20260313T000251: (36.804196, -121.849082) 2026-03-13T00:02:52.692Z,1773360172.692 [UniversalFixResidualReporter](INFO): Fix residual: 10.2 %DT, over the last 606.0 m. Residual distance 61.5 m at bearing 99.5 degrees. Fix at (36.8042, -121.8491) with 492.6 m made good. 2026-03-13T00:02:52.695Z,1773360172.695 [keepstation:NeedComms:D] Stopped 2026-03-13T00:02:52.700Z,1773360172.700 [keepstation:NeedComms:E] Running Loop=1 2026-03-13T00:03:11.314Z,1773360191.314 [NAL9602](INFO): SBD MO Status=1, MOMSN=32668, MT Status=0, MTMSN=0 2026-03-13T00:03:11.364Z,1773360191.364 [NAL9602](INFO): Sent 207 bytes from file Logs/20260312T230054/Courier0018.lzma 2026-03-13T00:03:11.364Z,1773360191.364 [NAL9602](INFO): Packets left to send: 0 2026-03-13T00:03:23.522Z,1773360203.522 [NAL9602](INFO): SBD MO Status=2, MOMSN=32669, MT Status=2, MTMSN=0 2026-03-13T00:03:23.522Z,1773360203.522 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-03-13T00:03:23.584Z,1773360203.584 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-13T00:03:36.114Z,1773360216.114 [NAL9602](INFO): SBD MO Status=1, MOMSN=32669, MT Status=0, MTMSN=0 2026-03-13T00:03:36.166Z,1773360216.166 [NAL9602](INFO): Sent 281 bytes from file Logs/20260312T230054/Express0019.lzma 2026-03-13T00:03:36.166Z,1773360216.166 [NAL9602](INFO): Packets left to send: 0 2026-03-13T00:03:44.594Z,1773360224.594 [NAL9602](INFO): SBD MO Status=0, MOMSN=32670, MT Status=0, MTMSN=0 2026-03-13T00:03:44.714Z,1773360224.714 [keepstation:NeedComms:E] Stopped 2026-03-13T00:03:44.715Z,1773360224.715 [keepstation:NeedComms:F] Running Loop=1 2026-03-13T00:03:47.017Z,1773360227.017 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000346.00,A,3648.25082,N,12150.94605,W,1.089,294.72,130326,,,D*7A 2026-03-13T00:03:47.020Z,1773360227.020 [NAL9602](INFO): GPS fix at 20260313T000346: (36.804180, -121.849101) 2026-03-13T00:03:47.033Z,1773360227.033 [keepstation:NeedComms:F] Stopped 2026-03-13T00:03:47.033Z,1773360227.033 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2026-03-13T00:03:47.033Z,1773360227.033 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2026-03-13T00:03:47.033Z,1773360227.033 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2026-03-13T00:03:47.474Z,1773360227.474 [keepstation:NeedComms:setRestoreMode:A] Stopped 2026-03-13T00:03:47.474Z,1773360227.474 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2026-03-13T00:03:47.474Z,1773360227.474 [keepstation:NeedComms:setRestoreMode] Stopped 2026-03-13T00:03:47.474Z,1773360227.474 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2026-03-13T00:03:47.475Z,1773360227.475 [keepstation:NeedComms:setInactive] Running Loop=1 2026-03-13T00:03:47.475Z,1773360227.475 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2026-03-13T00:03:47.475Z,1773360227.475 [keepstation:NeedComms:setInactive:A] Running Loop=1 2026-03-13T00:03:47.842Z,1773360227.842 [keepstation:NeedComms:setInactive:A] Stopped 2026-03-13T00:03:47.842Z,1773360227.842 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2026-03-13T00:03:47.843Z,1773360227.843 [keepstation:NeedComms:setInactive] Stopped 2026-03-13T00:03:47.843Z,1773360227.843 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2026-03-13T00:03:47.844Z,1773360227.844 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2026-03-13T00:03:47.844Z,1773360227.844 [keepstation:NeedComms] Stopped 2026-03-13T00:03:47.844Z,1773360227.844 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2026-03-13T00:03:47.844Z,1773360227.844 [keepstation:NeedComms:C.GoToSurface] Stopped 2026-03-13T00:03:47.844Z,1773360227.844 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-03-13T00:03:48.252Z,1773360228.252 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2026-03-13T00:03:48.252Z,1773360228.252 [keepstation:SurfaceComms:B] Stopped 2026-03-13T00:03:48.252Z,1773360228.252 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2026-03-13T00:03:48.252Z,1773360228.252 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2026-03-13T00:03:48.252Z,1773360228.252 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2026-03-13T00:03:48.252Z,1773360228.252 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2026-03-13T00:03:48.252Z,1773360228.252 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-13T00:03:48.252Z,1773360228.252 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2026-03-13T00:03:48.665Z,1773360228.665 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position -2.43 mm. 2026-03-13T00:03:48.666Z,1773360228.666 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position -2.43 mm. 2026-03-13T00:03:48.672Z,1773360228.672 [keepstation:SurfaceComms:HandleNan] Stopped 2026-03-13T00:03:48.672Z,1773360228.672 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2026-03-13T00:03:48.672Z,1773360228.672 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2026-03-13T00:03:48.672Z,1773360228.672 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2026-03-13T00:03:48.672Z,1773360228.672 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2026-03-13T00:03:48.673Z,1773360228.673 [keepstation:SurfaceComms:setTransit] Running Loop=1 2026-03-13T00:03:48.673Z,1773360228.673 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2026-03-13T00:03:48.673Z,1773360228.673 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2026-03-13T00:03:49.052Z,1773360229.052 [keepstation:SurfaceComms:setTransit] Stopped 2026-03-13T00:03:49.052Z,1773360229.052 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2026-03-13T00:03:49.052Z,1773360229.052 [keepstation:SurfaceComms:setTransit:A] Stopped 2026-03-13T00:03:49.053Z,1773360229.053 [keepstation:SurfaceComms:setStation] Running Loop=1 2026-03-13T00:03:49.053Z,1773360229.053 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2026-03-13T00:03:49.053Z,1773360229.053 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2026-03-13T00:03:49.461Z,1773360229.461 [keepstation:SurfaceComms:setStation:A] Stopped 2026-03-13T00:03:49.461Z,1773360229.461 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation 2026-03-13T00:03:49.461Z,1773360229.461 [keepstation:SurfaceComms:setStation] Stopped 2026-03-13T00:03:49.461Z,1773360229.461 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2026-03-13T00:03:49.461Z,1773360229.461 [keepstation:SurfaceComms:setSink] Running Loop=1 2026-03-13T00:03:49.461Z,1773360229.461 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2026-03-13T00:03:49.462Z,1773360229.462 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2026-03-13T00:03:49.866Z,1773360229.866 [keepstation:KeepStation:Dive] Running Loop=1 2026-03-13T00:03:49.866Z,1773360229.866 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2026-03-13T00:03:49.866Z,1773360229.866 [keepstation:KeepStation:Dive:A] Running Loop=1 2026-03-13T00:03:49.866Z,1773360229.866 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2026-03-13T00:03:49.867Z,1773360229.867 [keepstation:DiveCmd:StationKeep] Running Loop=1 2026-03-13T00:03:49.867Z,1773360229.867 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2026-03-13T00:03:49.867Z,1773360229.867 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2026-03-13T00:03:49.867Z,1773360229.867 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2026-03-13T00:03:49.867Z,1773360229.867 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2026-03-13T00:03:49.868Z,1773360229.868 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2026-03-13T00:03:49.868Z,1773360229.868 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2026-03-13T00:03:49.869Z,1773360229.869 [keepstation:SurfaceComms:setSink] Stopped 2026-03-13T00:03:49.869Z,1773360229.869 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2026-03-13T00:03:49.869Z,1773360229.869 [keepstation:SurfaceComms:setSink:A] Stopped 2026-03-13T00:03:49.870Z,1773360229.870 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2026-03-13T00:03:49.870Z,1773360229.870 [keepstation:SurfaceComms] Stopped 2026-03-13T00:03:49.870Z,1773360229.870 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2026-03-13T00:03:54.720Z,1773360234.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-13T00:03:59.191Z,1773360239.191 [BuoyancyServo](ERROR): getPosition uart error serial timeout 2026-03-13T00:03:59.192Z,1773360239.192 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout 2026-03-13T00:03:59.192Z,1773360239.192 [BuoyancyServo] Communications Fault, FailCount= 1 2026-03-13T00:03:59.192Z,1773360239.192 [BuoyancyServo](ERROR): Communications Fault 2026-03-13T00:03:59.196Z,1773360239.196 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2026-03-13T00:04:00.858Z,1773360240.858 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2026-03-13T00:04:00.858Z,1773360240.858 [BuoyancyServo](INFO): Powering down 2026-03-13T00:04:01.658Z,1773360241.658 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2026-03-13T00:04:01.659Z,1773360241.659 [BuoyancyServo] No Fault, FailCount= 1 2026-03-13T00:04:02.060Z,1773360242.060 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2026-03-13T00:04:02.060Z,1773360242.060 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2026-03-13T00:04:19.415Z,1773360259.415 [NAL9602](INFO): Not Powering down - fast GPS 2026-03-13T00:04:25.844Z,1773360265.844 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-13T00:04:56.976Z,1773360296.976 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-13T00:05:28.108Z,1773360328.108 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-13T00:05:59.244Z,1773360359.244 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-03-13T00:06:07.900Z,1773360367.900 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003070 2026-03-13T00:09:11.745Z,1773360551.745 [DataOverHttps](IMPORTANT): SBD MTMSN=20260313T000910 2026-03-13T00:09:19.243Z,1773360559.243 [DataOverHttps](INFO): Received command: restart logs;schedule resume