2025-04-24T03:00:00.480Z,1745463600.480 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist 2025-04-24T03:00:00.483Z,1745463600.483 [CommandExec](IMPORTANT): got command restart logs 2025-04-24T03:00:08.036Z,1745463608.036 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-04-24T03:00:08.037Z,1745463608.037 [MissionManager](INFO): Uninitializing Mission profile_station 2025-04-24T03:00:08.037Z,1745463608.037 [profile_station] Stopped 2025-04-24T03:00:08.037Z,1745463608.037 [profile_station](DEBUG): Aggregate::uninitialize profile_station 2025-04-24T03:00:08.037Z,1745463608.037 [profile_station:Science] Stopped 2025-04-24T03:00:08.037Z,1745463608.037 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science 2025-04-24T03:00:08.037Z,1745463608.037 [profile_station:Science:B] Stopped 2025-04-24T03:00:08.037Z,1745463608.037 [profile_station:Science:C] Stopped 2025-04-24T03:00:08.037Z,1745463608.037 [profile_station:Science:E] Stopped 2025-04-24T03:00:08.037Z,1745463608.037 [profile_station:Science:OceanCurrent] Stopped 2025-04-24T03:00:08.037Z,1745463608.037 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent 2025-04-24T03:00:08.037Z,1745463608.037 [profile_station:Science:OceanCurrent:A.] Stopped 2025-04-24T03:00:08.037Z,1745463608.037 [profile_station:Science:PeakDetectChl] Stopped 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth] Stopped 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Uninitialize. 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:StandardEnvelopes] Stopped 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-04-24T03:00:08.038Z,1745463608.038 [profile_station:BackseatDriver] Stopped 2025-04-24T03:00:08.039Z,1745463608.039 [profile_station:BackseatDriver](DEBUG): Aggregate::uninitialize profile_station:BackseatDriver 2025-04-24T03:00:08.039Z,1745463608.039 [profile_station:BackseatDriver:A.BackseatDriver] Stopped 2025-04-24T03:00:08.039Z,1745463608.039 [profile_station:PowerOnly] Stopped 2025-04-24T03:00:08.039Z,1745463608.039 [profile_station:PowerOnly](DEBUG): Aggregate::uninitialize profile_station:PowerOnly 2025-04-24T03:00:08.039Z,1745463608.039 [profile_station:PowerOnly:E.Wait] Stopped 2025-04-24T03:00:08.039Z,1745463608.039 [profile_station:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-04-24T03:00:08.039Z,1745463608.039 [profile_station:DiveCmd] Stopped 2025-04-24T03:00:08.039Z,1745463608.039 [profile_station:DiveCmd](DEBUG): Aggregate::uninitialize profile_station:DiveCmd 2025-04-24T03:00:08.039Z,1745463608.039 [profile_station:DiveCmd:SurfaceSink] Stopped 2025-04-24T03:00:08.039Z,1745463608.039 [profile_station:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:SurfaceSink 2025-04-24T03:00:08.042Z,1745463608.042 [profile_station:DiveCmd:YoYoDive] Stopped 2025-04-24T03:00:08.042Z,1745463608.042 [profile_station:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive 2025-04-24T03:00:08.042Z,1745463608.042 [profile_station:DiveCmd:YoYoDive:dive] Stopped 2025-04-24T03:00:08.042Z,1745463608.042 [profile_station:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive 2025-04-24T03:00:08.042Z,1745463608.042 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch] Stopped 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed] Stopped 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize. 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-04-24T03:00:08.043Z,1745463608.043 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo] Stopped 2025-04-24T03:00:08.052Z,1745463608.052 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent. 2025-04-24T03:00:08.052Z,1745463608.052 [profile_station:CircleWrapper] Stopped 2025-04-24T03:00:08.052Z,1745463608.052 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper 2025-04-24T03:00:08.053Z,1745463608.053 [profile_station:CircleWrapper:A] Stopped 2025-04-24T03:00:08.053Z,1745463608.053 [profile_station:CircleWrapper:Dive] Stopped 2025-04-24T03:00:08.053Z,1745463608.053 [profile_station:CircleWrapper:Dive](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive 2025-04-24T03:00:08.053Z,1745463608.053 [profile_station:CircleWrapper:Dive:A] Stopped 2025-04-24T03:00:08.053Z,1745463608.053 [profile_station:CircleWrapper:Dive:A](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive:A 2025-04-24T03:00:08.053Z,1745463608.053 [profile_station:CircleWrapper:C.Circle] Stopped 2025-04-24T03:00:08.053Z,1745463608.053 [profile_station:CircleWrapper:C.Circle](DEBUG): Uninitialize. 2025-04-24T03:00:08.192Z,1745463608.192 [WetLabsBB2FL](INFO): Powering down 2025-04-24T03:00:08.236Z,1745463608.236 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-04-24T03:00:08.236Z,1745463608.236 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-04-24T03:00:08.246Z,1745463608.246 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-04-24T03:00:10.534Z,1745463610.534 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into: 4 45 NaN NaN 30 15 4 0.75 200 10 10 45 2 0 0 1 2 3 NaN 0 Sinking to Timed out while trying to reach target sink depth at current depth of 2025-04-24T03:00:10.549Z,1745463610.549 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-04-24T03:00:10.552Z,1745463610.552 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-04-24T03:00:10.555Z,1745463610.555 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-04-24T03:00:10.558Z,1745463610.558 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-04-24T03:00:10.561Z,1745463610.561 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-04-24T03:00:10.564Z,1745463610.564 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-04-24T03:00:10.567Z,1745463610.567 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-04-24T03:00:10.571Z,1745463610.571 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-04-24T03:00:10.574Z,1745463610.574 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-04-24T03:00:10.577Z,1745463610.577 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool 2025-04-24T03:00:10.580Z,1745463610.580 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m 2025-04-24T03:00:10.583Z,1745463610.583 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min 2025-04-24T03:00:10.586Z,1745463610.586 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-04-24T03:00:10.589Z,1745463610.589 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-04-24T03:00:10.632Z,1745463610.632 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count 2025-04-24T03:00:10.636Z,1745463610.636 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count 2025-04-24T03:00:10.639Z,1745463610.639 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count 2025-04-24T03:00:10.654Z,1745463610.654 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count 2025-04-24T03:00:10.658Z,1745463610.658 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count 2025-04-24T03:00:10.661Z,1745463610.661 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool 2025-04-24T03:00:10.661Z,1745463610.661 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-04-24T03:00:10.661Z,1745463610.661 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-04-24T03:00:10.663Z,1745463610.663 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-04-24T03:00:11.044Z,1745463611.044 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2025-04-24T03:00:11.050Z,1745463611.050 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-04-24T03:00:11.054Z,1745463611.054 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-04-24T03:00:11.057Z,1745463611.057 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-04-24T03:00:11.060Z,1745463611.060 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-04-24T03:00:11.084Z,1745463611.084 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-04-24T03:00:11.092Z,1745463611.092 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s 2025-04-24T03:00:11.095Z,1745463611.095 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-04-24T03:00:11.103Z,1745463611.103 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-04-24T03:00:11.159Z,1745463611.159 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-24T03:00:11.242Z,1745463611.242 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-04-24T03:00:11.242Z,1745463611.242 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-04-24T03:00:11.243Z,1745463611.243 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-04-24T03:00:11.372Z,1745463611.372 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2025-04-24T03:00:11.378Z,1745463611.378 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-04-24T03:00:11.381Z,1745463611.381 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2025-04-24T03:00:11.385Z,1745463611.385 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-04-24T03:00:11.388Z,1745463611.388 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-04-24T03:00:11.389Z,1745463611.389 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-04-24T03:00:11.397Z,1745463611.397 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-04-24T03:00:11.423Z,1745463611.423 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-04-24T03:00:11.463Z,1745463611.463 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-04-24T03:00:11.463Z,1745463611.463 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-04-24T03:00:11.470Z,1745463611.470 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-04-24T03:00:11.545Z,1745463611.545 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-04-24T03:00:11.551Z,1745463611.551 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-04-24T03:00:11.558Z,1745463611.558 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-04-24T03:00:11.569Z,1745463611.569 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-04-24T03:00:11.569Z,1745463611.569 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-04-24T03:00:11.570Z,1745463611.570 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-04-24T03:00:11.807Z,1745463611.807 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-04-24T03:00:11.812Z,1745463611.812 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-04-24T03:00:11.815Z,1745463611.815 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-04-24T03:00:11.818Z,1745463611.818 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-04-24T03:00:11.820Z,1745463611.820 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-04-24T03:00:11.824Z,1745463611.824 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool 2025-04-24T03:00:11.831Z,1745463611.831 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-04-24T03:00:11.833Z,1745463611.833 [keepstation:H.Pitch](DEBUG): Construct. 2025-04-24T03:00:11.910Z,1745463611.910 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-04-24T03:00:11.977Z,1745463611.977 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct. 2025-04-24T03:00:11.986Z,1745463611.986 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct. 2025-04-24T03:00:12.026Z,1745463612.026 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-04-24T03:00:12.034Z,1745463612.034 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct. 2025-04-24T03:00:12.049Z,1745463612.049 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct. 2025-04-24T03:00:12.064Z,1745463612.064 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct. 2025-04-24T03:00:12.074Z,1745463612.074 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation. 2025-04-24T03:00:12.096Z,1745463612.096 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-04-24T03:00:12.122Z,1745463612.122 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait. 2025-04-24T03:00:12.158Z,1745463612.158 [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 refId="NeedComms" aggregate setTransit { run in sequence break if ( SinkOffSurface or OnStation ) assign in sequence DiveMode = TransitToStation } aggregate setStation { run in sequence break if ( SinkOffSurface or not OnStation ) assign in sequence DiveMode = StationKeep } aggregate setSink { run in sequence break if ( not SinkOffSurface ) assign in sequence DiveMode = SurfaceSink } } call id="StartingMission" 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 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" refId="NeedComms" aggregate KeepStation { run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call refId="DiveCmd" } behavior Guidance:Wait { """ Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. """ run in sequence set duration = MissionTimeout } } } 2025-04-24T03:00:12.159Z,1745463612.159 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-04-24T03:00:12.381Z,1745463612.381 [MissionManager](IMPORTANT): Started mission Default 2025-04-24T03:00:12.381Z,1745463612.381 [Default] Running Loop=1 2025-04-24T03:00:12.381Z,1745463612.381 [Default](DEBUG): Aggregate::initialize Default 2025-04-24T03:00:12.381Z,1745463612.381 [Default:B.GoToSurface] Running Loop=1 2025-04-24T03:00:12.381Z,1745463612.381 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-24T03:00:12.382Z,1745463612.382 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-24T03:00:12.382Z,1745463612.382 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-24T03:00:12.382Z,1745463612.382 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-24T03:00:12.383Z,1745463612.383 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-24T03:00:12.383Z,1745463612.383 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-24T03:00:12.383Z,1745463612.383 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-24T03:00:12.383Z,1745463612.383 [Default:A.Wait] Running Loop=1 2025-04-24T03:00:12.392Z,1745463612.392 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-04-24T03:00:12.795Z,1745463612.795 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm (3 active estimators). 2025-04-24T03:00:12.900Z,1745463612.900 [Radio_Surface](INFO): Powering up 2025-04-24T03:00:18.868Z,1745463618.868 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-24T03:00:25.790Z,1745463625.790 [Default:A.Wait](INFO): Done Waiting. 2025-04-24T03:00:25.790Z,1745463625.790 [Default:A.Wait] Stopped 2025-04-24T03:00:25.790Z,1745463625.790 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-24T03:00:32.471Z,1745463632.471 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 11 hour 2025-04-24T03:00:32.471Z,1745463632.471 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 120 minute 2025-04-24T03:00:32.486Z,1745463632.486 [CommandExec](IMPORTANT): got command set keepstation.Depth 20 meter 2025-04-24T03:00:32.487Z,1745463632.487 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool 2025-04-24T03:00:32.487Z,1745463632.487 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 45 meter 2025-04-24T03:00:43.976Z,1745463643.976 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second 2025-04-24T03:00:43.977Z,1745463643.977 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 10 minute 2025-04-24T03:00:43.978Z,1745463643.978 [CommandExec](IMPORTANT): got command run 2025-04-24T03:00:43.980Z,1745463643.980 [CommandExec](IMPORTANT): Running 2025-04-24T03:00:44.078Z,1745463644.078 [Default] Stopped 2025-04-24T03:00:44.078Z,1745463644.078 [Default](DEBUG): Aggregate::uninitialize Default 2025-04-24T03:00:44.078Z,1745463644.078 [Default:B.GoToSurface] Stopped 2025-04-24T03:00:44.078Z,1745463644.078 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-24T03:00:44.078Z,1745463644.078 [MissionManager](IMPORTANT): Started mission keepstation 2025-04-24T03:00:44.079Z,1745463644.079 [keepstation] Running Loop=1 2025-04-24T03:00:44.079Z,1745463644.079 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-04-24T03:00:44.079Z,1745463644.079 [keepstation:StandardEnvelopes] Running Loop=1 2025-04-24T03:00:44.079Z,1745463644.079 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-04-24T03:00:44.079Z,1745463644.079 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-04-24T03:00:44.079Z,1745463644.079 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-04-24T03:00:44.079Z,1745463644.079 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-04-24T03:00:44.079Z,1745463644.079 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-04-24T03:00:44.080Z,1745463644.080 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-04-24T03:00:44.080Z,1745463644.080 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-04-24T03:00:44.080Z,1745463644.080 [keepstation:BackseatDriver] Running Loop=1 2025-04-24T03:00:44.080Z,1745463644.080 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-04-24T03:00:44.080Z,1745463644.080 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-04-24T03:00:44.080Z,1745463644.080 [keepstation:PowerOnly] Running Loop=1 2025-04-24T03:00:44.081Z,1745463644.081 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-04-24T03:00:44.081Z,1745463644.081 [keepstation:PowerOnly:A] Running Loop=1 2025-04-24T03:00:44.081Z,1745463644.081 [keepstation:PowerOnly:B] Running Loop=1 2025-04-24T03:00:44.081Z,1745463644.081 [keepstation:PowerOnly:C] Running Loop=1 2025-04-24T03:00:44.081Z,1745463644.081 [keepstation:PowerOnly:D] Running Loop=1 2025-04-24T03:00:44.081Z,1745463644.081 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-04-24T03:00:44.081Z,1745463644.081 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-04-24T03:00:44.081Z,1745463644.081 [keepstation:H.Pitch] Running Loop=1 2025-04-24T03:00:44.081Z,1745463644.081 [keepstation:H.Pitch](DEBUG): Initialize. 2025-04-24T03:00:44.081Z,1745463644.081 [keepstation:B] Running Loop=1 2025-04-24T03:00:44.082Z,1745463644.082 [keepstation:H.Pitch] Running Loop=1 2025-04-24T03:00:44.082Z,1745463644.082 [keepstation:PowerOnly] Running Loop=1 2025-04-24T03:00:44.083Z,1745463644.083 [keepstation:PowerOnly:D] Stopped 2025-04-24T03:00:44.083Z,1745463644.083 [keepstation:PowerOnly:C] Stopped 2025-04-24T03:00:44.083Z,1745463644.083 [keepstation:PowerOnly:B] Stopped 2025-04-24T03:00:44.083Z,1745463644.083 [keepstation:PowerOnly:A] Stopped 2025-04-24T03:00:44.083Z,1745463644.083 [keepstation:BackseatDriver] Running Loop=1 2025-04-24T03:00:44.083Z,1745463644.083 [keepstation:StandardEnvelopes] Running Loop=1 2025-04-24T03:00:44.083Z,1745463644.083 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-04-24T03:00:44.084Z,1745463644.084 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-04-24T03:00:44.085Z,1745463644.085 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-04-24T03:00:44.085Z,1745463644.085 [keepstation:B] Stopped 2025-04-24T03:00:44.085Z,1745463644.085 [keepstation:D] Running Loop=1 2025-04-24T03:00:44.439Z,1745463644.439 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -0.45 mm (4 active estimators). 2025-04-24T03:00:44.446Z,1745463644.446 [keepstation:D] Stopped 2025-04-24T03:00:44.446Z,1745463644.446 [keepstation:E] Running Loop=1 2025-04-24T03:00:44.862Z,1745463644.862 [keepstation:E] Stopped 2025-04-24T03:00:44.862Z,1745463644.862 [keepstation:StartingMission] Running Loop=1 2025-04-24T03:00:44.862Z,1745463644.862 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-04-24T03:00:45.255Z,1745463645.255 [keepstation:SurfaceComms] Running Loop=1 2025-04-24T03:00:45.255Z,1745463645.255 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-04-24T03:00:45.255Z,1745463645.255 [keepstation:SurfaceComms:A] Running Loop=1 2025-04-24T03:00:45.256Z,1745463645.256 [keepstation:SurfaceComms:A] Stopped 2025-04-24T03:00:45.256Z,1745463645.256 [keepstation:SurfaceComms:B] Running Loop=1 2025-04-24T03:00:45.256Z,1745463645.256 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-04-24T03:00:45.655Z,1745463645.655 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2025-04-24T03:00:45.655Z,1745463645.655 [keepstation:NeedComms] Running Loop=1 2025-04-24T03:00:45.655Z,1745463645.655 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-24T03:00:45.655Z,1745463645.655 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-24T03:00:45.655Z,1745463645.655 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-24T03:00:45.656Z,1745463645.656 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-24T03:00:45.657Z,1745463645.657 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-24T03:00:45.657Z,1745463645.657 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-24T03:00:45.658Z,1745463645.658 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-24T03:00:45.658Z,1745463645.658 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-24T03:00:45.658Z,1745463645.658 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-24T03:00:45.658Z,1745463645.658 [keepstation:NeedComms:A] Running Loop=1 2025-04-24T03:00:45.660Z,1745463645.660 [keepstation:NeedComms:A](INFO): last time_fix was: 1745460630.000000 second since 1970/01/01T00:00:00Z 2025-04-24T03:00:45.660Z,1745463645.660 [keepstation:NeedComms:A] Stopped 2025-04-24T03:00:49.014Z,1745463649.014 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:00:56.984Z,1745463656.984 [keepstation:NeedComms:C] Running Loop=1 2025-04-24T03:00:57.392Z,1745463657.392 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-24T03:01:20.136Z,1745463680.136 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:01:29.700Z,1745463689.700 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-24T03:01:29.780Z,1745463689.780 [NAL9602](ERROR): received: +CSQ:0 OK439, 2, 0, 0, 0 OK 2025-04-24T03:01:51.260Z,1745463711.260 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:01:57.173Z,1745463717.173 [NAL9602](INFO): SBD MO Status=2, MOMSN=20439, MT Status=2, MTMSN=0 2025-04-24T03:01:57.173Z,1745463717.173 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-24T03:02:15.353Z,1745463735.353 [NAL9602](INFO): SBD MO Status=0, MOMSN=20439, MT Status=0, MTMSN=0 2025-04-24T03:02:15.353Z,1745463735.353 [NAL9602](INFO): No messages in MT queue 2025-04-24T03:02:16.569Z,1745463736.569 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030209.00,A,3646.76660,N,12153.87873,W,0.758,111.61,240425,,,A*7C 2025-04-24T03:02:16.571Z,1745463736.571 [NAL9602](INFO): GPS fix at 20250424T030209: (36.779443, -121.897979) 2025-04-24T03:02:16.583Z,1745463736.583 [UniversalFixResidualReporter](INFO): Fix residual: 24.0 %DT, over the last 2727.2 m. Residual distance 655.1 m at bearing -45.4 degrees. Fix at (36.7794, -121.8980) with 1888.2 m made good. 2025-04-24T03:02:16.586Z,1745463736.586 [keepstation:NeedComms:C] Stopped 2025-04-24T03:02:16.586Z,1745463736.586 [keepstation:NeedComms:D] Running Loop=1 2025-04-24T03:02:17.027Z,1745463737.027 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-24T03:02:22.384Z,1745463742.384 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:02:34.135Z,1745463754.135 [NAL9602](INFO): SBD MO Status=1, MOMSN=20440, MT Status=0, MTMSN=0 2025-04-24T03:02:34.185Z,1745463754.185 [NAL9602](INFO): Sent 91 bytes from file Logs/20250423T194226/Courier0049.lzma 2025-04-24T03:02:34.186Z,1745463754.186 [NAL9602](INFO): Packets left to send: 0 2025-04-24T03:02:40.017Z,1745463760.017 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003484 2025-04-24T03:02:46.427Z,1745463766.427 [NAL9602](INFO): SBD MO Status=1, MOMSN=20441, MT Status=0, MTMSN=0 2025-04-24T03:02:46.476Z,1745463766.476 [NAL9602](INFO): Sent 329 bytes from file Logs/20250424T030000/Courier0000.lzma 2025-04-24T03:02:46.476Z,1745463766.476 [NAL9602](INFO): Packets left to send: 0 2025-04-24T03:02:48.626Z,1745463768.626 [DataOverHttps](INFO): Sending 1063 bytes from file Logs/20250423T194226/Express0050.lzma 2025-04-24T03:02:49.626Z,1745463769.626 [DataOverHttps](INFO): Moved sent file to Logs/20250423T194226/Express0050.lzma.bak 2025-04-24T03:02:49.626Z,1745463769.626 [DataOverHttps](INFO): SBD MOMSN=24722069 2025-04-24T03:03:05.798Z,1745463785.798 [DataOverHttps](INFO): Sending 943 bytes from file Logs/20250424T030000/Express0001.lzma 2025-04-24T03:03:06.798Z,1745463786.798 [DataOverHttps](INFO): Moved sent file to Logs/20250424T030000/Express0001.lzma.bak 2025-04-24T03:03:06.798Z,1745463786.798 [DataOverHttps](INFO): SBD MOMSN=24722074 2025-04-24T03:03:08.665Z,1745463788.665 [keepstation:NeedComms:D] Stopped 2025-04-24T03:03:08.665Z,1745463788.665 [keepstation:NeedComms:E] Running Loop=1 2025-04-24T03:03:09.053Z,1745463789.053 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-24T03:03:10.663Z,1745463790.663 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030304.00,A,3646.76223,N,12153.87067,W,0.641,24.05,240425,,,A*42 2025-04-24T03:03:10.665Z,1745463790.665 [NAL9602](INFO): GPS fix at 20250424T030304: (36.779370, -121.897845) 2025-04-24T03:03:10.678Z,1745463790.678 [keepstation:NeedComms:E] Stopped 2025-04-24T03:03:10.679Z,1745463790.679 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-24T03:03:10.679Z,1745463790.679 [keepstation:NeedComms] Stopped 2025-04-24T03:03:10.679Z,1745463790.679 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-24T03:03:10.679Z,1745463790.679 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-24T03:03:10.679Z,1745463790.679 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-24T03:03:11.104Z,1745463791.104 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-04-24T03:03:11.104Z,1745463791.104 [keepstation:SurfaceComms:B] Stopped 2025-04-24T03:03:11.105Z,1745463791.105 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-04-24T03:03:11.105Z,1745463791.105 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-04-24T03:03:11.105Z,1745463791.105 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-04-24T03:03:11.105Z,1745463791.105 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-04-24T03:03:11.477Z,1745463791.477 [keepstation:SurfaceComms:setTransit] Stopped 2025-04-24T03:03:11.477Z,1745463791.477 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-04-24T03:03:11.477Z,1745463791.477 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-04-24T03:03:11.477Z,1745463791.477 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-04-24T03:03:11.477Z,1745463791.477 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-04-24T03:03:11.477Z,1745463791.477 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-04-24T03:03:11.919Z,1745463791.919 [keepstation:SurfaceComms:setStation] Stopped 2025-04-24T03:03:11.919Z,1745463791.919 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-04-24T03:03:11.919Z,1745463791.919 [keepstation:SurfaceComms:setStation:A] Stopped 2025-04-24T03:03:11.928Z,1745463791.928 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-04-24T03:03:11.928Z,1745463791.928 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-04-24T03:03:11.928Z,1745463791.928 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-04-24T03:03:12.307Z,1745463792.307 [keepstation:SurfaceComms:setSink:A] Stopped 2025-04-24T03:03:12.307Z,1745463792.307 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-04-24T03:03:12.307Z,1745463792.307 [keepstation:SurfaceComms:setSink] Stopped 2025-04-24T03:03:12.307Z,1745463792.307 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-04-24T03:03:12.307Z,1745463792.307 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-04-24T03:03:12.307Z,1745463792.307 [keepstation:SurfaceComms] Stopped 2025-04-24T03:03:12.307Z,1745463792.307 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-04-24T03:03:12.724Z,1745463792.724 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-04-24T03:03:12.724Z,1745463792.724 [keepstation:StartingMission] Stopped 2025-04-24T03:03:12.724Z,1745463792.724 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-04-24T03:03:12.724Z,1745463792.724 [keepstation:TransitToStation] Running Loop=1 2025-04-24T03:03:12.725Z,1745463792.725 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-04-24T03:03:12.725Z,1745463792.725 [keepstation:TransitToStation:Dive] Running Loop=1 2025-04-24T03:03:12.725Z,1745463792.725 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-04-24T03:03:12.725Z,1745463792.725 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-04-24T03:03:12.725Z,1745463792.725 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-04-24T03:03:12.725Z,1745463792.725 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-04-24T03:03:12.725Z,1745463792.725 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-24T03:03:13.117Z,1745463793.117 [keepstation:TransitToStation:Dive] Running Loop=1 2025-04-24T03:03:13.118Z,1745463793.118 [keepstation:DiveCmd] Running Loop=1 2025-04-24T03:03:13.118Z,1745463793.118 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2025-04-24T03:03:13.118Z,1745463793.118 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-04-24T03:03:13.118Z,1745463793.118 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-04-24T03:03:13.118Z,1745463793.118 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-04-24T03:03:13.118Z,1745463793.118 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T03:03:13.118Z,1745463793.118 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-24T03:03:13.118Z,1745463793.118 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-24T03:03:13.118Z,1745463793.118 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-24T03:03:13.118Z,1745463793.118 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-04-24T03:03:13.119Z,1745463793.119 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-04-24T03:03:13.119Z,1745463793.119 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-04-24T03:03:13.119Z,1745463793.119 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-04-24T03:03:13.119Z,1745463793.119 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-04-24T03:03:13.119Z,1745463793.119 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-04-24T03:03:13.119Z,1745463793.119 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-04-24T03:03:13.119Z,1745463793.119 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-24T03:03:13.119Z,1745463793.119 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-04-24T03:03:13.119Z,1745463793.119 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-04-24T03:03:13.119Z,1745463793.119 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-04-24T03:03:13.124Z,1745463793.124 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-04-24T03:03:13.124Z,1745463793.124 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-24T03:03:13.124Z,1745463793.124 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-04-24T03:03:13.124Z,1745463793.124 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-24T03:03:13.124Z,1745463793.124 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-24T03:03:13.124Z,1745463793.124 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-04-24T03:03:13.124Z,1745463793.124 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-24T03:03:13.125Z,1745463793.125 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-24T03:03:13.125Z,1745463793.125 [keepstation:DiveCmd:StationKeep] Stopped 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:TransitToStation] Stopped 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-04-24T03:03:13.126Z,1745463793.126 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-04-24T03:03:13.127Z,1745463793.127 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-04-24T03:03:13.127Z,1745463793.127 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-04-24T03:03:13.127Z,1745463793.127 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-04-24T03:03:13.127Z,1745463793.127 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-04-24T03:03:13.127Z,1745463793.127 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-04-24T03:03:13.132Z,1745463793.132 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-24T03:03:13.132Z,1745463793.132 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-24T03:03:43.396Z,1745463823.396 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-24T03:05:41.400Z,1745463941.400 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:05:57.244Z,1745463957.244 [Radio_Surface](INFO): Powering down 2025-04-24T03:06:12.529Z,1745463972.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:06:13.532Z,1745463973.532 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-24T03:07:04.185Z,1745464024.185 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-04-24T03:07:04.185Z,1745464024.185 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-04-24T03:07:04.186Z,1745464024.186 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-24T03:07:04.186Z,1745464024.186 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-04-24T03:07:04.599Z,1745464024.599 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-04-24T03:07:04.599Z,1745464024.599 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-24T03:07:04.599Z,1745464024.599 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-04-24T03:07:04.599Z,1745464024.599 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-24T03:07:04.599Z,1745464024.599 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-04-24T03:07:04.599Z,1745464024.599 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T03:07:04.599Z,1745464024.599 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-04-24T03:07:04.991Z,1745464024.991 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-04-24T03:07:04.991Z,1745464024.991 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-04-24T03:07:04.991Z,1745464024.991 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-04-24T03:07:04.991Z,1745464024.991 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-04-24T03:07:04.991Z,1745464024.991 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-04-24T03:07:04.991Z,1745464024.991 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-24T03:07:04.991Z,1745464024.991 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-04-24T03:07:04.991Z,1745464024.991 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-04-24T03:07:04.992Z,1745464024.992 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-04-24T03:07:04.992Z,1745464024.992 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-04-24T03:07:04.992Z,1745464024.992 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-04-24T03:07:04.992Z,1745464024.992 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-04-24T03:07:04.992Z,1745464024.992 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-04-24T03:07:04.993Z,1745464024.993 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-04-24T03:07:04.993Z,1745464024.993 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-04-24T03:07:04.993Z,1745464024.993 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-04-24T03:07:04.993Z,1745464024.993 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-04-24T03:07:04.993Z,1745464024.993 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T03:07:04.993Z,1745464024.993 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-04-24T03:07:04.993Z,1745464024.993 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-24T03:07:04.994Z,1745464024.994 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-04-24T03:07:04.994Z,1745464024.994 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-04-24T03:07:04.994Z,1745464024.994 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-04-24T03:07:04.994Z,1745464024.994 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T03:07:04.994Z,1745464024.994 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-04-24T03:07:05.394Z,1745464025.394 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position -0.45 mm (5 active estimators). 2025-04-24T03:07:09.838Z,1745464029.838 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-04-24T03:07:09.838Z,1745464029.838 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-04-24T03:07:09.839Z,1745464029.839 [keepstation:TransitToStation:C] Running Loop=1 2025-04-24T03:07:10.239Z,1745464030.239 [keepstation:TransitToStation:C] Stopped 2025-04-24T03:07:10.239Z,1745464030.239 [keepstation:TransitToStation:D] Running Loop=1 2025-04-24T03:07:10.240Z,1745464030.240 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-24T03:07:10.240Z,1745464030.240 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-04-24T03:07:10.240Z,1745464030.240 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-24T03:07:10.240Z,1745464030.240 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-24T03:07:10.240Z,1745464030.240 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-04-24T03:07:10.240Z,1745464030.240 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-24T03:07:10.241Z,1745464030.241 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-24T03:07:10.241Z,1745464030.241 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-24T03:07:10.241Z,1745464030.241 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-24T03:07:10.241Z,1745464030.241 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-24T03:07:10.242Z,1745464030.242 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-24T03:07:10.242Z,1745464030.242 [keepstation:DiveCmd:TransitToStation] Stopped 2025-04-24T03:07:10.242Z,1745464030.242 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-04-24T03:07:10.242Z,1745464030.242 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-04-24T03:07:10.242Z,1745464030.242 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-04-24T03:07:10.243Z,1745464030.243 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-04-24T03:07:10.243Z,1745464030.243 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-24T03:07:10.243Z,1745464030.243 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-04-24T03:07:10.243Z,1745464030.243 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-04-24T03:07:10.243Z,1745464030.243 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-04-24T03:07:10.650Z,1745464030.650 [keepstation:TransitToStation:D] Stopped 2025-04-24T03:07:10.650Z,1745464030.650 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-04-24T03:07:10.650Z,1745464030.650 [keepstation:TransitToStation] Stopped 2025-04-24T03:07:10.650Z,1745464030.650 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-04-24T03:07:10.650Z,1745464030.650 [keepstation:TransitToStation:Dive] Stopped 2025-04-24T03:07:10.650Z,1745464030.650 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-04-24T03:07:10.650Z,1745464030.650 [keepstation:TransitToStation:Dive:A] Stopped 2025-04-24T03:07:10.651Z,1745464030.651 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-04-24T03:07:10.651Z,1745464030.651 [keepstation:OnStation] Running Loop=1 2025-04-24T03:07:10.651Z,1745464030.651 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-04-24T03:07:11.060Z,1745464031.060 [keepstation:NeedComms] Running Loop=1 2025-04-24T03:07:11.060Z,1745464031.060 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-24T03:07:11.060Z,1745464031.060 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-24T03:07:11.060Z,1745464031.060 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-24T03:07:11.060Z,1745464031.060 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-24T03:07:11.061Z,1745464031.061 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-24T03:07:11.061Z,1745464031.061 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-24T03:07:11.062Z,1745464031.062 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-24T03:07:11.062Z,1745464031.062 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-24T03:07:11.062Z,1745464031.062 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-24T03:07:11.062Z,1745464031.062 [keepstation:NeedComms:A] Running Loop=1 2025-04-24T03:07:11.064Z,1745464031.064 [keepstation:NeedComms:A](INFO): last time_fix was: 1745463784.000000 second since 1970/01/01T00:00:00Z 2025-04-24T03:07:11.064Z,1745464031.064 [keepstation:NeedComms:A] Stopped 2025-04-24T03:07:17.816Z,1745464037.816 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.375244 2025-04-24T03:07:57.555Z,1745464077.555 [keepstation:NeedComms:C] Running Loop=1 2025-04-24T03:07:58.041Z,1745464078.041 [Radio_Surface](INFO): Powering up 2025-04-24T03:08:04.000Z,1745464084.000 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-24T03:08:26.672Z,1745464106.672 [NAL9602](INFO): SBD MO Status=0, MOMSN=20442, MT Status=0, MTMSN=0 2025-04-24T03:08:26.672Z,1745464106.672 [NAL9602](INFO): No messages in MT queue 2025-04-24T03:08:27.874Z,1745464107.874 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030821.00,A,3646.71740,N,12153.82828,W,1.594,175.83,240425,,,A*7E 2025-04-24T03:08:27.886Z,1745464107.886 [NAL9602](INFO): GPS fix at 20250424T030821: (36.778623, -121.897138) 2025-04-24T03:08:27.901Z,1745464107.901 [keepstation:NeedComms:C] Stopped 2025-04-24T03:08:27.901Z,1745464107.901 [keepstation:NeedComms:D] Running Loop=1 2025-04-24T03:08:34.132Z,1745464114.132 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:08:44.275Z,1745464124.275 [NAL9602](INFO): SBD MO Status=1, MOMSN=20443, MT Status=0, MTMSN=0 2025-04-24T03:08:44.332Z,1745464124.332 [NAL9602](INFO): Sent 76 bytes from file Logs/20250424T030000/Courier0003.lzma 2025-04-24T03:08:44.332Z,1745464124.332 [NAL9602](INFO): Packets left to send: 0 2025-04-24T03:08:51.641Z,1745464131.641 [NAL9602](INFO): SBD MO Status=1, MOMSN=20444, MT Status=0, MTMSN=0 2025-04-24T03:08:51.688Z,1745464131.688 [NAL9602](INFO): Sent 192 bytes from file Logs/20250424T030000/Express0004.lzma 2025-04-24T03:08:51.688Z,1745464131.688 [NAL9602](INFO): Packets left to send: 0 2025-04-24T03:09:00.947Z,1745464140.947 [NAL9602](INFO): SBD MO Status=0, MOMSN=20445, MT Status=0, MTMSN=0 2025-04-24T03:09:01.032Z,1745464141.032 [keepstation:NeedComms:D] Stopped 2025-04-24T03:09:01.032Z,1745464141.032 [keepstation:NeedComms:E] Running Loop=1 2025-04-24T03:09:03.347Z,1745464143.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030856.00,A,3646.71243,N,12153.82178,W,0.933,145.44,240425,,,A*7C 2025-04-24T03:09:03.350Z,1745464143.350 [NAL9602](INFO): GPS fix at 20250424T030856: (36.778540, -121.897030) 2025-04-24T03:09:03.363Z,1745464143.363 [keepstation:NeedComms:E] Stopped 2025-04-24T03:09:03.365Z,1745464143.365 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-24T03:09:03.365Z,1745464143.365 [keepstation:NeedComms] Stopped 2025-04-24T03:09:03.365Z,1745464143.365 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-24T03:09:03.365Z,1745464143.365 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-24T03:09:03.365Z,1745464143.365 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-24T03:09:03.773Z,1745464143.773 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-04-24T03:09:03.773Z,1745464143.773 [keepstation:OnStation] Stopped 2025-04-24T03:09:03.773Z,1745464143.773 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-04-24T03:09:03.773Z,1745464143.773 [keepstation:KeepStation] Running Loop=1 2025-04-24T03:09:03.773Z,1745464143.773 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-04-24T03:09:03.774Z,1745464143.774 [keepstation:KeepStation:Dive] Running Loop=1 2025-04-24T03:09:03.774Z,1745464143.774 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-04-24T03:09:03.774Z,1745464143.774 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-04-24T03:09:03.774Z,1745464143.774 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-04-24T03:09:03.774Z,1745464143.774 [keepstation:KeepStation:B.Wait] Running Loop=1 2025-04-24T03:09:03.774Z,1745464143.774 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component. 2025-04-24T03:09:04.163Z,1745464144.163 [keepstation:KeepStation:Dive] Running Loop=1 2025-04-24T03:09:05.264Z,1745464145.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:09:35.261Z,1745464175.261 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-24T03:09:36.393Z,1745464176.393 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:10:07.516Z,1745464207.516 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:10:17.741Z,1745464217.741 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003449 2025-04-24T03:11:08.940Z,1745464268.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:11:40.086Z,1745464300.086 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:11:48.732Z,1745464308.732 [Radio_Surface](INFO): Powering down 2025-04-24T03:12:11.228Z,1745464331.228 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T03:12:12.232Z,1745464332.232 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-24T03:12:52.418Z,1745464372.418 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T03:13:18.500Z,1745464398.500 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.763351 2025-04-24T03:22:52.768Z,1745464972.768 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T03:32:53.126Z,1745465573.126 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T03:42:33.278Z,1745466153.278 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-24T03:42:33.279Z,1745466153.279 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-24T03:42:33.304Z,1745466153.304 [BPC1](INFO): Received data from all battery sticks. 2025-04-24T03:42:41.018Z,1745466161.018 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-24T03:42:45.576Z,1745466165.576 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.897010 CHAN A1 (24V): 0.846495 CHAN A2 (12V): 0.926265 CHAN A3 (5V): 0.558466 CHAN B0 (3.3V): 0.377265 CHAN B1 (3.15aV): 0.489778 CHAN B2 (3.15bV): 0.489206 CHAN B3 (GND): 0.008861 OPEN: 0.007001 Full Scale: +/- 1 mA 2025-04-24T03:42:53.481Z,1745466173.481 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T03:52:53.823Z,1745466773.823 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T04:02:54.162Z,1745467374.162 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T04:12:54.502Z,1745467974.502 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T04:22:54.856Z,1745468574.856 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T04:32:55.203Z,1745469175.203 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T04:42:55.546Z,1745469775.546 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T04:50:11.881Z,1745470211.881 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-24T04:50:11.882Z,1745470211.882 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-24T04:50:11.886Z,1745470211.886 [BPC1](INFO): Received data from all battery sticks. 2025-04-24T04:52:55.898Z,1745470375.898 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T05:02:56.236Z,1745470976.236 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T05:09:03.519Z,1745471343.519 [keepstation:SurfaceComms] Running Loop=1 2025-04-24T05:09:03.519Z,1745471343.519 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-04-24T05:09:03.519Z,1745471343.519 [keepstation:SurfaceComms:A] Running Loop=1 2025-04-24T05:09:03.520Z,1745471343.520 [keepstation:SurfaceComms:A] Stopped 2025-04-24T05:09:03.520Z,1745471343.520 [keepstation:SurfaceComms:B] Running Loop=1 2025-04-24T05:09:03.520Z,1745471343.520 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-04-24T05:09:03.902Z,1745471343.902 [keepstation:KeepStation:Dive] Stopped 2025-04-24T05:09:03.902Z,1745471343.902 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-04-24T05:09:03.902Z,1745471343.902 [keepstation:KeepStation:Dive:A] Stopped 2025-04-24T05:09:03.902Z,1745471343.902 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-04-24T05:09:03.902Z,1745471343.902 [keepstation:DiveCmd:StationKeep] Stopped 2025-04-24T05:09:03.902Z,1745471343.902 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-04-24T05:09:03.903Z,1745471343.903 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-04-24T05:09:03.903Z,1745471343.903 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-04-24T05:09:03.903Z,1745471343.903 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-04-24T05:09:03.903Z,1745471343.903 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-04-24T05:09:03.905Z,1745471343.905 [keepstation:NeedComms] Running Loop=1 2025-04-24T05:09:03.905Z,1745471343.905 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-24T05:09:03.905Z,1745471343.905 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-24T05:09:03.905Z,1745471343.905 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-24T05:09:03.905Z,1745471343.905 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-24T05:09:03.906Z,1745471343.906 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-24T05:09:03.906Z,1745471343.906 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-24T05:09:03.906Z,1745471343.906 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-24T05:09:03.907Z,1745471343.907 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-24T05:09:03.907Z,1745471343.907 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-24T05:09:03.907Z,1745471343.907 [keepstation:NeedComms:A] Running Loop=1 2025-04-24T05:09:03.909Z,1745471343.909 [keepstation:NeedComms:A](INFO): last time_fix was: 1745464136.000000 second since 1970/01/01T00:00:00Z 2025-04-24T05:09:03.909Z,1745471343.909 [keepstation:NeedComms:A] Stopped 2025-04-24T05:10:30.461Z,1745471430.461 [keepstation:NeedComms:C] Running Loop=1 2025-04-24T05:10:30.924Z,1745471430.924 [Radio_Surface](INFO): Powering up 2025-04-24T05:10:36.480Z,1745471436.480 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-24T05:10:51.445Z,1745471451.445 [NAL9602](INFO): SBD MO Status=0, MOMSN=20446, MT Status=0, MTMSN=0 2025-04-24T05:10:51.445Z,1745471451.445 [NAL9602](INFO): No messages in MT queue 2025-04-24T05:11:06.600Z,1745471466.600 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T05:11:08.009Z,1745471468.009 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,051101.00,A,3646.81025,N,12153.90553,W,1.069,233.75,240425,,,A*74 2025-04-24T05:11:08.012Z,1745471468.012 [NAL9602](INFO): GPS fix at 20250424T051101: (36.780171, -121.898426) 2025-04-24T05:11:08.046Z,1745471468.046 [keepstation:NeedComms:C] Stopped 2025-04-24T05:11:08.046Z,1745471468.046 [keepstation:NeedComms:D] Running Loop=1 2025-04-24T05:11:37.725Z,1745471497.725 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T05:11:39.897Z,1745471499.897 [NAL9602](INFO): SBD MO Status=1, MOMSN=20447, MT Status=0, MTMSN=0 2025-04-24T05:11:39.948Z,1745471499.948 [NAL9602](INFO): Sent 207 bytes from file Logs/20250424T030000/Courier0006.lzma 2025-04-24T05:11:39.948Z,1745471499.948 [NAL9602](INFO): Packets left to send: 0 2025-04-24T05:11:56.586Z,1745471516.586 [NAL9602](INFO): SBD MO Status=2, MOMSN=20448, MT Status=2, MTMSN=0 2025-04-24T05:11:56.586Z,1745471516.586 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-24T05:12:08.848Z,1745471528.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T05:12:20.622Z,1745471540.622 [NAL9602](INFO): SBD MO Status=2, MOMSN=20448, MT Status=2, MTMSN=0 2025-04-24T05:12:20.622Z,1745471540.622 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-24T05:12:39.988Z,1745471559.988 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T05:12:48.673Z,1745471568.673 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003447 2025-04-24T05:12:55.637Z,1745471575.637 [NAL9602](INFO): SBD MO Status=1, MOMSN=20448, MT Status=0, MTMSN=0 2025-04-24T05:12:55.692Z,1745471575.692 [NAL9602](INFO): Sent 332 bytes from file Logs/20250424T030000/Express0007.lzma 2025-04-24T05:12:55.692Z,1745471575.692 [NAL9602](INFO): Packets left to send: 2 2025-04-24T05:12:56.093Z,1745471576.093 [DataOverHttps](INFO): Sending 689 bytes from file Logs/20250424T030000/Express0007.lzma 2025-04-24T05:12:56.449Z,1745471576.449 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T05:12:57.094Z,1745471577.094 [DataOverHttps](INFO): Moved sent file to Logs/20250424T030000/Express0007.lzma.bak 2025-04-24T05:12:57.094Z,1745471577.094 [DataOverHttps](INFO): SBD MOMSN=24722357 2025-04-24T05:12:59.302Z,1745471579.302 [keepstation:NeedComms:D] Stopped 2025-04-24T05:12:59.302Z,1745471579.302 [keepstation:NeedComms:E] Running Loop=1 2025-04-24T05:13:01.299Z,1745471581.299 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,051254.00,A,3646.79462,N,12153.89307,W,0.758,72.37,240425,,,A*4D 2025-04-24T05:13:01.301Z,1745471581.301 [NAL9602](INFO): GPS fix at 20250424T051254: (36.779910, -121.898218) 2025-04-24T05:13:01.323Z,1745471581.323 [keepstation:NeedComms:E] Stopped 2025-04-24T05:13:01.328Z,1745471581.328 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-24T05:13:01.328Z,1745471581.328 [keepstation:NeedComms] Stopped 2025-04-24T05:13:01.328Z,1745471581.328 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-24T05:13:01.328Z,1745471581.328 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-24T05:13:01.329Z,1745471581.329 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-24T05:13:01.739Z,1745471581.739 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-04-24T05:13:01.739Z,1745471581.739 [keepstation:SurfaceComms:B] Stopped 2025-04-24T05:13:01.739Z,1745471581.739 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-04-24T05:13:01.739Z,1745471581.739 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-04-24T05:13:01.739Z,1745471581.739 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-04-24T05:13:01.739Z,1745471581.739 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-04-24T05:13:02.131Z,1745471582.131 [keepstation:SurfaceComms:setTransit] Stopped 2025-04-24T05:13:02.131Z,1745471582.131 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-04-24T05:13:02.131Z,1745471582.131 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-04-24T05:13:02.131Z,1745471582.131 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-04-24T05:13:02.131Z,1745471582.131 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-04-24T05:13:02.131Z,1745471582.131 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-04-24T05:13:02.550Z,1745471582.550 [keepstation:SurfaceComms:setStation] Stopped 2025-04-24T05:13:02.550Z,1745471582.550 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-04-24T05:13:02.550Z,1745471582.550 [keepstation:SurfaceComms:setStation:A] Stopped 2025-04-24T05:13:02.550Z,1745471582.550 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-04-24T05:13:02.550Z,1745471582.550 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-04-24T05:13:02.550Z,1745471582.550 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-04-24T05:13:02.950Z,1745471582.950 [keepstation:SurfaceComms:setSink:A] Stopped 2025-04-24T05:13:02.950Z,1745471582.950 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-04-24T05:13:02.950Z,1745471582.950 [keepstation:SurfaceComms:setSink] Stopped 2025-04-24T05:13:02.950Z,1745471582.950 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-04-24T05:13:02.951Z,1745471582.951 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-04-24T05:13:02.951Z,1745471582.951 [keepstation:SurfaceComms] Stopped 2025-04-24T05:13:02.951Z,1745471582.951 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-04-24T05:13:03.325Z,1745471583.325 [keepstation:KeepStation:Dive] Running Loop=1 2025-04-24T05:13:03.325Z,1745471583.325 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-04-24T05:13:03.325Z,1745471583.325 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-04-24T05:13:03.325Z,1745471583.325 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-04-24T05:13:03.325Z,1745471583.325 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-04-24T05:13:03.325Z,1745471583.325 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-04-24T05:13:03.326Z,1745471583.326 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-04-24T05:13:03.326Z,1745471583.326 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T05:13:03.326Z,1745471583.326 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-24T05:13:03.326Z,1745471583.326 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-24T05:13:03.326Z,1745471583.326 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-24T05:13:03.326Z,1745471583.326 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-04-24T05:13:03.326Z,1745471583.326 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-04-24T05:13:03.327Z,1745471583.327 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-04-24T05:13:03.327Z,1745471583.327 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-04-24T05:13:03.327Z,1745471583.327 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-04-24T05:13:03.327Z,1745471583.327 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-04-24T05:13:33.298Z,1745471613.298 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-24T05:15:09.000Z,1745471709.000 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T05:15:40.124Z,1745471740.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T05:15:42.412Z,1745471742.412 [Radio_Surface](INFO): Powering down 2025-04-24T05:16:11.248Z,1745471771.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T05:16:12.252Z,1745471772.252 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-24T05:16:36.323Z,1745471796.323 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-04-24T05:16:36.323Z,1745471796.323 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-04-24T05:16:36.323Z,1745471796.323 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-24T05:16:36.323Z,1745471796.323 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-04-24T05:16:36.730Z,1745471796.730 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-04-24T05:16:36.730Z,1745471796.730 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-24T05:16:36.730Z,1745471796.730 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-04-24T05:16:36.730Z,1745471796.730 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-04-24T05:16:36.731Z,1745471796.731 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T05:16:36.731Z,1745471796.731 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-04-24T05:16:37.132Z,1745471797.132 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-04-24T05:16:37.133Z,1745471797.133 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T05:16:37.133Z,1745471797.133 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-04-24T05:16:37.133Z,1745471797.133 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T05:16:37.133Z,1745471797.133 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T05:16:37.133Z,1745471797.133 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-04-24T05:16:37.133Z,1745471797.133 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T05:16:37.134Z,1745471797.134 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-04-24T05:16:37.134Z,1745471797.134 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-24T05:16:37.134Z,1745471797.134 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-04-24T05:16:37.134Z,1745471797.134 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-04-24T05:16:37.134Z,1745471797.134 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-04-24T05:16:37.545Z,1745471797.545 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-24T05:16:37.545Z,1745471797.545 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-04-24T05:16:37.545Z,1745471797.545 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-24T05:16:37.545Z,1745471797.545 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-24T05:16:37.545Z,1745471797.545 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-04-24T05:16:37.545Z,1745471797.545 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-24T05:16:37.545Z,1745471797.545 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-24T05:17:16.528Z,1745471836.528 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.770737 2025-04-24T05:22:56.482Z,1745472176.482 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T05:32:56.822Z,1745472776.822 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T05:42:41.061Z,1745473361.061 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-24T05:42:45.492Z,1745473365.492 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.897381 CHAN A1 (24V): 0.845100 CHAN A2 (12V): 0.925547 CHAN A3 (5V): 0.553679 CHAN B0 (3.3V): 0.373835 CHAN B1 (3.15aV): 0.484186 CHAN B2 (3.15bV): 0.483560 CHAN B3 (GND): 0.009993 OPEN: 0.006613 Full Scale: +/- 1 mA 2025-04-24T05:42:57.176Z,1745473377.176 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T05:52:57.523Z,1745473977.523 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T05:57:47.598Z,1745474267.598 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-24T05:57:47.598Z,1745474267.598 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-24T05:57:47.602Z,1745474267.602 [BPC1](INFO): Received data from all battery sticks. 2025-04-24T06:02:57.867Z,1745474577.867 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T06:12:58.227Z,1745475178.227 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T06:22:58.582Z,1745475778.582 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T06:32:58.926Z,1745476378.926 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T06:42:59.294Z,1745476979.294 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T07:05:25.423Z,1745478325.423 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-24T07:05:25.425Z,1745478325.425 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-24T07:05:25.427Z,1745478325.427 [BPC1](INFO): Received data from all battery sticks. 2025-04-24T07:13:01.544Z,1745478781.544 [keepstation:SurfaceComms] Running Loop=1 2025-04-24T07:13:01.545Z,1745478781.545 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-04-24T07:13:01.545Z,1745478781.545 [keepstation:SurfaceComms:A] Running Loop=1 2025-04-24T07:13:01.545Z,1745478781.545 [keepstation:SurfaceComms:A] Stopped 2025-04-24T07:13:01.545Z,1745478781.545 [keepstation:SurfaceComms:B] Running Loop=1 2025-04-24T07:13:01.545Z,1745478781.545 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-04-24T07:13:01.547Z,1745478781.547 [keepstation:NeedComms] Running Loop=1 2025-04-24T07:13:01.547Z,1745478781.547 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-24T07:13:01.547Z,1745478781.547 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-24T07:13:01.547Z,1745478781.547 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-24T07:13:01.548Z,1745478781.548 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-24T07:13:01.548Z,1745478781.548 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-24T07:13:01.549Z,1745478781.549 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-24T07:13:01.549Z,1745478781.549 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-24T07:13:01.549Z,1745478781.549 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-24T07:13:01.550Z,1745478781.550 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-24T07:13:01.550Z,1745478781.550 [keepstation:NeedComms:A] Running Loop=1 2025-04-24T07:13:01.551Z,1745478781.551 [keepstation:NeedComms:A](INFO): last time_fix was: 1745471574.000000 second since 1970/01/01T00:00:00Z 2025-04-24T07:13:01.551Z,1745478781.551 [keepstation:NeedComms:A] Stopped 2025-04-24T07:13:01.951Z,1745478781.951 [keepstation:KeepStation:Dive] Stopped 2025-04-24T07:13:01.951Z,1745478781.951 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-04-24T07:13:01.951Z,1745478781.951 [keepstation:KeepStation:Dive:A] Stopped 2025-04-24T07:13:01.951Z,1745478781.951 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-04-24T07:13:01.952Z,1745478781.952 [keepstation:DiveCmd:StationKeep] Stopped 2025-04-24T07:13:01.952Z,1745478781.952 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-04-24T07:13:01.952Z,1745478781.952 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-04-24T07:13:01.952Z,1745478781.952 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-04-24T07:13:01.952Z,1745478781.952 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-04-24T07:13:01.952Z,1745478781.952 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-04-24T07:14:36.997Z,1745478876.997 [keepstation:NeedComms:C] Running Loop=1 2025-04-24T07:14:37.488Z,1745478877.488 [Radio_Surface](INFO): Powering up 2025-04-24T07:14:43.737Z,1745478883.737 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-24T07:15:13.864Z,1745478913.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T07:15:14.157Z,1745478914.157 [NAL9602](INFO): SBD MO Status=0, MOMSN=20449, MT Status=0, MTMSN=0 2025-04-24T07:15:14.157Z,1745478914.157 [NAL9602](INFO): No messages in MT queue 2025-04-24T07:15:15.377Z,1745478915.377 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,071508.00,A,3647.11754,N,12153.60385,W,0.505,0.00,240425,,,A*7E 2025-04-24T07:15:15.379Z,1745478915.379 [NAL9602](INFO): GPS fix at 20250424T071508: (36.785292, -121.893398) 2025-04-24T07:15:15.392Z,1745478915.392 [keepstation:NeedComms:C] Stopped 2025-04-24T07:15:15.393Z,1745478915.393 [keepstation:NeedComms:D] Running Loop=1 2025-04-24T07:15:44.992Z,1745478944.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T07:15:46.649Z,1745478946.649 [NAL9602](INFO): SBD MO Status=1, MOMSN=20450, MT Status=0, MTMSN=0 2025-04-24T07:15:46.696Z,1745478946.696 [NAL9602](INFO): Sent 90 bytes from file Logs/20250424T030000/Courier0009.lzma 2025-04-24T07:15:46.696Z,1745478946.696 [NAL9602](INFO): Packets left to send: 0 2025-04-24T07:15:58.561Z,1745478958.561 [NAL9602](INFO): SBD MO Status=1, MOMSN=20451, MT Status=0, MTMSN=0 2025-04-24T07:15:58.616Z,1745478958.616 [NAL9602](INFO): Sent 332 bytes from file Logs/20250424T030000/Express0010.lzma 2025-04-24T07:15:58.616Z,1745478958.616 [NAL9602](INFO): Packets left to send: 2 2025-04-24T07:16:12.897Z,1745478972.897 [NAL9602](INFO): SBD MO Status=2, MOMSN=20452, MT Status=2, MTMSN=0 2025-04-24T07:16:12.897Z,1745478972.897 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-24T07:16:16.116Z,1745478976.116 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T07:16:33.651Z,1745478993.651 [NAL9602](INFO): SBD MO Status=1, MOMSN=20452, MT Status=0, MTMSN=0 2025-04-24T07:16:33.708Z,1745478993.708 [NAL9602](INFO): Sent 332 bytes from file Logs/20250424T030000/Express0010.lzma 2025-04-24T07:16:33.708Z,1745478993.708 [NAL9602](INFO): Packets left to send: 1 2025-04-24T07:16:44.275Z,1745479004.275 [NAL9602](INFO): SBD MO Status=1, MOMSN=20453, MT Status=0, MTMSN=0 2025-04-24T07:16:44.328Z,1745479004.328 [NAL9602](INFO): Sent 2 bytes from file Logs/20250424T030000/Express0010.lzma 2025-04-24T07:16:44.328Z,1745479004.328 [NAL9602](INFO): Packets left to send: 0 2025-04-24T07:16:47.240Z,1745479007.240 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T07:16:54.840Z,1745479014.840 [NAL9602](INFO): SBD MO Status=0, MOMSN=20454, MT Status=0, MTMSN=0 2025-04-24T07:16:54.930Z,1745479014.930 [keepstation:NeedComms:D] Stopped 2025-04-24T07:16:54.930Z,1745479014.930 [keepstation:NeedComms:E] Running Loop=1 2025-04-24T07:16:55.841Z,1745479015.841 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003446 2025-04-24T07:16:57.269Z,1745479017.269 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,071650.00,A,3647.10531,N,12153.59240,W,0.467,152.12,240425,,,A*72 2025-04-24T07:16:57.271Z,1745479017.271 [NAL9602](INFO): GPS fix at 20250424T071650: (36.785089, -121.893207) 2025-04-24T07:16:57.284Z,1745479017.284 [keepstation:NeedComms:E] Stopped 2025-04-24T07:16:57.285Z,1745479017.285 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-24T07:16:57.285Z,1745479017.285 [keepstation:NeedComms] Stopped 2025-04-24T07:16:57.285Z,1745479017.285 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-24T07:16:57.285Z,1745479017.285 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-24T07:16:57.285Z,1745479017.285 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-24T07:16:57.707Z,1745479017.707 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-04-24T07:16:57.707Z,1745479017.707 [keepstation:SurfaceComms:B] Stopped 2025-04-24T07:16:57.707Z,1745479017.707 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-04-24T07:16:57.707Z,1745479017.707 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-04-24T07:16:57.708Z,1745479017.708 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-04-24T07:16:57.708Z,1745479017.708 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-04-24T07:16:58.075Z,1745479018.075 [keepstation:SurfaceComms:setTransit] Stopped 2025-04-24T07:16:58.075Z,1745479018.075 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-04-24T07:16:58.075Z,1745479018.075 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-04-24T07:16:58.075Z,1745479018.075 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-04-24T07:16:58.075Z,1745479018.075 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-04-24T07:16:58.075Z,1745479018.075 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-04-24T07:16:58.489Z,1745479018.489 [keepstation:SurfaceComms:setStation] Stopped 2025-04-24T07:16:58.489Z,1745479018.489 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-04-24T07:16:58.489Z,1745479018.489 [keepstation:SurfaceComms:setStation:A] Stopped 2025-04-24T07:16:58.489Z,1745479018.489 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-04-24T07:16:58.489Z,1745479018.489 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-04-24T07:16:58.489Z,1745479018.489 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-04-24T07:16:58.904Z,1745479018.904 [keepstation:SurfaceComms:setSink:A] Stopped 2025-04-24T07:16:58.908Z,1745479018.908 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-04-24T07:16:58.909Z,1745479018.909 [keepstation:SurfaceComms:setSink] Stopped 2025-04-24T07:16:58.909Z,1745479018.909 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-04-24T07:16:58.909Z,1745479018.909 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-04-24T07:16:58.909Z,1745479018.909 [keepstation:SurfaceComms] Stopped 2025-04-24T07:16:58.909Z,1745479018.909 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-04-24T07:16:59.290Z,1745479019.290 [keepstation:KeepStation:Dive] Running Loop=1 2025-04-24T07:16:59.291Z,1745479019.291 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-04-24T07:16:59.291Z,1745479019.291 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-04-24T07:16:59.291Z,1745479019.291 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-04-24T07:16:59.291Z,1745479019.291 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-04-24T07:16:59.291Z,1745479019.291 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-04-24T07:16:59.291Z,1745479019.291 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-04-24T07:16:59.292Z,1745479019.292 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T07:16:59.292Z,1745479019.292 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-24T07:16:59.292Z,1745479019.292 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-24T07:16:59.292Z,1745479019.292 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-24T07:16:59.292Z,1745479019.292 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-04-24T07:16:59.292Z,1745479019.292 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-04-24T07:16:59.293Z,1745479019.293 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-04-24T07:16:59.293Z,1745479019.293 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-04-24T07:16:59.293Z,1745479019.293 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-04-24T07:16:59.293Z,1745479019.293 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-04-24T07:17:29.618Z,1745479049.618 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-24T07:24:27.480Z,1745479467.480 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T07:24:58.605Z,1745479498.605 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T07:25:29.728Z,1745479529.728 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T07:25:41.380Z,1745479541.380 [Radio_Surface](INFO): Powering down 2025-04-24T07:26:00.856Z,1745479560.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T07:26:01.861Z,1745479561.861 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-24T07:26:34.992Z,1745479594.992 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.723358 2025-04-24T07:26:59.654Z,1745479619.654 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-04-24T07:16:59.3Z 2025-04-24T07:26:59.654Z,1745479619.654 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2025-04-24T07:26:59.654Z,1745479619.654 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-04-24T07:26:59.654Z,1745479619.654 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2025-04-24T07:26:59.655Z,1745479619.655 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 9.440679 m 2025-04-24T07:26:59.655Z,1745479619.655 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2025-04-24T07:26:59.655Z,1745479619.655 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-04-24T07:26:59.655Z,1745479619.655 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-04-24T07:26:59.655Z,1745479619.655 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-04-24T07:26:59.655Z,1745479619.655 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-24T07:26:59.656Z,1745479619.656 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-04-24T07:27:00.085Z,1745479620.085 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-04-24T07:27:00.085Z,1745479620.085 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-24T07:27:00.085Z,1745479620.085 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-04-24T07:27:00.085Z,1745479620.085 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-04-24T07:27:00.085Z,1745479620.085 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T07:27:00.085Z,1745479620.085 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-04-24T07:27:00.459Z,1745479620.459 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-04-24T07:27:00.459Z,1745479620.459 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T07:27:00.460Z,1745479620.460 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-04-24T07:27:00.460Z,1745479620.460 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T07:27:00.460Z,1745479620.460 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T07:27:00.460Z,1745479620.460 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-04-24T07:27:00.461Z,1745479620.461 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T07:27:00.461Z,1745479620.461 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-04-24T07:27:00.461Z,1745479620.461 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-24T07:27:00.461Z,1745479620.461 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-04-24T07:27:00.461Z,1745479620.461 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-04-24T07:27:00.461Z,1745479620.461 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-04-24T07:27:00.871Z,1745479620.871 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-24T07:27:00.871Z,1745479620.871 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-04-24T07:27:00.872Z,1745479620.872 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-24T07:27:00.872Z,1745479620.872 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-24T07:27:00.872Z,1745479620.872 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-04-24T07:27:00.872Z,1745479620.872 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-24T07:27:00.872Z,1745479620.872 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-24T07:42:41.390Z,1745480561.390 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-24T07:42:45.880Z,1745480565.880 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.895267 CHAN A1 (24V): 0.843176 CHAN A2 (12V): 0.923905 CHAN A3 (5V): 0.545762 CHAN B0 (3.3V): 0.367738 CHAN B1 (3.15aV): 0.474648 CHAN B2 (3.15bV): 0.474133 CHAN B3 (GND): 0.006957 OPEN: 0.026431 Full Scale: +/- 1 mA 2025-04-24T08:13:04.242Z,1745482384.242 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-24T08:13:04.243Z,1745482384.243 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-24T08:13:04.252Z,1745482384.252 [BPC1](INFO): Received data from all battery sticks. 2025-04-24T09:16:57.405Z,1745486217.405 [keepstation:SurfaceComms] Running Loop=1 2025-04-24T09:16:57.406Z,1745486217.406 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-04-24T09:16:57.406Z,1745486217.406 [keepstation:SurfaceComms:A] Running Loop=1 2025-04-24T09:16:57.406Z,1745486217.406 [keepstation:SurfaceComms:A] Stopped 2025-04-24T09:16:57.406Z,1745486217.406 [keepstation:SurfaceComms:B] Running Loop=1 2025-04-24T09:16:57.407Z,1745486217.407 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-04-24T09:16:57.425Z,1745486217.425 [keepstation:NeedComms] Running Loop=1 2025-04-24T09:16:57.425Z,1745486217.425 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-24T09:16:57.425Z,1745486217.425 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-24T09:16:57.425Z,1745486217.425 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-24T09:16:57.426Z,1745486217.426 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-24T09:16:57.426Z,1745486217.426 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-24T09:16:57.426Z,1745486217.426 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-24T09:16:57.427Z,1745486217.427 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-24T09:16:57.427Z,1745486217.427 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-24T09:16:57.427Z,1745486217.427 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-24T09:16:57.432Z,1745486217.432 [keepstation:NeedComms:A] Running Loop=1 2025-04-24T09:16:57.434Z,1745486217.434 [keepstation:NeedComms:A](INFO): last time_fix was: 1745479010.000000 second since 1970/01/01T00:00:00Z 2025-04-24T09:16:57.434Z,1745486217.434 [keepstation:NeedComms:A] Stopped 2025-04-24T09:16:57.870Z,1745486217.870 [keepstation:KeepStation:Dive] Stopped 2025-04-24T09:16:57.870Z,1745486217.870 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-04-24T09:16:57.870Z,1745486217.870 [keepstation:KeepStation:Dive:A] Stopped 2025-04-24T09:16:57.870Z,1745486217.870 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-04-24T09:16:57.871Z,1745486217.871 [keepstation:DiveCmd:StationKeep] Stopped 2025-04-24T09:16:57.871Z,1745486217.871 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-04-24T09:16:57.871Z,1745486217.871 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-04-24T09:16:57.871Z,1745486217.871 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-04-24T09:16:57.871Z,1745486217.871 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-04-24T09:16:57.871Z,1745486217.871 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-04-24T09:18:12.670Z,1745486292.670 [keepstation:NeedComms:C] Running Loop=1 2025-04-24T09:18:13.117Z,1745486293.117 [Radio_Surface](INFO): Powering up 2025-04-24T09:18:18.896Z,1745486298.896 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-24T09:18:29.624Z,1745486309.624 [NAL9602](INFO): SBD MO Status=0, MOMSN=20455, MT Status=0, MTMSN=0 2025-04-24T09:18:29.624Z,1745486309.624 [NAL9602](INFO): No messages in MT queue 2025-04-24T09:18:30.831Z,1745486310.831 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,091824.00,A,3647.42471,N,12153.20955,W,1.030,318.92,240425,,,A*71 2025-04-24T09:18:30.834Z,1745486310.834 [NAL9602](INFO): GPS fix at 20250424T091824: (36.790412, -121.886826) 2025-04-24T09:18:30.847Z,1745486310.847 [keepstation:NeedComms:C] Stopped 2025-04-24T09:18:30.847Z,1745486310.847 [keepstation:NeedComms:D] Running Loop=1 2025-04-24T09:18:49.016Z,1745486329.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T09:19:07.086Z,1745486347.086 [NAL9602](INFO): SBD MO Status=1, MOMSN=20456, MT Status=0, MTMSN=0 2025-04-24T09:19:07.140Z,1745486347.140 [NAL9602](INFO): Sent 90 bytes from file Logs/20250424T030000/Courier0012.lzma 2025-04-24T09:19:07.140Z,1745486347.140 [NAL9602](INFO): Packets left to send: 0 2025-04-24T09:19:19.779Z,1745486359.779 [NAL9602](INFO): SBD MO Status=1, MOMSN=20457, MT Status=0, MTMSN=0 2025-04-24T09:19:19.832Z,1745486359.832 [NAL9602](INFO): Sent 332 bytes from file Logs/20250424T030000/Express0013.lzma 2025-04-24T09:19:19.832Z,1745486359.832 [NAL9602](INFO): Packets left to send: 2 2025-04-24T09:19:20.148Z,1745486360.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T09:19:37.345Z,1745486377.345 [NAL9602](INFO): SBD MO Status=2, MOMSN=20458, MT Status=2, MTMSN=0 2025-04-24T09:19:37.345Z,1745486377.345 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-24T09:19:51.272Z,1745486391.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T09:20:02.999Z,1745486402.999 [NAL9602](INFO): SBD MO Status=2, MOMSN=20458, MT Status=2, MTMSN=0 2025-04-24T09:20:02.000Z,1745486403.000 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-24T09:20:22.420Z,1745486422.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T09:20:31.529Z,1745486431.529 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.007981 2025-04-24T09:20:38.337Z,1745486438.337 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-24T09:20:38.338Z,1745486438.338 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6. 2025-04-24T09:20:38.349Z,1745486438.349 [BPC1](INFO): Received data from all battery sticks. 2025-04-24T09:20:39.490Z,1745486439.490 [DataOverHttps](INFO): Sending 749 bytes from file Logs/20250424T030000/Express0013.lzma 2025-04-24T09:20:40.490Z,1745486440.490 [DataOverHttps](INFO): Moved sent file to Logs/20250424T030000/Express0013.lzma.bak 2025-04-24T09:20:40.490Z,1745486440.490 [DataOverHttps](INFO): SBD MOMSN=24722721 2025-04-24T09:20:43.183Z,1745486443.183 [keepstation:NeedComms:D] Stopped 2025-04-24T09:20:43.183Z,1745486443.183 [keepstation:NeedComms:E] Running Loop=1 2025-04-24T09:20:50.025Z,1745486450.025 [NAL9602](INFO): SBD MO Status=2, MOMSN=20458, MT Status=2, MTMSN=0 2025-04-24T09:20:50.025Z,1745486450.025 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-24T09:20:51.239Z,1745486451.239 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,092044.00,A,3647.42125,N,12153.20660,W,0.194,104.59,240425,,,A*77 2025-04-24T09:20:51.241Z,1745486451.241 [NAL9602](INFO): GPS fix at 20250424T092044: (36.790354, -121.886777) 2025-04-24T09:20:51.254Z,1745486451.254 [keepstation:NeedComms:E] Stopped 2025-04-24T09:20:51.255Z,1745486451.255 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-24T09:20:51.255Z,1745486451.255 [keepstation:NeedComms] Stopped 2025-04-24T09:20:51.256Z,1745486451.256 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-24T09:20:51.256Z,1745486451.256 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-24T09:20:51.256Z,1745486451.256 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-24T09:20:51.669Z,1745486451.669 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-04-24T09:20:51.669Z,1745486451.669 [keepstation:SurfaceComms:B] Stopped 2025-04-24T09:20:51.669Z,1745486451.669 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-04-24T09:20:51.669Z,1745486451.669 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-04-24T09:20:51.669Z,1745486451.669 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-04-24T09:20:51.669Z,1745486451.669 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-04-24T09:20:52.046Z,1745486452.046 [keepstation:SurfaceComms:setTransit] Stopped 2025-04-24T09:20:52.046Z,1745486452.046 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-04-24T09:20:52.046Z,1745486452.046 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-04-24T09:20:52.046Z,1745486452.046 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-04-24T09:20:52.047Z,1745486452.047 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-04-24T09:20:52.047Z,1745486452.047 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-04-24T09:20:52.462Z,1745486452.462 [keepstation:SurfaceComms:setStation] Stopped 2025-04-24T09:20:52.462Z,1745486452.462 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-04-24T09:20:52.462Z,1745486452.462 [keepstation:SurfaceComms:setStation:A] Stopped 2025-04-24T09:20:52.462Z,1745486452.462 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-04-24T09:20:52.462Z,1745486452.462 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-04-24T09:20:52.462Z,1745486452.462 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-04-24T09:20:52.891Z,1745486452.891 [keepstation:SurfaceComms:setSink:A] Stopped 2025-04-24T09:20:52.891Z,1745486452.891 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-04-24T09:20:52.891Z,1745486452.891 [keepstation:SurfaceComms:setSink] Stopped 2025-04-24T09:20:52.891Z,1745486452.891 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-04-24T09:20:52.891Z,1745486452.891 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-04-24T09:20:52.891Z,1745486452.891 [keepstation:SurfaceComms] Stopped 2025-04-24T09:20:52.891Z,1745486452.891 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-04-24T09:20:53.296Z,1745486453.296 [keepstation:KeepStation:Dive] Running Loop=1 2025-04-24T09:20:53.296Z,1745486453.296 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-04-24T09:20:53.296Z,1745486453.296 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-04-24T09:20:53.297Z,1745486453.297 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-04-24T09:20:53.297Z,1745486453.297 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-04-24T09:20:53.297Z,1745486453.297 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-04-24T09:20:53.297Z,1745486453.297 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-04-24T09:20:53.297Z,1745486453.297 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T09:20:53.297Z,1745486453.297 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-24T09:20:53.298Z,1745486453.298 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-24T09:20:53.298Z,1745486453.298 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-24T09:20:53.298Z,1745486453.298 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-04-24T09:20:53.298Z,1745486453.298 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-04-24T09:20:53.298Z,1745486453.298 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-04-24T09:20:53.298Z,1745486453.298 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-04-24T09:20:53.299Z,1745486453.299 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-04-24T09:20:53.299Z,1745486453.299 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-04-24T09:21:23.609Z,1745486483.609 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-24T09:24:56.492Z,1745486696.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T09:25:07.732Z,1745486707.732 [Radio_Surface](INFO): Powering down 2025-04-24T09:25:27.628Z,1745486727.628 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T09:25:28.632Z,1745486728.632 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-24T09:26:31.891Z,1745486791.891 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.120850 2025-04-24T09:27:05.811Z,1745486825.811 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-04-24T09:27:05.811Z,1745486825.811 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-04-24T09:27:05.811Z,1745486825.811 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-24T09:27:05.811Z,1745486825.811 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-04-24T09:27:06.212Z,1745486826.212 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-04-24T09:27:06.212Z,1745486826.212 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-24T09:27:06.212Z,1745486826.212 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-04-24T09:27:06.213Z,1745486826.213 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-04-24T09:27:06.213Z,1745486826.213 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T09:27:06.213Z,1745486826.213 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-04-24T09:27:06.623Z,1745486826.623 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-04-24T09:27:06.623Z,1745486826.623 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T09:27:06.623Z,1745486826.623 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-04-24T09:27:06.623Z,1745486826.623 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T09:27:06.625Z,1745486826.625 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T09:27:06.625Z,1745486826.625 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-04-24T09:27:06.626Z,1745486826.626 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T09:27:06.626Z,1745486826.626 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-04-24T09:27:06.626Z,1745486826.626 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-24T09:27:06.627Z,1745486826.627 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-04-24T09:27:06.627Z,1745486826.627 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-04-24T09:27:06.628Z,1745486826.628 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-04-24T09:27:07.038Z,1745486827.038 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-24T09:27:07.038Z,1745486827.038 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-04-24T09:27:07.038Z,1745486827.038 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-24T09:27:07.038Z,1745486827.038 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-24T09:27:07.038Z,1745486827.038 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-04-24T09:27:07.039Z,1745486827.039 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-24T09:27:07.039Z,1745486827.039 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-24T09:42:41.489Z,1745487761.489 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-24T09:42:46.076Z,1745487766.076 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.896615 CHAN A1 (24V): 0.844151 CHAN A2 (12V): 0.924308 CHAN A3 (5V): 0.548428 CHAN B0 (3.3V): 0.369081 CHAN B1 (3.15aV): 0.476253 CHAN B2 (3.15bV): 0.475737 CHAN B3 (GND): 0.008366 OPEN: 0.005853 Full Scale: +/- 1 mA 2025-04-24T10:28:13.337Z,1745490493.337 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-24T10:28:13.338Z,1745490493.338 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-24T10:28:13.340Z,1745490493.340 [BPC1](INFO): Received data from all battery sticks. 2025-04-24T11:20:51.435Z,1745493651.435 [keepstation:SurfaceComms] Running Loop=1 2025-04-24T11:20:51.435Z,1745493651.435 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-04-24T11:20:51.435Z,1745493651.435 [keepstation:SurfaceComms:A] Running Loop=1 2025-04-24T11:20:51.435Z,1745493651.435 [keepstation:SurfaceComms:A] Stopped 2025-04-24T11:20:51.435Z,1745493651.435 [keepstation:SurfaceComms:B] Running Loop=1 2025-04-24T11:20:51.436Z,1745493651.436 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-04-24T11:20:51.437Z,1745493651.437 [keepstation:NeedComms] Running Loop=1 2025-04-24T11:20:51.438Z,1745493651.438 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-24T11:20:51.438Z,1745493651.438 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-24T11:20:51.438Z,1745493651.438 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-24T11:20:51.438Z,1745493651.438 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-24T11:20:51.438Z,1745493651.438 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-24T11:20:51.439Z,1745493651.439 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-24T11:20:51.439Z,1745493651.439 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-24T11:20:51.439Z,1745493651.439 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-24T11:20:51.440Z,1745493651.440 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-24T11:20:51.440Z,1745493651.440 [keepstation:NeedComms:A] Running Loop=1 2025-04-24T11:20:51.442Z,1745493651.442 [keepstation:NeedComms:A](INFO): last time_fix was: 1745486444.000000 second since 1970/01/01T00:00:00Z 2025-04-24T11:20:51.442Z,1745493651.442 [keepstation:NeedComms:A] Stopped 2025-04-24T11:20:51.830Z,1745493651.830 [keepstation:KeepStation:Dive] Stopped 2025-04-24T11:20:51.830Z,1745493651.830 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-04-24T11:20:51.830Z,1745493651.830 [keepstation:KeepStation:Dive:A] Stopped 2025-04-24T11:20:51.830Z,1745493651.830 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-04-24T11:20:51.831Z,1745493651.831 [keepstation:DiveCmd:StationKeep] Stopped 2025-04-24T11:20:51.831Z,1745493651.831 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-04-24T11:20:51.831Z,1745493651.831 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-04-24T11:20:51.831Z,1745493651.831 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-04-24T11:20:51.831Z,1745493651.831 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-04-24T11:20:51.831Z,1745493651.831 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-04-24T11:21:53.297Z,1745493713.297 [keepstation:NeedComms:C] Running Loop=1 2025-04-24T11:21:53.912Z,1745493713.912 [Radio_Surface](INFO): Powering up 2025-04-24T11:21:59.408Z,1745493719.408 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-24T11:22:09.509Z,1745493729.509 [NAL9602](INFO): SBD MO Status=1, MOMSN=20458, MT Status=0, MTMSN=0 2025-04-24T11:22:09.509Z,1745493729.509 [NAL9602](INFO): No messages in MT queue 2025-04-24T11:22:10.728Z,1745493730.728 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112204.00,A,3647.82051,N,12152.49036,W,2.041,64.96,240425,,,A*42 2025-04-24T11:22:10.730Z,1745493730.730 [NAL9602](INFO): GPS fix at 20250424T112204: (36.797008, -121.874839) 2025-04-24T11:22:10.743Z,1745493730.743 [keepstation:NeedComms:C] Stopped 2025-04-24T11:22:10.743Z,1745493730.743 [keepstation:NeedComms:D] Running Loop=1 2025-04-24T11:22:29.123Z,1745493749.123 [NAL9602](INFO): SBD MO Status=1, MOMSN=20459, MT Status=0, MTMSN=0 2025-04-24T11:22:29.176Z,1745493749.176 [NAL9602](INFO): Sent 89 bytes from file Logs/20250424T030000/Courier0015.lzma 2025-04-24T11:22:29.176Z,1745493749.176 [NAL9602](INFO): Packets left to send: 0 2025-04-24T11:22:29.540Z,1745493749.540 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T11:22:48.404Z,1745493768.404 [NAL9602](INFO): SBD MO Status=2, MOMSN=20460, MT Status=2, MTMSN=0 2025-04-24T11:22:48.404Z,1745493768.404 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-24T11:23:00.664Z,1745493780.664 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T11:23:14.181Z,1745493794.181 [NAL9602](INFO): SBD MO Status=1, MOMSN=20460, MT Status=0, MTMSN=0 2025-04-24T11:23:14.236Z,1745493794.236 [NAL9602](INFO): Sent 332 bytes from file Logs/20250424T030000/Express0016.lzma 2025-04-24T11:23:14.236Z,1745493794.236 [NAL9602](INFO): Packets left to send: 1 2025-04-24T11:23:26.466Z,1745493806.466 [NAL9602](INFO): SBD MO Status=1, MOMSN=20461, MT Status=0, MTMSN=0 2025-04-24T11:23:26.516Z,1745493806.516 [NAL9602](INFO): Sent 256 bytes from file Logs/20250424T030000/Express0016.lzma 2025-04-24T11:23:26.516Z,1745493806.516 [NAL9602](INFO): Packets left to send: 0 2025-04-24T11:23:31.790Z,1745493811.790 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T11:23:36.222Z,1745493816.222 [NAL9602](INFO): SBD MO Status=0, MOMSN=20462, MT Status=0, MTMSN=0 2025-04-24T11:23:36.313Z,1745493816.313 [keepstation:NeedComms:D] Stopped 2025-04-24T11:23:36.313Z,1745493816.313 [keepstation:NeedComms:E] Running Loop=1 2025-04-24T11:23:38.642Z,1745493818.642 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112332.00,A,3647.82056,N,12152.47976,W,0.330,94.99,240425,,,A*45 2025-04-24T11:23:38.654Z,1745493818.654 [NAL9602](INFO): GPS fix at 20250424T112332: (36.797009, -121.874663) 2025-04-24T11:23:38.667Z,1745493818.667 [keepstation:NeedComms:E] Stopped 2025-04-24T11:23:38.668Z,1745493818.668 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-24T11:23:38.669Z,1745493818.669 [keepstation:NeedComms] Stopped 2025-04-24T11:23:38.669Z,1745493818.669 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-24T11:23:38.669Z,1745493818.669 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-24T11:23:38.669Z,1745493818.669 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-24T11:23:39.088Z,1745493819.088 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-04-24T11:23:39.089Z,1745493819.089 [keepstation:SurfaceComms:B] Stopped 2025-04-24T11:23:39.089Z,1745493819.089 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-04-24T11:23:39.089Z,1745493819.089 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-04-24T11:23:39.089Z,1745493819.089 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-04-24T11:23:39.089Z,1745493819.089 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-04-24T11:23:39.464Z,1745493819.464 [keepstation:SurfaceComms:setTransit] Stopped 2025-04-24T11:23:39.464Z,1745493819.464 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-04-24T11:23:39.464Z,1745493819.464 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-04-24T11:23:39.464Z,1745493819.464 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-04-24T11:23:39.465Z,1745493819.465 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-04-24T11:23:39.465Z,1745493819.465 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-04-24T11:23:39.865Z,1745493819.865 [keepstation:SurfaceComms:setStation] Stopped 2025-04-24T11:23:39.865Z,1745493819.865 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-04-24T11:23:39.865Z,1745493819.865 [keepstation:SurfaceComms:setStation:A] Stopped 2025-04-24T11:23:39.865Z,1745493819.865 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-04-24T11:23:39.865Z,1745493819.865 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-04-24T11:23:39.865Z,1745493819.865 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-04-24T11:23:40.274Z,1745493820.274 [keepstation:SurfaceComms:setSink:A] Stopped 2025-04-24T11:23:40.275Z,1745493820.275 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-04-24T11:23:40.275Z,1745493820.275 [keepstation:SurfaceComms:setSink] Stopped 2025-04-24T11:23:40.275Z,1745493820.275 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-04-24T11:23:40.275Z,1745493820.275 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-04-24T11:23:40.275Z,1745493820.275 [keepstation:SurfaceComms] Stopped 2025-04-24T11:23:40.275Z,1745493820.275 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-04-24T11:23:40.690Z,1745493820.690 [keepstation:KeepStation:Dive] Running Loop=1 2025-04-24T11:23:40.691Z,1745493820.691 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-04-24T11:23:40.691Z,1745493820.691 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-04-24T11:23:40.691Z,1745493820.691 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-04-24T11:23:40.691Z,1745493820.691 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-04-24T11:23:40.691Z,1745493820.691 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-04-24T11:23:40.691Z,1745493820.691 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-04-24T11:23:40.692Z,1745493820.692 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T11:23:40.692Z,1745493820.692 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-24T11:23:40.692Z,1745493820.692 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-24T11:23:40.692Z,1745493820.692 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-24T11:23:40.692Z,1745493820.692 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-04-24T11:23:40.692Z,1745493820.692 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-04-24T11:23:40.693Z,1745493820.693 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-04-24T11:23:40.693Z,1745493820.693 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-04-24T11:23:40.693Z,1745493820.693 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-04-24T11:23:40.693Z,1745493820.693 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-04-24T11:24:02.912Z,1745493842.912 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T11:24:11.380Z,1745493851.380 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-24T11:24:12.332Z,1745493852.332 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003212 2025-04-24T11:26:06.216Z,1745493966.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T11:26:23.824Z,1745493983.824 [Radio_Surface](INFO): Powering down 2025-04-24T11:26:37.344Z,1745493997.344 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-24T11:26:38.348Z,1745493998.348 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-24T11:27:39.457Z,1745494059.457 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-04-24T11:27:39.457Z,1745494059.457 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-04-24T11:27:39.457Z,1745494059.457 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-24T11:27:39.457Z,1745494059.457 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-04-24T11:27:39.866Z,1745494059.866 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-04-24T11:27:39.866Z,1745494059.866 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-24T11:27:39.866Z,1745494059.866 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-04-24T11:27:39.866Z,1745494059.866 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-04-24T11:27:39.866Z,1745494059.866 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T11:27:39.866Z,1745494059.866 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-04-24T11:27:40.263Z,1745494060.263 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-04-24T11:27:40.263Z,1745494060.263 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T11:27:40.280Z,1745494060.280 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-04-24T11:27:40.280Z,1745494060.280 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-24T11:27:40.280Z,1745494060.280 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T11:27:40.281Z,1745494060.281 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-04-24T11:27:40.281Z,1745494060.281 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-24T11:27:40.281Z,1745494060.281 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-04-24T11:27:40.281Z,1745494060.281 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-24T11:27:40.281Z,1745494060.281 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-04-24T11:27:40.281Z,1745494060.281 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-04-24T11:27:40.281Z,1745494060.281 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-04-24T11:27:40.662Z,1745494060.662 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-24T11:27:40.662Z,1745494060.662 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-04-24T11:27:40.663Z,1745494060.663 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-24T11:27:40.663Z,1745494060.663 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-24T11:27:40.663Z,1745494060.663 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-04-24T11:27:40.663Z,1745494060.663 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-24T11:27:40.663Z,1745494060.663 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-24T11:27:44.620Z,1745494064.620 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.218979 2025-04-24T11:35:51.535Z,1745494551.535 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-24T11:35:51.537Z,1745494551.537 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-24T11:35:51.539Z,1745494551.539 [BPC1](INFO): Received data from all battery sticks. 2025-04-24T11:42:41.763Z,1745494961.763 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-24T11:42:46.224Z,1745494966.224 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.897429 CHAN A1 (24V): 0.845578 CHAN A2 (12V): 0.925582 CHAN A3 (5V): 0.550609 CHAN B0 (3.3V): 0.370713 CHAN B1 (3.15aV): 0.479293 CHAN B2 (3.15bV): 0.478225 CHAN B3 (GND): 0.005491 OPEN: 0.006860 Full Scale: +/- 1 mA 2025-04-24T11:53:09.028Z,1745495589.028 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T12:03:09.386Z,1745496189.386 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T12:23:10.094Z,1745497390.094 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T12:33:10.438Z,1745497990.438 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T12:43:10.788Z,1745498590.788 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-24T12:43:27.750Z,1745498607.750 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-24T12:43:27.751Z,1745498607.751 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-24T12:43:27.753Z,1745498607.753 [BPC1](INFO): Received data from all battery sticks. 2025-04-24T12:53:11.134Z,1745499191.134 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging