2025-12-13T01:15:00.281Z,1765588500.281 [CommandExec](IMPORTANT): got command configSet CBIT.navErrorThreshold 200 percent persist
2025-12-13T01:15:00.287Z,1765588500.287 [CommandExec](IMPORTANT): got command restart logs
2025-12-13T01:15:09.023Z,1765588509.023 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-12-13T01:15:09.023Z,1765588509.023 [MissionManager](INFO): Uninitializing Mission sci2_vtyoyo
2025-12-13T01:15:09.023Z,1765588509.023 [sci2_vtyoyo] Stopped
2025-12-13T01:15:09.028Z,1765588509.028 [sci2_vtyoyo](DEBUG): Aggregate::uninitialize sci2_vtyoyo
2025-12-13T01:15:09.028Z,1765588509.028 [sci2_vtyoyo:Science] Stopped
2025-12-13T01:15:09.028Z,1765588509.028 [sci2_vtyoyo:Science](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Science
2025-12-13T01:15:09.028Z,1765588509.028 [sci2_vtyoyo:Science:B] Stopped
2025-12-13T01:15:09.028Z,1765588509.028 [sci2_vtyoyo:Science:C] Stopped
2025-12-13T01:15:09.028Z,1765588509.028 [sci2_vtyoyo:Science:E] Stopped
2025-12-13T01:15:09.029Z,1765588509.029 [sci2_vtyoyo:Science:OceanCurrent] Stopped
2025-12-13T01:15:09.029Z,1765588509.029 [sci2_vtyoyo:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Science:OceanCurrent
2025-12-13T01:15:09.029Z,1765588509.029 [sci2_vtyoyo:Science:OceanCurrent:A.] Stopped
2025-12-13T01:15:09.029Z,1765588509.029 [sci2_vtyoyo:StandardEnvelopes] Stopped
2025-12-13T01:15:09.029Z,1765588509.029 [sci2_vtyoyo:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2_vtyoyo:StandardEnvelopes
2025-12-13T01:15:09.029Z,1765588509.029 [sci2_vtyoyo:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-12-13T01:15:09.029Z,1765588509.029 [sci2_vtyoyo:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-12-13T01:15:09.029Z,1765588509.029 [sci2_vtyoyo:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-12-13T01:15:09.029Z,1765588509.029 [sci2_vtyoyo:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-12-13T01:15:09.030Z,1765588509.030 [sci2_vtyoyo:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-12-13T01:15:09.030Z,1765588509.030 [sci2_vtyoyo:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-12-13T01:15:09.030Z,1765588509.030 [sci2_vtyoyo:BackseatDriver] Stopped
2025-12-13T01:15:09.030Z,1765588509.030 [sci2_vtyoyo:BackseatDriver](DEBUG): Aggregate::uninitialize sci2_vtyoyo:BackseatDriver
2025-12-13T01:15:09.030Z,1765588509.030 [sci2_vtyoyo:BackseatDriver:A.BackseatDriver] Stopped
2025-12-13T01:15:09.030Z,1765588509.030 [sci2_vtyoyo:PowerOnly] Stopped
2025-12-13T01:15:09.030Z,1765588509.030 [sci2_vtyoyo:PowerOnly](DEBUG): Aggregate::uninitialize sci2_vtyoyo:PowerOnly
2025-12-13T01:15:09.030Z,1765588509.030 [sci2_vtyoyo:PowerOnly:E.Wait] Stopped
2025-12-13T01:15:09.030Z,1765588509.030 [sci2_vtyoyo:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-12-13T01:15:09.031Z,1765588509.031 [sci2_vtyoyo:manageDiveMode] Stopped
2025-12-13T01:15:09.031Z,1765588509.031 [sci2_vtyoyo:manageDiveMode](DEBUG): Aggregate::uninitialize sci2_vtyoyo:manageDiveMode
2025-12-13T01:15:09.031Z,1765588509.031 [sci2_vtyoyo:Lap] Stopped
2025-12-13T01:15:09.031Z,1765588509.031 [sci2_vtyoyo:Lap](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap
2025-12-13T01:15:09.031Z,1765588509.031 [sci2_vtyoyo:Lap:Dive] Stopped
2025-12-13T01:15:09.031Z,1765588509.031 [sci2_vtyoyo:Lap:Dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive
2025-12-13T01:15:09.031Z,1765588509.031 [sci2_vtyoyo:Lap:Dive:A] Stopped
2025-12-13T01:15:09.031Z,1765588509.031 [sci2_vtyoyo:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A
2025-12-13T01:15:09.031Z,1765588509.031 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink] Stopped
2025-12-13T01:15:09.036Z,1765588509.036 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:SurfaceSink
2025-12-13T01:15:09.037Z,1765588509.037 [sci2_vtyoyo:Lap:Dive:A:YoYoDive] Stopped
2025-12-13T01:15:09.038Z,1765588509.038 [sci2_vtyoyo:Lap:Dive:A:YoYoDive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive
2025-12-13T01:15:09.038Z,1765588509.038 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive] Stopped
2025-12-13T01:15:09.039Z,1765588509.039 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive
2025-12-13T01:15:09.039Z,1765588509.039 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:A.Pitch] Stopped
2025-12-13T01:15:09.039Z,1765588509.039 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy] Stopped
2025-12-13T01:15:09.044Z,1765588509.044 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-13T01:15:09.044Z,1765588509.044 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed] Stopped
2025-12-13T01:15:09.044Z,1765588509.044 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-12-13T01:15:09.044Z,1765588509.044 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope] Stopped
2025-12-13T01:15:09.044Z,1765588509.044 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize.
2025-12-13T01:15:09.044Z,1765588509.044 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope] Stopped
2025-12-13T01:15:09.044Z,1765588509.044 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-12-13T01:15:09.045Z,1765588509.045 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor] Stopped
2025-12-13T01:15:09.045Z,1765588509.045 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor](DEBUG): Uninitialize Value-Terminated YoYo.
2025-12-13T01:15:09.046Z,1765588509.046 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo] Stopped
2025-12-13T01:15:09.047Z,1765588509.047 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo](DEBUG): Uninitialize YoYoComponent.
2025-12-13T01:15:09.047Z,1765588509.047 [sci2_vtyoyo:Lap:Wpt6] Stopped
2025-12-13T01:15:09.047Z,1765588509.047 [sci2_vtyoyo:Lap:Wpt6](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Wpt6
2025-12-13T01:15:09.047Z,1765588509.047 [sci2_vtyoyo:Lap:Wpt6:A.Waypoint] Stopped
2025-12-13T01:15:09.047Z,1765588509.047 [sci2_vtyoyo:Lap:Wpt6:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-12-13T01:15:09.275Z,1765588509.275 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-12-13T01:15:09.275Z,1765588509.275 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-12-13T01:15:09.299Z,1765588509.299 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-12-13T01:15:09.416Z,1765588509.416 [WetLabsBB2FL](INFO): Powering down
2025-12-13T01:15:11.241Z,1765588511.241 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-12-13T01:15:11.242Z,1765588511.242 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2025-12-13T01:15:11.831Z,1765588511.831 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
4
45
NaN
NaN
30
15
4
0.75
200
10
10
45
2
0
0
1
2
3
NaN
0
Sinking to
Timed out while trying to reach target sink depth at current depth of
2025-12-13T01:15:11.839Z,1765588511.839 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-12-13T01:15:11.850Z,1765588511.850 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-12-13T01:15:11.862Z,1765588511.862 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-12-13T01:15:11.865Z,1765588511.865 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-12-13T01:15:11.867Z,1765588511.867 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-12-13T01:15:11.869Z,1765588511.869 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-12-13T01:15:11.871Z,1765588511.871 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-12-13T01:15:11.875Z,1765588511.875 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-12-13T01:15:11.877Z,1765588511.877 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-12-13T01:15:11.879Z,1765588511.879 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-12-13T01:15:11.881Z,1765588511.881 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-12-13T01:15:11.883Z,1765588511.883 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-12-13T01:15:11.886Z,1765588511.886 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-12-13T01:15:11.888Z,1765588511.888 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-12-13T01:15:11.890Z,1765588511.890 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-12-13T01:15:11.913Z,1765588511.913 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-12-13T01:15:11.915Z,1765588511.915 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-12-13T01:15:11.922Z,1765588511.922 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-12-13T01:15:11.930Z,1765588511.930 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-12-13T01:15:11.940Z,1765588511.940 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-12-13T01:15:11.941Z,1765588511.941 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-12-13T01:15:11.941Z,1765588511.941 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-12-13T01:15:11.942Z,1765588511.942 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-12-13T01:15:12.330Z,1765588512.330 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1500
7
30
0
0
0
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2025-12-13T01:15:12.336Z,1765588512.336 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-12-13T01:15:12.338Z,1765588512.338 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-12-13T01:15:12.341Z,1765588512.341 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-12-13T01:15:12.343Z,1765588512.343 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-12-13T01:15:12.345Z,1765588512.345 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-12-13T01:15:12.347Z,1765588512.347 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1500.000000 s
2025-12-13T01:15:12.350Z,1765588512.350 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-12-13T01:15:12.352Z,1765588512.352 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-12-13T01:15:12.354Z,1765588512.354 [MissionManager](INFO): DefineArg keepstation:NeedComms.DoingCommsMode = 0.000000 count
2025-12-13T01:15:12.357Z,1765588512.357 [MissionManager](INFO): DefineArg keepstation:NeedComms.RestoreMode = 0.000000 count
2025-12-13T01:15:12.359Z,1765588512.359 [MissionManager](INFO): DefineOutput keepstation:NeedComms.IsActive = 0 bool
2025-12-13T01:15:12.362Z,1765588512.362 [MissionManager](INFO): DefineOutput keepstation:NeedComms.RequiredDiveMode = 0.000000 count
2025-12-13T01:15:12.399Z,1765588512.399 [keepstation:NeedComms:C.GoToSurface](DEBUG): Construct GoToSurface.
2025-12-13T01:15:12.459Z,1765588512.459 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-12-13T01:15:12.459Z,1765588512.459 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-12-13T01:15:12.460Z,1765588512.460 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-12-13T01:15:12.529Z,1765588512.529 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2025-12-13T01:15:12.533Z,1765588512.533 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-12-13T01:15:12.535Z,1765588512.535 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-12-13T01:15:12.537Z,1765588512.537 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-12-13T01:15:12.539Z,1765588512.539 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-12-13T01:15:12.541Z,1765588512.541 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-12-13T01:15:12.608Z,1765588512.608 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-12-13T01:15:12.614Z,1765588512.614 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-12-13T01:15:12.643Z,1765588512.643 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-12-13T01:15:12.643Z,1765588512.643 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-12-13T01:15:12.649Z,1765588512.649 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-12-13T01:15:12.686Z,1765588512.686 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-12-13T01:15:12.689Z,1765588512.689 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-12-13T01:15:12.691Z,1765588512.691 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-12-13T01:15:12.694Z,1765588512.694 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-12-13T01:15:12.694Z,1765588512.694 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-12-13T01:15:12.696Z,1765588512.696 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-12-13T01:15:12.810Z,1765588512.810 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-12-13T01:15:12.814Z,1765588512.814 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-12-13T01:15:12.815Z,1765588512.815 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-12-13T01:15:12.818Z,1765588512.818 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-12-13T01:15:12.819Z,1765588512.819 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-12-13T01:15:12.822Z,1765588512.822 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool
2025-12-13T01:15:12.828Z,1765588512.828 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-12-13T01:15:12.830Z,1765588512.830 [keepstation:H.Pitch](DEBUG): Construct.
2025-12-13T01:15:12.892Z,1765588512.892 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-12-13T01:15:12.895Z,1765588512.895 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-12-13T01:15:12.898Z,1765588512.898 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-12-13T01:15:12.911Z,1765588512.911 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-12-13T01:15:12.914Z,1765588512.914 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-12-13T01:15:12.918Z,1765588512.918 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-12-13T01:15:12.923Z,1765588512.923 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-12-13T01:15:12.927Z,1765588512.927 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-12-13T01:15:12.935Z,1765588512.935 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-12-13T01:15:13.006Z,1765588513.006 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-12-13T01:15:13.042Z,1765588513.042 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission keepstation {
"""
Vehicle transits to desired waypoint (or stays put if none is commanded)
and stays within the specified radius.
"""
arguments {
MissionTimeout = 4 hour
"""
Maximum duration of mission
"""
NeedCommsTime = 45 minute
"""
How often to surface for communications
"""
Latitude = NaN degree
"""
Latitude of waypoint to hold. If NaN, hold the latitude at start of
mission.
"""
Longitude = NaN degree
"""
Longitude of waypoint to hold. If NaN, hold the longitude at start of
mission.
"""
Depth = 30 meter
"""
Depth held during drift mode
"""
ApproachDepth = 15 meter
"""
Depth for initial approach to station.
"""
DepthDeadband = 4 meter
"""
How much vertical drift from the specified depth is allowed in drift
mode
"""
Speed = 0.75 meter_per_second
"""
Vehicle transit speed.
"""
Radius = 200 meter
"""
Radius of circle around waypoint to hold.
"""
SinkOffSurface = false
"""
If true, use buoyancy only to decend to SinkDepth before driving to first target depth
"""
SinkDepth = 10 meter
"""
Initial depth to decend using only buoyancy before driving
"""
SinkDepthTimeout = 10 minute
"""
Max wait time for the vehicle to reach the target depth
"""
MaxDepth = 45 meter
"""
Maximum allowable depth during the mission.
"""
MinOffshore = 2 kilometer
"""
Minimum distance from the shoreline to maintain
"""
}
output {
DiveMode = 0 count
"""
Mission variable (don't change). The mission sets this variable to
switch between dive modes. Initialized to DoingComms.
"""
DoingComms = 0 count
"""
Mission variable (don't change). The mission will run in this mode
when doing surface comms.
"""
SurfaceSink = 1 count
"""
Mission variable (don't change). The mission will run in this mode
when sinking from the surface using only buoyancy.
"""
TransitToStation = 2 count
"""
Mission variable (don't change). The mission will run in this mode
when performing the transit to station
"""
StationKeep = 3 count
"""
Mission variable (don't change). The mission will run in this mode
when keeping station
"""
OnStation = false
"""
Mission variable (don't change). Determines last mode (Transit or StationKeep)
to resume after needcomms
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Pitch {
run in parallel
set massPosition = Control:VerticalControl.massDefault
}
aggregate SurfaceComms {
run when (
called
or ( elapsed ( Universal:time_fix ) > NeedCommsTime )
)
assign in sequence DiveMode = DoingComms
call priorityHere=false refId="NeedComms"
aggregate HandleNan {
run in sequence
break if (
not isNaN ( Latitude )
and not isNaN ( Longitude )
)
# If both lat and lon are nan, we are implicitly on station and should skip initial transit, so force OnStation to true
aggregate SetOnStation {
run in sequence
break if (
not isNaN ( Latitude )
or not isNaN ( Longitude )
)
assign in sequence OnStation = true
}
# The "assigns" here mean we should only ever run these aggregates once
# An initial NaN lat/lon should be set here during the initial surface NeedComms call
# Use the most recent fix -- if there isn't one, the keepstation behavior will use Universal:lat/lon
aggregate SetLat {
run in sequence
break if ( not isNaN ( Latitude ) )
assign in sequence Latitude = Universal:latitude_fix
}
aggregate SetLon {
run in sequence
break if ( not isNaN ( Longitude ) )
assign in sequence Longitude = Universal:longitude_fix
}
}
aggregate setTransit {
run in sequence
break if (
SinkOffSurface
or OnStation
)
assign in sequence DiveMode = TransitToStation
}
aggregate setStation {
run in sequence
break if (
SinkOffSurface
or not OnStation
)
assign in sequence DiveMode = StationKeep
}
aggregate setSink {
run in sequence
break if ( not SinkOffSurface )
assign in sequence DiveMode = SurfaceSink
}
}
call id="StartingMission" priorityHere=false refId="SurfaceComms"
aggregate DiveCmd {
run when ( called )
aggregate SurfaceSink {
run while ( DiveMode == SurfaceSink )
aggregate sink {
run in sequence
behavior Guidance:Buoyancy id="BuoyancyHold" {
run in parallel
set position = NaN cc
}
behavior Guidance:SetSpeed {
run in parallel
set speed = 0 m/s
}
syslog info "Sinking to " + SinkDepth~meter
behavior Guidance:Pitch {
run in sequence
timeout duration=SinkDepthTimeout {
syslog important "Timed out while trying to reach target sink depth at current depth of "
+ Universal:depth~meter
}
set depth = SinkDepth
}
# Once we've sunk, either resume transit or station keeping depths and speeds
aggregate sinkToTransit {
run in sequence
break if ( OnStation )
assign in sequence DiveMode = TransitToStation
}
aggregate sinkToStation {
run in sequence
break if ( not OnStation )
assign in sequence DiveMode = StationKeep
}
}
}
aggregate TransitToStation {
run while ( DiveMode == TransitToStation )
aggregate dive {
run in sequence
behavior Guidance:Buoyancy {
run in parallel
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch {
run in parallel
set depth = ApproachDepth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
}
}
aggregate StationKeep {
run while ( DiveMode == StationKeep )
assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:KeepStation {
run in parallel
set latitude = Latitude
set longitude = Longitude
set radius = Radius
set speed = Speed
}
}
}
aggregate TransitToStation {
"""
Need a separate aggregate for transit if we want to specify a
different depth for the approach.
"""
run in sequence
aggregate Dive {
run while ( not ( DiveMode == DoingComms ) )
call priorityHere=false refId="DiveCmd"
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
}
assign in sequence DiveMode = StationKeep
assign in sequence OnStation = true
}
call id="OnStation" priorityHere=false refId="NeedComms"
aggregate KeepStation {
run in sequence
aggregate Dive {
run while ( not ( DiveMode == DoingComms ) )
call priorityHere=false refId="DiveCmd"
}
behavior Guidance:Wait {
"""
Due to the way the KeepStation behavior is currently
implemented, we must run it in parallel and use a Wait to keep
from completing the mission before the timeout expires. However,
this new version of the mission uses a top-level timeout so that
the entire mission will not run for longer then MissionTimeout.
"""
run in sequence
set duration = MissionTimeout
}
}
}
2025-12-13T01:15:13.043Z,1765588513.043 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-12-13T01:15:13.509Z,1765588513.509 [MissionManager](IMPORTANT): Started mission Default
2025-12-13T01:15:13.510Z,1765588513.510 [Default] Running Loop=1
2025-12-13T01:15:13.510Z,1765588513.510 [Default](DEBUG): Aggregate::initialize Default
2025-12-13T01:15:13.510Z,1765588513.510 [Default:B.GoToSurface] Running Loop=1
2025-12-13T01:15:13.510Z,1765588513.510 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-13T01:15:13.510Z,1765588513.510 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-12-13T01:15:13.510Z,1765588513.510 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-12-13T01:15:13.511Z,1765588513.511 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-12-13T01:15:13.511Z,1765588513.511 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-12-13T01:15:13.511Z,1765588513.511 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-13T01:15:13.532Z,1765588513.532 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-13T01:15:13.532Z,1765588513.532 [Default:A.Wait] Running Loop=1
2025-12-13T01:15:13.532Z,1765588513.532 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-12-13T01:15:14.144Z,1765588514.144 [Radio_Surface](INFO): Powering up
2025-12-13T01:15:19.809Z,1765588519.809 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-13T01:15:26.917Z,1765588526.917 [Default:A.Wait](INFO): Done Waiting.
2025-12-13T01:15:26.917Z,1765588526.917 [Default:A.Wait] Stopped
2025-12-13T01:15:26.917Z,1765588526.917 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-12-13T01:15:29.428Z,1765588529.428 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2025-12-13T01:15:29.429Z,1765588529.429 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2025-12-13T01:15:29.429Z,1765588529.429 [LcmPublisher](INFO): Deactivating messaging.
2025-12-13T01:15:33.031Z,1765588533.031 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 14 hour
2025-12-13T01:15:33.032Z,1765588533.032 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 90 minute
2025-12-13T01:15:33.033Z,1765588533.033 [CommandExec](IMPORTANT): got command set keepstation.Depth 75 meter
2025-12-13T01:15:33.034Z,1765588533.034 [CommandExec](IMPORTANT): got command set keepstation.Speed 1 meter_per_second
2025-12-13T01:15:33.034Z,1765588533.034 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool
2025-12-13T01:15:42.908Z,1765588542.908 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm.
2025-12-13T01:15:42.909Z,1765588542.909 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.70 m/s, pitch 20.00 deg, mass-position 31.17 mm.
2025-12-13T01:15:42.910Z,1765588542.910 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.70 m/s, pitch -20.00 deg, mass-position 31.17 mm.
2025-12-13T01:15:42.919Z,1765588542.919 [Default:CheckIn] Running Loop=1
2025-12-13T01:15:42.919Z,1765588542.919 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-12-13T01:15:42.919Z,1765588542.919 [Default:CheckIn:Read_GPS] Running Loop=1
2025-12-13T01:15:49.349Z,1765588549.349 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 90 meter
2025-12-13T01:15:49.350Z,1765588549.350 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second
2025-12-13T01:15:49.350Z,1765588549.350 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 30 minute
2025-12-13T01:15:49.351Z,1765588549.351 [CommandExec](IMPORTANT): got command run
2025-12-13T01:15:49.353Z,1765588549.353 [CommandExec](IMPORTANT): Running
2025-12-13T01:15:49.622Z,1765588549.622 [Default] Stopped
2025-12-13T01:15:49.622Z,1765588549.622 [Default](DEBUG): Aggregate::uninitialize Default
2025-12-13T01:15:49.623Z,1765588549.623 [Default:B.GoToSurface] Stopped
2025-12-13T01:15:49.623Z,1765588549.623 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-13T01:15:49.623Z,1765588549.623 [Default:CheckIn] Stopped
2025-12-13T01:15:49.623Z,1765588549.623 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-12-13T01:15:49.623Z,1765588549.623 [Default:CheckIn:Read_GPS] Stopped
2025-12-13T01:15:49.623Z,1765588549.623 [MissionManager](IMPORTANT): Started mission keepstation
2025-12-13T01:15:49.623Z,1765588549.623 [keepstation] Running Loop=1
2025-12-13T01:15:49.623Z,1765588549.623 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-12-13T01:15:49.623Z,1765588549.623 [keepstation:StandardEnvelopes] Running Loop=1
2025-12-13T01:15:49.624Z,1765588549.624 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-12-13T01:15:49.624Z,1765588549.624 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-12-13T01:15:49.624Z,1765588549.624 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-12-13T01:15:49.624Z,1765588549.624 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-12-13T01:15:49.624Z,1765588549.624 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-12-13T01:15:49.624Z,1765588549.624 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-12-13T01:15:49.624Z,1765588549.624 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-12-13T01:15:49.625Z,1765588549.625 [keepstation:BackseatDriver] Running Loop=1
2025-12-13T01:15:49.625Z,1765588549.625 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-12-13T01:15:49.625Z,1765588549.625 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-12-13T01:15:49.625Z,1765588549.625 [keepstation:PowerOnly] Running Loop=1
2025-12-13T01:15:49.625Z,1765588549.625 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-12-13T01:15:49.625Z,1765588549.625 [keepstation:PowerOnly:A] Running Loop=1
2025-12-13T01:15:49.625Z,1765588549.625 [keepstation:PowerOnly:B] Running Loop=1
2025-12-13T01:15:49.625Z,1765588549.625 [keepstation:PowerOnly:C] Running Loop=1
2025-12-13T01:15:49.625Z,1765588549.625 [keepstation:PowerOnly:D] Running Loop=1
2025-12-13T01:15:49.625Z,1765588549.625 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-12-13T01:15:49.625Z,1765588549.625 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-12-13T01:15:49.626Z,1765588549.626 [keepstation:H.Pitch] Running Loop=1
2025-12-13T01:15:49.626Z,1765588549.626 [keepstation:H.Pitch](DEBUG): Initialize.
2025-12-13T01:15:49.626Z,1765588549.626 [keepstation:B] Running Loop=1
2025-12-13T01:15:49.627Z,1765588549.627 [keepstation:H.Pitch] Running Loop=1
2025-12-13T01:15:49.627Z,1765588549.627 [keepstation:PowerOnly] Running Loop=1
2025-12-13T01:15:49.627Z,1765588549.627 [keepstation:PowerOnly:D] Stopped
2025-12-13T01:15:49.627Z,1765588549.627 [keepstation:PowerOnly:C] Stopped
2025-12-13T01:15:49.627Z,1765588549.627 [keepstation:PowerOnly:B] Stopped
2025-12-13T01:15:49.627Z,1765588549.627 [keepstation:PowerOnly:A] Stopped
2025-12-13T01:15:49.628Z,1765588549.628 [keepstation:BackseatDriver] Running Loop=1
2025-12-13T01:15:49.628Z,1765588549.628 [keepstation:StandardEnvelopes] Running Loop=1
2025-12-13T01:15:49.628Z,1765588549.628 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-12-13T01:15:49.628Z,1765588549.628 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-12-13T01:15:49.629Z,1765588549.629 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-12-13T01:15:49.631Z,1765588549.631 [keepstation:B] Stopped
2025-12-13T01:15:49.632Z,1765588549.632 [keepstation:D] Running Loop=1
2025-12-13T01:15:49.993Z,1765588549.993 [keepstation:D] Stopped
2025-12-13T01:15:49.993Z,1765588549.993 [keepstation:E] Running Loop=1
2025-12-13T01:15:50.398Z,1765588550.398 [keepstation:E] Stopped
2025-12-13T01:15:50.398Z,1765588550.398 [keepstation:StartingMission] Running Loop=1
2025-12-13T01:15:50.398Z,1765588550.398 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-12-13T01:15:50.816Z,1765588550.816 [keepstation:SurfaceComms] Running Loop=1
2025-12-13T01:15:50.816Z,1765588550.816 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-13T01:15:50.816Z,1765588550.816 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-13T01:15:50.817Z,1765588550.817 [keepstation:SurfaceComms:A] Stopped
2025-12-13T01:15:50.817Z,1765588550.817 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-13T01:15:50.817Z,1765588550.817 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-13T01:15:51.195Z,1765588551.195 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-12-13T01:15:51.195Z,1765588551.195 [keepstation:NeedComms] Running Loop=1
2025-12-13T01:15:51.195Z,1765588551.195 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-13T01:15:51.195Z,1765588551.195 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-13T01:15:51.195Z,1765588551.195 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-13T01:15:51.196Z,1765588551.196 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-13T01:15:51.196Z,1765588551.196 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-13T01:15:51.197Z,1765588551.197 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-13T01:15:51.197Z,1765588551.197 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-13T01:15:51.197Z,1765588551.197 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-13T01:15:51.198Z,1765588551.198 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-13T01:15:51.198Z,1765588551.198 [keepstation:NeedComms:A] Running Loop=1
2025-12-13T01:15:51.199Z,1765588551.199 [keepstation:NeedComms:A](INFO): last time_fix was: 1765587234.000000 second since 1970/01/01T00:00:00Z
2025-12-13T01:15:51.199Z,1765588551.199 [keepstation:NeedComms:A] Stopped
2025-12-13T01:15:51.199Z,1765588551.199 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-13T01:15:51.199Z,1765588551.199 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-13T01:15:51.201Z,1765588551.201 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-13T01:15:51.599Z,1765588551.599 [keepstation:NeedComms:setActive:A] Stopped
2025-12-13T01:15:51.599Z,1765588551.599 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-13T01:15:52.011Z,1765588552.011 [keepstation:NeedComms:setActive:B] Stopped
2025-12-13T01:15:52.011Z,1765588552.011 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-13T01:15:52.011Z,1765588552.011 [keepstation:NeedComms:setActive] Stopped
2025-12-13T01:15:52.011Z,1765588552.011 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-13T01:15:52.424Z,1765588552.424 [keepstation:NeedComms:D] Running Loop=1
2025-12-13T01:15:52.813Z,1765588552.813 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-12-13T01:16:15.841Z,1765588575.841 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-12-13T01:16:15.924Z,1765588575.924 [NAL9602](ERROR): received:
+CSQ:0
OK436, 2, 0, 0, 0
OK
2025-12-13T01:17:14.924Z,1765588634.924 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003005
2025-12-13T01:19:25.016Z,1765588765.016 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T01:19:25.794Z,1765588765.794 [NAL9602](INFO): SBD MO Status=2, MOMSN=24436, MT Status=2, MTMSN=0
2025-12-13T01:19:25.795Z,1765588765.795 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T01:20:23.141Z,1765588823.141 [NAL9602](INFO): SBD MO Status=2, MOMSN=24436, MT Status=2, MTMSN=0
2025-12-13T01:20:23.141Z,1765588823.141 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T01:20:54.659Z,1765588854.659 [NAL9602](INFO): SBD MO Status=2, MOMSN=24436, MT Status=2, MTMSN=0
2025-12-13T01:20:54.659Z,1765588854.659 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T01:20:55.056Z,1765588855.056 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-12-13T01:20:56.328Z,1765588856.328 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012054.00,A,3659.62175,N,12226.41903,W,0.544,316.14,131225,,,D*7D
2025-12-13T01:20:56.330Z,1765588856.330 [NAL9602](INFO): GPS fix at 20251213T012054: (36.993696, -122.440317)
2025-12-13T01:20:56.342Z,1765588856.342 [UniversalFixResidualReporter](INFO): Fix residual: 50.9 %DT, over the last 632.0 m. Residual distance 322.0 m at bearing 145.3 degrees. Fix at (36.9937, -122.4403) with 279.9 m made good.
2025-12-13T01:20:56.345Z,1765588856.345 [keepstation:NeedComms:D] Stopped
2025-12-13T01:20:56.345Z,1765588856.345 [keepstation:NeedComms:E] Running Loop=1
2025-12-13T01:20:56.722Z,1765588856.722 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-12-13T01:21:03.535Z,1765588863.535 [DataOverHttps](INFO): Sending 92 bytes from file Logs/20251212T143000/Courier0027.lzma
2025-12-13T01:21:04.539Z,1765588864.539 [DataOverHttps](INFO): Moved sent file to Logs/20251212T143000/Courier0027.lzma.bak
2025-12-13T01:21:04.539Z,1765588864.539 [DataOverHttps](INFO): SBD MOMSN=26494339
2025-12-13T01:21:20.921Z,1765588880.921 [DataOverHttps](INFO): Sending 328 bytes from file Logs/20251213T011500/Courier0000.lzma
2025-12-13T01:21:21.922Z,1765588881.922 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Courier0000.lzma.bak
2025-12-13T01:21:21.922Z,1765588881.922 [DataOverHttps](INFO): SBD MOMSN=26494342
2025-12-13T01:21:28.680Z,1765588888.680 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-13T01:21:38.500Z,1765588898.500 [DataOverHttps](INFO): Sending 514 bytes from file Logs/20251212T143000/Express0028.lzma
2025-12-13T01:21:39.503Z,1765588899.503 [DataOverHttps](INFO): Moved sent file to Logs/20251212T143000/Express0028.lzma.bak
2025-12-13T01:21:39.503Z,1765588899.503 [DataOverHttps](INFO): SBD MOMSN=26494346
2025-12-13T01:21:55.754Z,1765588915.754 [DataOverHttps](INFO): Sending 1137 bytes from file Logs/20251213T011500/Express0001.lzma
2025-12-13T01:21:56.754Z,1765588916.754 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Express0001.lzma.bak
2025-12-13T01:21:56.754Z,1765588916.754 [DataOverHttps](INFO): SBD MOMSN=26494350
2025-12-13T01:21:58.182Z,1765588918.182 [keepstation:NeedComms:E] Stopped
2025-12-13T01:21:58.183Z,1765588918.183 [keepstation:NeedComms:F] Running Loop=1
2025-12-13T01:21:58.589Z,1765588918.589 [keepstation:NeedComms:F](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-12-13T01:22:00.195Z,1765588920.195 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012158.00,A,3659.62798,N,12226.42479,W,0.914,326.23,131225,,,D*78
2025-12-13T01:22:00.198Z,1765588920.198 [NAL9602](INFO): GPS fix at 20251213T012158: (36.993800, -122.440413)
2025-12-13T01:22:00.238Z,1765588920.238 [keepstation:NeedComms:F] Stopped
2025-12-13T01:22:00.238Z,1765588920.238 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-13T01:22:00.239Z,1765588920.239 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-13T01:22:00.239Z,1765588920.239 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-13T01:22:00.645Z,1765588920.645 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-13T01:22:00.645Z,1765588920.645 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-13T01:22:00.645Z,1765588920.645 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-13T01:22:00.645Z,1765588920.645 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-13T01:22:00.645Z,1765588920.645 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-13T01:22:00.645Z,1765588920.645 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-13T01:22:00.646Z,1765588920.646 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-13T01:22:01.017Z,1765588921.017 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-13T01:22:01.017Z,1765588921.017 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-13T01:22:01.017Z,1765588921.017 [keepstation:NeedComms:setInactive] Stopped
2025-12-13T01:22:01.017Z,1765588921.017 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-13T01:22:01.018Z,1765588921.018 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-13T01:22:01.018Z,1765588921.018 [keepstation:NeedComms] Stopped
2025-12-13T01:22:01.018Z,1765588921.018 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-13T01:22:01.018Z,1765588921.018 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-13T01:22:01.018Z,1765588921.018 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-13T01:22:01.427Z,1765588921.427 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-13T01:22:01.427Z,1765588921.427 [keepstation:SurfaceComms:B] Stopped
2025-12-13T01:22:01.427Z,1765588921.427 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-13T01:22:01.427Z,1765588921.427 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-13T01:22:01.427Z,1765588921.427 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-13T01:22:01.432Z,1765588921.432 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-13T01:22:01.432Z,1765588921.432 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T01:22:01.432Z,1765588921.432 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-13T01:22:01.854Z,1765588921.854 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-13T01:22:01.854Z,1765588921.854 [keepstation:SurfaceComms:HandleNan:SetOnStation](INFO): Completed keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T01:22:01.855Z,1765588921.855 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-13T01:22:01.855Z,1765588921.855 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T01:22:01.855Z,1765588921.855 [keepstation:SurfaceComms:HandleNan:SetLat] Running Loop=1
2025-12-13T01:22:01.855Z,1765588921.855 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLat
2025-12-13T01:22:01.855Z,1765588921.855 [keepstation:SurfaceComms:HandleNan:SetLat:A] Running Loop=1
2025-12-13T01:22:02.244Z,1765588922.244 [keepstation:SurfaceComms:HandleNan:SetLat:A] Stopped
2025-12-13T01:22:02.244Z,1765588922.244 [keepstation:SurfaceComms:HandleNan:SetLat](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLat
2025-12-13T01:22:02.245Z,1765588922.245 [keepstation:SurfaceComms:HandleNan:SetLat] Stopped
2025-12-13T01:22:02.245Z,1765588922.245 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLat
2025-12-13T01:22:02.245Z,1765588922.245 [keepstation:SurfaceComms:HandleNan:SetLon] Running Loop=1
2025-12-13T01:22:02.245Z,1765588922.245 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLon
2025-12-13T01:22:02.245Z,1765588922.245 [keepstation:SurfaceComms:HandleNan:SetLon:A] Running Loop=1
2025-12-13T01:22:02.625Z,1765588922.625 [keepstation:SurfaceComms:HandleNan:SetLon:A] Stopped
2025-12-13T01:22:02.625Z,1765588922.625 [keepstation:SurfaceComms:HandleNan:SetLon](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLon
2025-12-13T01:22:02.625Z,1765588922.625 [keepstation:SurfaceComms:HandleNan:SetLon] Stopped
2025-12-13T01:22:02.625Z,1765588922.625 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLon
2025-12-13T01:22:02.625Z,1765588922.625 [keepstation:SurfaceComms:HandleNan](INFO): Completed keepstation:SurfaceComms:HandleNan
2025-12-13T01:22:02.625Z,1765588922.625 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-13T01:22:02.626Z,1765588922.626 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-13T01:22:02.626Z,1765588922.626 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-13T01:22:02.626Z,1765588922.626 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-13T01:22:02.626Z,1765588922.626 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-13T01:22:03.065Z,1765588923.065 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-13T01:22:03.065Z,1765588923.065 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-13T01:22:03.065Z,1765588923.065 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-13T01:22:03.066Z,1765588923.066 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-13T01:22:03.066Z,1765588923.066 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-13T01:22:03.066Z,1765588923.066 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-13T01:22:03.440Z,1765588923.440 [keepstation:SurfaceComms:setStation] Stopped
2025-12-13T01:22:03.440Z,1765588923.440 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-13T01:22:03.441Z,1765588923.441 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-13T01:22:03.441Z,1765588923.441 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-13T01:22:03.441Z,1765588923.441 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-13T01:22:03.441Z,1765588923.441 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-13T01:22:03.903Z,1765588923.903 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-13T01:22:03.903Z,1765588923.903 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-12-13T01:22:03.903Z,1765588923.903 [keepstation:SurfaceComms:setSink] Stopped
2025-12-13T01:22:03.903Z,1765588923.903 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-13T01:22:03.904Z,1765588923.904 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-13T01:22:03.905Z,1765588923.905 [keepstation:SurfaceComms] Stopped
2025-12-13T01:22:03.905Z,1765588923.905 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-13T01:22:04.243Z,1765588924.243 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-12-13T01:22:04.243Z,1765588924.243 [keepstation:StartingMission] Stopped
2025-12-13T01:22:04.243Z,1765588924.243 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-12-13T01:22:04.243Z,1765588924.243 [keepstation:TransitToStation] Running Loop=1
2025-12-13T01:22:04.243Z,1765588924.243 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-12-13T01:22:04.243Z,1765588924.243 [keepstation:TransitToStation:Dive] Running Loop=1
2025-12-13T01:22:04.243Z,1765588924.243 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-12-13T01:22:04.243Z,1765588924.243 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-12-13T01:22:04.244Z,1765588924.244 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-12-13T01:22:04.260Z,1765588924.260 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-12-13T01:22:04.260Z,1765588924.260 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-12-13T01:22:04.261Z,1765588924.261 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.993800,-122.440413
2025-12-13T01:22:04.676Z,1765588924.676 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.993800,-122.440413
2025-12-13T01:22:04.677Z,1765588924.677 [keepstation:TransitToStation:Dive] Running Loop=1
2025-12-13T01:22:04.677Z,1765588924.677 [keepstation:DiveCmd] Running Loop=1
2025-12-13T01:22:04.677Z,1765588924.677 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-12-13T01:22:04.677Z,1765588924.677 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-12-13T01:22:04.677Z,1765588924.677 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-12-13T01:22:04.677Z,1765588924.677 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-12-13T01:22:04.677Z,1765588924.677 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T01:22:04.677Z,1765588924.677 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-12-13T01:22:04.677Z,1765588924.677 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-13T01:22:04.678Z,1765588924.678 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-12-13T01:22:04.678Z,1765588924.678 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-12-13T01:22:04.678Z,1765588924.678 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-12-13T01:22:04.678Z,1765588924.678 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-12-13T01:22:04.678Z,1765588924.678 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-12-13T01:22:04.678Z,1765588924.678 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-12-13T01:22:04.678Z,1765588924.678 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-12-13T01:22:04.678Z,1765588924.678 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-12-13T01:22:04.678Z,1765588924.678 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-13T01:22:04.678Z,1765588924.678 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-12-13T01:22:04.678Z,1765588924.678 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-12-13T01:22:04.679Z,1765588924.679 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-12-13T01:22:04.679Z,1765588924.679 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-12-13T01:22:04.679Z,1765588924.679 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-13T01:22:04.679Z,1765588924.679 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-13T01:22:04.679Z,1765588924.679 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-13T01:22:04.679Z,1765588924.679 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-13T01:22:04.679Z,1765588924.679 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-13T01:22:04.679Z,1765588924.679 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-13T01:22:04.679Z,1765588924.679 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-13T01:22:04.680Z,1765588924.680 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:TransitToStation] Stopped
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-12-13T01:22:04.681Z,1765588924.681 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-12-13T01:22:04.682Z,1765588924.682 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-12-13T01:22:04.682Z,1765588924.682 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m
2025-12-13T01:22:04.682Z,1765588924.682 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-12-13T01:22:04.682Z,1765588924.682 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-12-13T01:22:04.682Z,1765588924.682 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-12-13T01:22:04.683Z,1765588924.683 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-12-13T01:22:04.683Z,1765588924.683 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-12-13T01:22:29.279Z,1765588949.279 [NAL9602](INFO): SBD MO Status=2, MOMSN=24436, MT Status=2, MTMSN=0
2025-12-13T01:22:29.279Z,1765588949.279 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T01:22:39.850Z,1765588959.850 [NAL9602](INFO): SBD MO Status=0, MOMSN=24436, MT Status=0, MTMSN=0
2025-12-13T01:22:39.850Z,1765588959.850 [NAL9602](INFO): No messages in MT queue
2025-12-13T01:23:10.509Z,1765588990.509 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-13T01:23:29.304Z,1765589009.304 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T01:24:00.464Z,1765589040.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T01:24:11.612Z,1765589051.612 [Radio_Surface](INFO): Powering down
2025-12-13T01:24:31.588Z,1765589071.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T01:24:32.592Z,1765589072.592 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-13T01:25:27.150Z,1765589127.150 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-12-13T01:25:27.150Z,1765589127.150 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-12-13T01:25:27.150Z,1765589127.150 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T01:25:27.150Z,1765589127.150 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-12-13T01:25:27.536Z,1765589127.536 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-12-13T01:25:27.536Z,1765589127.536 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T01:25:27.536Z,1765589127.536 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-12-13T01:25:27.537Z,1765589127.537 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-12-13T01:25:27.537Z,1765589127.537 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T01:25:27.537Z,1765589127.537 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-12-13T01:25:27.934Z,1765589127.934 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-12-13T01:25:27.934Z,1765589127.934 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T01:25:27.934Z,1765589127.934 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-12-13T01:25:27.934Z,1765589127.934 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T01:25:27.935Z,1765589127.935 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T01:25:27.935Z,1765589127.935 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-12-13T01:25:27.935Z,1765589127.935 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T01:25:27.935Z,1765589127.935 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-12-13T01:25:27.935Z,1765589127.935 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-13T01:25:27.935Z,1765589127.935 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-12-13T01:25:27.935Z,1765589127.935 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-12-13T01:25:27.935Z,1765589127.935 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-12-13T01:25:28.321Z,1765589128.321 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-13T01:25:28.321Z,1765589128.321 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-13T01:25:28.322Z,1765589128.322 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-13T01:25:28.322Z,1765589128.322 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-13T01:25:28.322Z,1765589128.322 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-13T01:25:28.322Z,1765589128.322 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-13T01:25:28.322Z,1765589128.322 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-13T01:25:28.322Z,1765589128.322 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-13T01:25:28.322Z,1765589128.322 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-13T01:25:28.323Z,1765589128.323 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-13T01:25:28.323Z,1765589128.323 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-13T01:25:38.856Z,1765589138.856 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.762375
2025-12-13T01:29:00.855Z,1765589340.855 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T01:38:13.485Z,1765589893.485 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T01:38:13.488Z,1765589893.488 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T02:00:26.736Z,1765591226.736 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T02:19:15.451Z,1765592355.451 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T02:19:15.464Z,1765592355.464 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T02:52:00.529Z,1765594320.529 [keepstation:SurfaceComms] Running Loop=1
2025-12-13T02:52:00.529Z,1765594320.529 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-13T02:52:00.530Z,1765594320.530 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-13T02:52:00.530Z,1765594320.530 [keepstation:SurfaceComms:A] Stopped
2025-12-13T02:52:00.530Z,1765594320.530 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-13T02:52:00.530Z,1765594320.530 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-13T02:52:00.532Z,1765594320.532 [keepstation:NeedComms] Running Loop=1
2025-12-13T02:52:00.532Z,1765594320.532 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-13T02:52:00.532Z,1765594320.532 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-13T02:52:00.532Z,1765594320.532 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-13T02:52:00.533Z,1765594320.533 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-13T02:52:00.533Z,1765594320.533 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-13T02:52:00.533Z,1765594320.533 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-13T02:52:00.534Z,1765594320.534 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-13T02:52:00.534Z,1765594320.534 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-13T02:52:00.534Z,1765594320.534 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-13T02:52:00.535Z,1765594320.535 [keepstation:NeedComms:A] Running Loop=1
2025-12-13T02:52:00.536Z,1765594320.536 [keepstation:NeedComms:A](INFO): last time_fix was: 1765588918.000000 second since 1970/01/01T00:00:00Z
2025-12-13T02:52:00.536Z,1765594320.536 [keepstation:NeedComms:A] Stopped
2025-12-13T02:52:00.537Z,1765594320.537 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-13T02:52:00.537Z,1765594320.537 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-13T02:52:00.537Z,1765594320.537 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-13T02:52:00.922Z,1765594320.922 [keepstation:TransitToStation:Dive] Stopped
2025-12-13T02:52:00.922Z,1765594320.922 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-12-13T02:52:00.922Z,1765594320.922 [keepstation:TransitToStation:Dive:A] Stopped
2025-12-13T02:52:00.923Z,1765594320.923 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-12-13T02:52:00.923Z,1765594320.923 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-13T02:52:00.923Z,1765594320.923 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-13T02:52:00.923Z,1765594320.923 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-13T02:52:00.923Z,1765594320.923 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-13T02:52:00.923Z,1765594320.923 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-13T02:52:00.923Z,1765594320.923 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-13T02:52:00.926Z,1765594320.926 [keepstation:NeedComms:setActive:A] Stopped
2025-12-13T02:52:00.927Z,1765594320.927 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-13T02:52:01.330Z,1765594321.330 [keepstation:NeedComms:setActive:B] Stopped
2025-12-13T02:52:01.331Z,1765594321.331 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-13T02:52:01.331Z,1765594321.331 [keepstation:NeedComms:setActive] Stopped
2025-12-13T02:52:01.331Z,1765594321.331 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-13T02:54:12.077Z,1765594452.077 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.993800,-122.440413
2025-12-13T02:54:12.077Z,1765594452.077 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-12-13T02:54:12.077Z,1765594452.077 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-12-13T02:54:12.077Z,1765594452.077 [keepstation:TransitToStation:C] Running Loop=1
2025-12-13T02:54:12.488Z,1765594452.488 [keepstation:TransitToStation:C] Stopped
2025-12-13T02:54:12.488Z,1765594452.488 [keepstation:TransitToStation:D] Running Loop=1
2025-12-13T02:54:12.489Z,1765594452.489 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-13T02:54:12.489Z,1765594452.489 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-13T02:54:12.489Z,1765594452.489 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-13T02:54:12.489Z,1765594452.489 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-13T02:54:12.489Z,1765594452.489 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-13T02:54:12.489Z,1765594452.489 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-13T02:54:12.489Z,1765594452.489 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-13T02:54:12.882Z,1765594452.882 [keepstation:TransitToStation:D] Stopped
2025-12-13T02:54:12.882Z,1765594452.882 [keepstation:TransitToStation:Dive] Running Loop=1
2025-12-13T02:54:12.882Z,1765594452.882 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-12-13T02:54:12.882Z,1765594452.882 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-12-13T02:54:12.883Z,1765594452.883 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-12-13T02:54:12.883Z,1765594452.883 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-12-13T02:54:12.883Z,1765594452.883 [keepstation:TransitToStation] Stopped
2025-12-13T02:54:12.883Z,1765594452.883 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-12-13T02:54:12.883Z,1765594452.883 [keepstation:TransitToStation:Dive] Stopped
2025-12-13T02:54:12.883Z,1765594452.883 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-12-13T02:54:12.883Z,1765594452.883 [keepstation:TransitToStation:Dive:A] Stopped
2025-12-13T02:54:12.883Z,1765594452.883 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-12-13T02:54:12.883Z,1765594452.883 [keepstation:OnStation] Running Loop=1
2025-12-13T02:54:12.883Z,1765594452.883 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-12-13T02:56:43.393Z,1765594603.393 [keepstation:NeedComms:D] Running Loop=1
2025-12-13T02:56:44.060Z,1765594604.060 [Radio_Surface](INFO): Powering up
2025-12-13T02:56:49.700Z,1765594609.700 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-13T02:57:19.828Z,1765594639.828 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T02:57:39.098Z,1765594659.098 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T02:57:39.098Z,1765594659.098 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T02:57:50.953Z,1765594670.953 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T02:58:22.088Z,1765594702.088 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T02:58:53.212Z,1765594733.212 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T02:59:01.728Z,1765594741.728 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003016
2025-12-13T03:00:17.479Z,1765594817.479 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T03:00:17.483Z,1765594817.483 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T03:01:47.587Z,1765594907.587 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-12-13T03:01:48.803Z,1765594908.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030147.00,A,3700.06136,N,12227.10165,W,0.758,356.82,131225,,,D*7F
2025-12-13T03:01:48.805Z,1765594908.805 [NAL9602](INFO): GPS fix at 20251213T030147: (37.001023, -122.451694)
2025-12-13T03:01:48.817Z,1765594908.817 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-12-13T03:01:48.820Z,1765594908.820 [keepstation:NeedComms:D] Stopped
2025-12-13T03:01:48.821Z,1765594908.821 [keepstation:NeedComms:E] Running Loop=1
2025-12-13T03:01:56.708Z,1765594916.708 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20251213T011500/Courier0003.lzma
2025-12-13T03:01:57.710Z,1765594917.710 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Courier0003.lzma.bak
2025-12-13T03:01:57.710Z,1765594917.710 [DataOverHttps](INFO): SBD MOMSN=26494373
2025-12-13T03:02:14.617Z,1765594934.617 [DataOverHttps](INFO): Sending 857 bytes from file Logs/20251213T011500/Express0004.lzma
2025-12-13T03:02:15.618Z,1765594935.618 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Express0004.lzma.bak
2025-12-13T03:02:15.618Z,1765594935.618 [DataOverHttps](INFO): SBD MOMSN=26494376
2025-12-13T03:02:17.107Z,1765594937.107 [keepstation:NeedComms:E] Stopped
2025-12-13T03:02:17.107Z,1765594937.107 [keepstation:NeedComms:F] Running Loop=1
2025-12-13T03:02:19.107Z,1765594939.107 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030217.00,A,3700.06409,N,12227.10305,W,0.292,323.54,131225,,,D*7E
2025-12-13T03:02:19.109Z,1765594939.109 [NAL9602](INFO): GPS fix at 20251213T030217: (37.001068, -122.451718)
2025-12-13T03:02:19.156Z,1765594939.156 [keepstation:NeedComms:F] Stopped
2025-12-13T03:02:19.156Z,1765594939.156 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-13T03:02:19.156Z,1765594939.156 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-13T03:02:19.156Z,1765594939.156 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-13T03:02:19.561Z,1765594939.561 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-13T03:02:19.561Z,1765594939.561 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-13T03:02:19.561Z,1765594939.561 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-13T03:02:19.561Z,1765594939.561 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-13T03:02:19.561Z,1765594939.561 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-13T03:02:19.561Z,1765594939.561 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-13T03:02:19.561Z,1765594939.561 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-13T03:02:19.966Z,1765594939.966 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-13T03:02:19.966Z,1765594939.966 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-13T03:02:19.966Z,1765594939.966 [keepstation:NeedComms:setInactive] Stopped
2025-12-13T03:02:19.967Z,1765594939.967 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-13T03:02:19.967Z,1765594939.967 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-13T03:02:19.967Z,1765594939.967 [keepstation:NeedComms] Stopped
2025-12-13T03:02:19.967Z,1765594939.967 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-13T03:02:19.967Z,1765594939.967 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-13T03:02:19.972Z,1765594939.972 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-13T03:02:20.379Z,1765594940.379 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-12-13T03:02:20.379Z,1765594940.379 [keepstation:OnStation] Stopped
2025-12-13T03:02:20.379Z,1765594940.379 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-12-13T03:02:20.385Z,1765594940.385 [keepstation:KeepStation] Running Loop=1
2025-12-13T03:02:20.385Z,1765594940.385 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-12-13T03:02:20.385Z,1765594940.385 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-13T03:02:20.385Z,1765594940.385 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-12-13T03:02:20.385Z,1765594940.385 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-12-13T03:02:20.386Z,1765594940.386 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-12-13T03:02:20.386Z,1765594940.386 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-12-13T03:02:20.386Z,1765594940.386 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-12-13T03:02:20.392Z,1765594940.392 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-13T03:02:20.392Z,1765594940.392 [keepstation:SurfaceComms:B] Stopped
2025-12-13T03:02:20.392Z,1765594940.392 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-13T03:02:20.392Z,1765594940.392 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-13T03:02:20.393Z,1765594940.393 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-13T03:02:20.393Z,1765594940.393 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-13T03:02:20.393Z,1765594940.393 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T03:02:20.393Z,1765594940.393 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-13T03:02:20.756Z,1765594940.756 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-13T03:02:20.758Z,1765594940.758 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-13T03:02:20.759Z,1765594940.759 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-13T03:02:20.759Z,1765594940.759 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-13T03:02:20.759Z,1765594940.759 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T03:02:20.759Z,1765594940.759 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-13T03:02:20.759Z,1765594940.759 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-13T03:02:20.759Z,1765594940.759 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-13T03:02:20.759Z,1765594940.759 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-13T03:02:21.164Z,1765594941.164 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-13T03:02:21.164Z,1765594941.164 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-13T03:02:21.164Z,1765594941.164 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-13T03:02:21.164Z,1765594941.164 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-13T03:02:21.165Z,1765594941.165 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-13T03:02:21.165Z,1765594941.165 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-13T03:02:21.547Z,1765594941.547 [keepstation:SurfaceComms:setStation] Stopped
2025-12-13T03:02:21.547Z,1765594941.547 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-13T03:02:21.547Z,1765594941.547 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-13T03:02:21.547Z,1765594941.547 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-13T03:02:21.547Z,1765594941.547 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-13T03:02:21.547Z,1765594941.547 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-13T03:02:21.951Z,1765594941.951 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-13T03:02:21.951Z,1765594941.951 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-12-13T03:02:21.952Z,1765594941.952 [keepstation:SurfaceComms:setSink] Stopped
2025-12-13T03:02:21.956Z,1765594941.956 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-13T03:02:21.956Z,1765594941.956 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-13T03:02:21.956Z,1765594941.956 [keepstation:SurfaceComms] Stopped
2025-12-13T03:02:21.956Z,1765594941.956 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-13T03:02:22.354Z,1765594942.354 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-13T03:02:22.354Z,1765594942.354 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-13T03:02:22.354Z,1765594942.354 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-13T03:02:22.354Z,1765594942.354 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-13T03:02:22.354Z,1765594942.354 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-13T03:02:22.354Z,1765594942.354 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-13T03:02:22.354Z,1765594942.354 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-12-13T03:02:22.354Z,1765594942.354 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-12-13T03:02:22.354Z,1765594942.354 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-12-13T03:02:22.354Z,1765594942.354 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T03:02:22.354Z,1765594942.354 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-12-13T03:02:22.355Z,1765594942.355 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-13T03:02:22.355Z,1765594942.355 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-12-13T03:02:22.355Z,1765594942.355 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-12-13T03:02:22.355Z,1765594942.355 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-12-13T03:02:22.355Z,1765594942.355 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m
2025-12-13T03:02:22.355Z,1765594942.355 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-12-13T03:02:22.380Z,1765594942.380 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-12-13T03:02:22.380Z,1765594942.380 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-12-13T03:02:51.465Z,1765594971.465 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-13T03:03:48.468Z,1765595028.468 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T03:04:19.592Z,1765595059.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T03:04:33.512Z,1765595073.512 [Radio_Surface](INFO): Powering down
2025-12-13T03:04:50.720Z,1765595090.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T03:04:51.724Z,1765595091.724 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-13T03:05:48.033Z,1765595148.033 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-12-13T03:05:48.034Z,1765595148.034 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-12-13T03:05:48.034Z,1765595148.034 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T03:05:48.034Z,1765595148.034 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-12-13T03:05:48.450Z,1765595148.450 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-12-13T03:05:48.450Z,1765595148.450 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T03:05:48.450Z,1765595148.450 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-12-13T03:05:48.451Z,1765595148.451 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-12-13T03:05:48.451Z,1765595148.451 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T03:05:48.451Z,1765595148.451 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-12-13T03:05:48.838Z,1765595148.838 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-12-13T03:05:48.839Z,1765595148.839 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T03:05:48.839Z,1765595148.839 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-12-13T03:05:48.839Z,1765595148.839 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T03:05:48.839Z,1765595148.839 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T03:05:48.839Z,1765595148.839 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-12-13T03:05:48.839Z,1765595148.839 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T03:05:48.840Z,1765595148.840 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-12-13T03:05:48.840Z,1765595148.840 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-13T03:05:48.840Z,1765595148.840 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-12-13T03:05:48.840Z,1765595148.840 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-12-13T03:05:48.840Z,1765595148.840 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-12-13T03:05:49.240Z,1765595149.240 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-13T03:05:49.240Z,1765595149.240 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-13T03:05:49.240Z,1765595149.240 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-13T03:05:49.240Z,1765595149.240 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-13T03:05:49.240Z,1765595149.240 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-13T03:05:49.241Z,1765595149.241 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-13T03:05:49.241Z,1765595149.241 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-13T03:05:49.241Z,1765595149.241 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-12-13T03:05:49.644Z,1765595149.644 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.17 mm (1 active estimators).
2025-12-13T03:05:55.989Z,1765595155.989 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739655
2025-12-13T03:06:02.162Z,1765595162.162 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T03:16:02.184Z,1765595762.184 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T03:23:26.737Z,1765596206.737 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T03:26:02.355Z,1765596362.355 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T03:32:08.126Z,1765596728.126 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T03:33:10.362Z,1765596790.362 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T03:35:00.249Z,1765596900.249 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-12-13T03:36:02.459Z,1765596962.459 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T03:41:19.185Z,1765597279.185 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T03:41:19.190Z,1765597279.190 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T03:46:02.807Z,1765597562.807 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T03:56:03.163Z,1765598163.163 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T04:06:03.368Z,1765598763.368 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T04:09:17.288Z,1765598957.288 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T04:13:12.822Z,1765599192.822 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T04:15:48.367Z,1765599348.367 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T04:16:03.718Z,1765599363.718 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T04:22:21.051Z,1765599741.051 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T04:22:21.054Z,1765599741.054 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T04:26:04.063Z,1765599964.063 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T04:32:19.388Z,1765600339.388 [keepstation:SurfaceComms] Running Loop=1
2025-12-13T04:32:19.389Z,1765600339.388 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-13T04:32:19.389Z,1765600339.389 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-13T04:32:19.389Z,1765600339.389 [keepstation:SurfaceComms:A] Stopped
2025-12-13T04:32:19.389Z,1765600339.389 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-13T04:32:19.389Z,1765600339.389 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-13T04:32:19.391Z,1765600339.391 [keepstation:NeedComms] Running Loop=1
2025-12-13T04:32:19.391Z,1765600339.391 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-13T04:32:19.391Z,1765600339.391 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-13T04:32:19.391Z,1765600339.391 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-13T04:32:19.392Z,1765600339.392 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-13T04:32:19.392Z,1765600339.392 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-13T04:32:19.393Z,1765600339.393 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-13T04:32:19.393Z,1765600339.393 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-13T04:32:19.393Z,1765600339.393 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-13T04:32:19.394Z,1765600339.394 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-13T04:32:19.394Z,1765600339.394 [keepstation:NeedComms:A] Running Loop=1
2025-12-13T04:32:19.395Z,1765600339.395 [keepstation:NeedComms:A](INFO): last time_fix was: 1765594937.000000 second since 1970/01/01T00:00:00Z
2025-12-13T04:32:19.395Z,1765600339.395 [keepstation:NeedComms:A] Stopped
2025-12-13T04:32:19.395Z,1765600339.395 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-13T04:32:19.395Z,1765600339.395 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-13T04:32:19.396Z,1765600339.396 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-13T04:32:19.790Z,1765600339.790 [keepstation:KeepStation:Dive] Stopped
2025-12-13T04:32:19.791Z,1765600339.791 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-12-13T04:32:19.791Z,1765600339.791 [keepstation:KeepStation:Dive:A] Stopped
2025-12-13T04:32:19.791Z,1765600339.791 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-12-13T04:32:19.791Z,1765600339.791 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-13T04:32:19.791Z,1765600339.791 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-13T04:32:19.791Z,1765600339.791 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-13T04:32:19.791Z,1765600339.791 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-13T04:32:19.792Z,1765600339.792 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-13T04:32:19.792Z,1765600339.792 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-13T04:32:19.795Z,1765600339.795 [keepstation:NeedComms:setActive:A] Stopped
2025-12-13T04:32:19.795Z,1765600339.795 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-13T04:32:20.203Z,1765600340.203 [keepstation:NeedComms:setActive:B] Stopped
2025-12-13T04:32:20.204Z,1765600340.204 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-13T04:32:20.204Z,1765600340.204 [keepstation:NeedComms:setActive] Stopped
2025-12-13T04:32:20.204Z,1765600340.204 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-13T04:36:04.461Z,1765600564.461 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T04:37:18.337Z,1765600638.337 [keepstation:NeedComms:D] Running Loop=1
2025-12-13T04:37:18.804Z,1765600638.804 [Radio_Surface](INFO): Powering up
2025-12-13T04:37:24.684Z,1765600644.684 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-13T04:37:54.804Z,1765600674.804 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T04:38:25.952Z,1765600705.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T04:38:57.076Z,1765600737.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T04:39:28.241Z,1765600768.241 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T04:39:36.944Z,1765600776.944 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003015
2025-12-13T04:39:56.829Z,1765600796.829 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T04:39:56.830Z,1765600796.830 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T04:40:37.678Z,1765600837.678 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T04:40:37.678Z,1765600837.678 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T04:41:26.543Z,1765600886.543 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T04:41:26.543Z,1765600886.543 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T04:41:56.850Z,1765600916.850 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T04:42:22.321Z,1765600942.321 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-12-13T04:42:29.158Z,1765600949.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,044227.00,A,3659.92261,N,12227.09422,W,0.117,81.95,131225,,,D*44
2025-12-13T04:42:29.160Z,1765600949.160 [NAL9602](INFO): GPS fix at 20251213T044227: (36.998710, -122.451570)
2025-12-13T04:42:29.192Z,1765600949.192 [UniversalFixResidualReporter](INFO): Fix residual: 76.6 %DT, over the last 1589.6 m. Residual distance 1217.5 m at bearing 124.4 degrees. Fix at (36.9987, -122.4516) with 262.5 m made good.
2025-12-13T04:42:29.195Z,1765600949.195 [keepstation:NeedComms:D] Stopped
2025-12-13T04:42:29.200Z,1765600949.200 [keepstation:NeedComms:E] Running Loop=1
2025-12-13T04:42:39.487Z,1765600959.487 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20251213T011500/Courier0006.lzma
2025-12-13T04:42:40.490Z,1765600960.490 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Courier0006.lzma.bak
2025-12-13T04:42:40.490Z,1765600960.490 [DataOverHttps](INFO): SBD MOMSN=26494639
2025-12-13T04:42:56.265Z,1765600976.265 [DataOverHttps](INFO): Sending 892 bytes from file Logs/20251213T011500/Express0007.lzma
2025-12-13T04:42:57.266Z,1765600977.266 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Express0007.lzma.bak
2025-12-13T04:42:57.266Z,1765600977.266 [DataOverHttps](INFO): SBD MOMSN=26494642
2025-12-13T04:42:58.693Z,1765600978.693 [keepstation:NeedComms:E] Stopped
2025-12-13T04:42:58.693Z,1765600978.693 [keepstation:NeedComms:F] Running Loop=1
2025-12-13T04:43:00.691Z,1765600980.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,044259.00,A,3659.92400,N,12227.09554,W,0.214,312.45,131225,,,D*78
2025-12-13T04:43:00.693Z,1765600980.693 [NAL9602](INFO): GPS fix at 20251213T044259: (36.998733, -122.451592)
2025-12-13T04:43:00.742Z,1765600980.742 [keepstation:NeedComms:F] Stopped
2025-12-13T04:43:00.743Z,1765600980.743 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-13T04:43:00.743Z,1765600980.743 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-13T04:43:00.743Z,1765600980.743 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-13T04:43:01.146Z,1765600981.146 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-13T04:43:01.146Z,1765600981.146 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-13T04:43:01.146Z,1765600981.146 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-13T04:43:01.146Z,1765600981.146 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-13T04:43:01.146Z,1765600981.146 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-13T04:43:01.146Z,1765600981.146 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-13T04:43:01.147Z,1765600981.147 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-13T04:43:01.662Z,1765600981.662 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-13T04:43:01.662Z,1765600981.662 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-13T04:43:01.662Z,1765600981.662 [keepstation:NeedComms:setInactive] Stopped
2025-12-13T04:43:01.662Z,1765600981.662 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-13T04:43:01.663Z,1765600981.663 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-13T04:43:01.663Z,1765600981.663 [keepstation:NeedComms] Stopped
2025-12-13T04:43:01.663Z,1765600981.663 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-13T04:43:01.663Z,1765600981.663 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-13T04:43:01.663Z,1765600981.663 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-13T04:43:01.990Z,1765600981.990 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-13T04:43:01.991Z,1765600981.991 [keepstation:SurfaceComms:B] Stopped
2025-12-13T04:43:01.991Z,1765600981.991 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-13T04:43:01.991Z,1765600981.991 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-13T04:43:01.991Z,1765600981.991 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-13T04:43:01.991Z,1765600981.991 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-13T04:43:01.991Z,1765600981.991 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T04:43:01.991Z,1765600981.991 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-13T04:43:02.350Z,1765600982.350 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-13T04:43:02.350Z,1765600982.350 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-13T04:43:02.350Z,1765600982.350 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-13T04:43:02.350Z,1765600982.350 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T04:43:02.350Z,1765600982.350 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-13T04:43:02.350Z,1765600982.350 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-13T04:43:02.350Z,1765600982.350 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-13T04:43:02.350Z,1765600982.350 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-13T04:43:02.765Z,1765600982.765 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-13T04:43:02.765Z,1765600982.765 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-13T04:43:02.765Z,1765600982.765 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-13T04:43:02.765Z,1765600982.765 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-13T04:43:02.765Z,1765600982.765 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-13T04:43:02.765Z,1765600982.765 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-13T04:43:03.145Z,1765600983.145 [keepstation:SurfaceComms:setStation] Stopped
2025-12-13T04:43:03.145Z,1765600983.145 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-13T04:43:03.145Z,1765600983.145 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-13T04:43:03.145Z,1765600983.145 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-13T04:43:03.145Z,1765600983.145 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-13T04:43:03.145Z,1765600983.145 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-13T04:43:03.552Z,1765600983.552 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-13T04:43:03.552Z,1765600983.552 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-12-13T04:43:03.552Z,1765600983.552 [keepstation:SurfaceComms:setSink] Stopped
2025-12-13T04:43:03.552Z,1765600983.552 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-13T04:43:03.553Z,1765600983.553 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-13T04:43:03.553Z,1765600983.553 [keepstation:SurfaceComms] Stopped
2025-12-13T04:43:03.553Z,1765600983.553 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-13T04:43:03.934Z,1765600983.934 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-13T04:43:03.934Z,1765600983.934 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-12-13T04:43:03.934Z,1765600983.934 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-12-13T04:43:03.935Z,1765600983.935 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-12-13T04:43:03.935Z,1765600983.935 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-12-13T04:43:03.935Z,1765600983.935 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-12-13T04:43:03.935Z,1765600983.935 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-12-13T04:43:03.935Z,1765600983.935 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T04:43:03.935Z,1765600983.935 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-12-13T04:43:03.972Z,1765600983.972 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-13T04:43:03.972Z,1765600983.972 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-12-13T04:43:03.972Z,1765600983.972 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-12-13T04:43:03.972Z,1765600983.972 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-12-13T04:43:03.973Z,1765600983.973 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m
2025-12-13T04:43:03.973Z,1765600983.973 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-12-13T04:43:03.973Z,1765600983.973 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-12-13T04:43:03.973Z,1765600983.973 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-12-13T04:43:32.615Z,1765601012.615 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-13T04:44:30.236Z,1765601070.236 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T04:44:36.901Z,1765601076.901 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T04:44:48.973Z,1765601088.973 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T04:45:01.364Z,1765601101.364 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T04:45:12.748Z,1765601112.748 [Radio_Surface](INFO): Powering down
2025-12-13T04:45:32.489Z,1765601132.489 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T04:45:33.493Z,1765601133.493 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-13T04:46:04.522Z,1765601164.522 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T04:46:17.929Z,1765601177.929 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-12-13T04:46:17.929Z,1765601177.929 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-12-13T04:46:17.930Z,1765601177.930 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T04:46:17.930Z,1765601177.930 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-12-13T04:46:18.267Z,1765601178.267 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-12-13T04:46:18.267Z,1765601178.267 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T04:46:18.267Z,1765601178.267 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-12-13T04:46:18.267Z,1765601178.267 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-12-13T04:46:18.267Z,1765601178.267 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T04:46:18.267Z,1765601178.267 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-12-13T04:46:18.689Z,1765601178.689 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-12-13T04:46:18.689Z,1765601178.689 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T04:46:18.690Z,1765601178.690 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-12-13T04:46:18.690Z,1765601178.690 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T04:46:18.690Z,1765601178.690 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T04:46:18.690Z,1765601178.690 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-12-13T04:46:18.690Z,1765601178.690 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T04:46:18.690Z,1765601178.690 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-12-13T04:46:18.691Z,1765601178.691 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-13T04:46:18.691Z,1765601178.691 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-12-13T04:46:18.691Z,1765601178.691 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-12-13T04:46:18.691Z,1765601178.691 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-12-13T04:46:19.092Z,1765601179.092 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-13T04:46:19.092Z,1765601179.092 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-13T04:46:19.093Z,1765601179.093 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-13T04:46:19.093Z,1765601179.093 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-13T04:46:19.093Z,1765601179.093 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-13T04:46:19.093Z,1765601179.093 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-13T04:46:19.093Z,1765601179.093 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-13T04:46:19.094Z,1765601179.094 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-12-13T04:46:37.757Z,1765601197.757 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743622
2025-12-13T04:54:14.124Z,1765601654.124 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T04:54:57.408Z,1765601697.408 [ElevatorServo](ERROR): getPosition uart error serial timeout
2025-12-13T04:54:57.408Z,1765601697.408 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout
2025-12-13T04:54:57.408Z,1765601697.408 [ElevatorServo] Communications Fault, FailCount= 1
2025-12-13T04:54:57.408Z,1765601697.408 [ElevatorServo](ERROR): Communications Fault
2025-12-13T04:54:57.427Z,1765601697.427 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2025-12-13T04:54:57.776Z,1765601697.776 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-12-13T04:54:57.776Z,1765601697.776 [ElevatorServo](INFO): Powering down
2025-12-13T04:54:58.565Z,1765601698.565 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-12-13T04:54:58.685Z,1765601698.685 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-12-13T04:54:58.703Z,1765601698.703 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-12-13T04:54:58.703Z,1765601698.703 [ElevatorServo] No Fault, FailCount= 1
2025-12-13T04:56:04.794Z,1765601764.794 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T05:03:23.245Z,1765602203.245 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T05:03:23.249Z,1765602203.249 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T05:06:04.845Z,1765602364.845 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T05:10:43.223Z,1765602643.223 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-12-13T05:16:05.211Z,1765602965.211 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T05:20:46.853Z,1765603246.853 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-12-13T05:23:03.753Z,1765603383.753 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-12-13T05:26:05.554Z,1765603565.554 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T05:36:05.913Z,1765604165.913 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T05:44:25.248Z,1765604665.248 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T05:44:25.252Z,1765604665.252 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T05:46:06.250Z,1765604766.250 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T05:56:06.595Z,1765605366.595 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T06:01:37.467Z,1765605697.467 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-12-13T06:05:34.624Z,1765605934.624 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T06:06:06.938Z,1765605966.938 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T06:06:41.693Z,1765606001.693 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T06:13:01.051Z,1765606381.051 [keepstation:SurfaceComms] Running Loop=1
2025-12-13T06:13:01.051Z,1765606381.051 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-13T06:13:01.052Z,1765606381.052 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-13T06:13:01.052Z,1765606381.052 [keepstation:SurfaceComms:A] Stopped
2025-12-13T06:13:01.052Z,1765606381.052 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-13T06:13:01.053Z,1765606381.053 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-13T06:13:01.054Z,1765606381.054 [keepstation:NeedComms] Running Loop=1
2025-12-13T06:13:01.054Z,1765606381.054 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-13T06:13:01.054Z,1765606381.054 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-13T06:13:01.054Z,1765606381.054 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-13T06:13:01.055Z,1765606381.055 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-13T06:13:01.055Z,1765606381.055 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-13T06:13:01.056Z,1765606381.056 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-13T06:13:01.076Z,1765606381.076 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-13T06:13:01.077Z,1765606381.077 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-13T06:13:01.077Z,1765606381.077 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-13T06:13:01.077Z,1765606381.077 [keepstation:NeedComms:A] Running Loop=1
2025-12-13T06:13:01.079Z,1765606381.079 [keepstation:NeedComms:A](INFO): last time_fix was: 1765600979.000000 second since 1970/01/01T00:00:00Z
2025-12-13T06:13:01.079Z,1765606381.079 [keepstation:NeedComms:A] Stopped
2025-12-13T06:13:01.079Z,1765606381.079 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-13T06:13:01.079Z,1765606381.079 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-13T06:13:01.079Z,1765606381.079 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-13T06:13:01.455Z,1765606381.455 [keepstation:KeepStation:Dive] Stopped
2025-12-13T06:13:01.455Z,1765606381.455 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-12-13T06:13:01.455Z,1765606381.455 [keepstation:KeepStation:Dive:A] Stopped
2025-12-13T06:13:01.455Z,1765606381.455 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-12-13T06:13:01.456Z,1765606381.456 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-13T06:13:01.456Z,1765606381.456 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-13T06:13:01.456Z,1765606381.456 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-13T06:13:01.456Z,1765606381.456 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-13T06:13:01.456Z,1765606381.456 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-13T06:13:01.456Z,1765606381.456 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-13T06:13:01.459Z,1765606381.459 [keepstation:NeedComms:setActive:A] Stopped
2025-12-13T06:13:01.459Z,1765606381.459 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-13T06:13:01.866Z,1765606381.866 [keepstation:NeedComms:setActive:B] Stopped
2025-12-13T06:13:01.866Z,1765606381.866 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-13T06:13:01.866Z,1765606381.866 [keepstation:NeedComms:setActive] Stopped
2025-12-13T06:13:01.867Z,1765606381.867 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-13T06:17:40.182Z,1765606660.182 [keepstation:NeedComms:D] Running Loop=1
2025-12-13T06:17:40.769Z,1765606660.769 [Radio_Surface](INFO): Powering up
2025-12-13T06:17:46.468Z,1765606666.468 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-13T06:18:12.900Z,1765606692.900 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-12-13T06:18:12.976Z,1765606692.976 [NAL9602](ERROR): received:
+CSQ:1
OK437, 2, 0, 0, 0
OK
2025-12-13T06:18:16.608Z,1765606696.608 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T06:18:29.862Z,1765606709.862 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T06:18:29.863Z,1765606709.863 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T06:18:47.736Z,1765606727.736 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T06:19:15.109Z,1765606755.109 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T06:19:15.109Z,1765606755.109 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T06:19:18.860Z,1765606758.860 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T06:19:49.984Z,1765606789.984 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T06:19:58.476Z,1765606798.476 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003022
2025-12-13T06:20:35.502Z,1765606835.502 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T06:20:35.503Z,1765606835.503 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T06:21:26.103Z,1765606886.103 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-12-13T06:21:32.481Z,1765606892.481 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T06:21:34.083Z,1765606894.083 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T06:21:34.083Z,1765606894.083 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T06:22:22.157Z,1765606942.157 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T06:22:22.157Z,1765606942.157 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T06:22:44.378Z,1765606964.378 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-12-13T06:22:50.037Z,1765606970.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,062248.00,A,3700.32940,N,12226.72254,W,1.788,291.88,131225,,,D*72
2025-12-13T06:22:50.039Z,1765606970.039 [NAL9602](INFO): GPS fix at 20251213T062248: (37.005490, -122.445376)
2025-12-13T06:22:50.051Z,1765606970.051 [UniversalFixResidualReporter](INFO): Fix residual: 92.8 %DT, over the last 1348.1 m. Residual distance 1250.9 m at bearing 154.5 degrees. Fix at (37.0055, -122.4454) with 932.3 m made good.
2025-12-13T06:22:50.054Z,1765606970.054 [keepstation:NeedComms:D] Stopped
2025-12-13T06:22:50.055Z,1765606970.055 [keepstation:NeedComms:E] Running Loop=1
2025-12-13T06:22:57.592Z,1765606977.592 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20251213T011500/Courier0009.lzma
2025-12-13T06:22:58.590Z,1765606978.590 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Courier0009.lzma.bak
2025-12-13T06:22:58.590Z,1765606978.590 [DataOverHttps](INFO): SBD MOMSN=26494795
2025-12-13T06:23:14.665Z,1765606994.665 [DataOverHttps](INFO): Sending 870 bytes from file Logs/20251213T011500/Express0010.lzma
2025-12-13T06:23:15.666Z,1765606995.666 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Express0010.lzma.bak
2025-12-13T06:23:15.666Z,1765606995.666 [DataOverHttps](INFO): SBD MOMSN=26494801
2025-12-13T06:23:17.120Z,1765606997.120 [keepstation:NeedComms:E] Stopped
2025-12-13T06:23:17.120Z,1765606997.120 [keepstation:NeedComms:F] Running Loop=1
2025-12-13T06:23:19.123Z,1765606999.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,062317.00,A,3700.33222,N,12226.72665,W,0.739,336.11,131225,,,D*76
2025-12-13T06:23:19.126Z,1765606999.126 [NAL9602](INFO): GPS fix at 20251213T062317: (37.005537, -122.445444)
2025-12-13T06:23:19.175Z,1765606999.175 [keepstation:NeedComms:F] Stopped
2025-12-13T06:23:19.175Z,1765606999.175 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-13T06:23:19.175Z,1765606999.175 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-13T06:23:19.194Z,1765606999.194 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-13T06:23:19.574Z,1765606999.574 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-13T06:23:19.574Z,1765606999.574 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-13T06:23:19.574Z,1765606999.574 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-13T06:23:19.574Z,1765606999.574 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-13T06:23:19.574Z,1765606999.574 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-13T06:23:19.574Z,1765606999.574 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-13T06:23:19.574Z,1765606999.574 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-13T06:23:19.959Z,1765606999.959 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-13T06:23:19.959Z,1765606999.959 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-13T06:23:19.959Z,1765606999.959 [keepstation:NeedComms:setInactive] Stopped
2025-12-13T06:23:19.959Z,1765606999.959 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-13T06:23:19.984Z,1765606999.984 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-13T06:23:19.984Z,1765606999.984 [keepstation:NeedComms] Stopped
2025-12-13T06:23:19.984Z,1765606999.984 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-13T06:23:19.985Z,1765606999.985 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-13T06:23:19.985Z,1765606999.985 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-13T06:23:20.357Z,1765607000.357 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-13T06:23:20.357Z,1765607000.357 [keepstation:SurfaceComms:B] Stopped
2025-12-13T06:23:20.357Z,1765607000.357 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-13T06:23:20.358Z,1765607000.358 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-13T06:23:20.358Z,1765607000.358 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-13T06:23:20.358Z,1765607000.358 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-13T06:23:20.358Z,1765607000.358 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T06:23:20.358Z,1765607000.358 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-13T06:23:20.773Z,1765607000.773 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-13T06:23:20.774Z,1765607000.774 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-13T06:23:20.774Z,1765607000.774 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-13T06:23:20.774Z,1765607000.774 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T06:23:20.774Z,1765607000.774 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-13T06:23:20.774Z,1765607000.774 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-13T06:23:20.774Z,1765607000.774 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-13T06:23:20.774Z,1765607000.774 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-13T06:23:21.178Z,1765607001.178 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-13T06:23:21.178Z,1765607001.178 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-13T06:23:21.178Z,1765607001.178 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-13T06:23:21.178Z,1765607001.178 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-13T06:23:21.178Z,1765607001.178 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-13T06:23:21.178Z,1765607001.178 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-13T06:23:21.671Z,1765607001.671 [keepstation:SurfaceComms:setStation] Stopped
2025-12-13T06:23:21.676Z,1765607001.676 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-13T06:23:21.676Z,1765607001.676 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-13T06:23:21.676Z,1765607001.676 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-13T06:23:21.676Z,1765607001.676 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-13T06:23:21.676Z,1765607001.676 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-13T06:23:22.010Z,1765607002.010 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-13T06:23:22.010Z,1765607002.010 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-12-13T06:23:22.010Z,1765607002.010 [keepstation:SurfaceComms:setSink] Stopped
2025-12-13T06:23:22.010Z,1765607002.010 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-13T06:23:22.010Z,1765607002.010 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-13T06:23:22.010Z,1765607002.010 [keepstation:SurfaceComms] Stopped
2025-12-13T06:23:22.010Z,1765607002.010 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-13T06:23:22.397Z,1765607002.397 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-13T06:23:22.397Z,1765607002.397 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-12-13T06:23:22.397Z,1765607002.397 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-12-13T06:23:22.397Z,1765607002.397 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-12-13T06:23:22.398Z,1765607002.398 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-12-13T06:23:22.398Z,1765607002.398 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-12-13T06:23:22.398Z,1765607002.398 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-12-13T06:23:22.398Z,1765607002.398 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T06:23:22.398Z,1765607002.398 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-12-13T06:23:22.398Z,1765607002.398 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-13T06:23:22.398Z,1765607002.398 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-12-13T06:23:22.399Z,1765607002.399 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-12-13T06:23:22.399Z,1765607002.399 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-12-13T06:23:22.399Z,1765607002.399 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m
2025-12-13T06:23:22.399Z,1765607002.399 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-12-13T06:23:22.399Z,1765607002.399 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-12-13T06:23:22.424Z,1765607002.424 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-12-13T06:23:51.441Z,1765607031.441 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-13T06:25:27.226Z,1765607127.226 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T06:25:27.232Z,1765607127.232 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T06:27:21.624Z,1765607241.624 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T06:27:49.216Z,1765607269.216 [Radio_Surface](INFO): Powering down
2025-12-13T06:27:52.756Z,1765607272.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T06:27:53.760Z,1765607273.760 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-13T06:29:00.530Z,1765607340.530 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-12-13T06:29:00.530Z,1765607340.530 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-12-13T06:29:00.531Z,1765607340.531 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T06:29:00.531Z,1765607340.531 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-12-13T06:29:00.926Z,1765607340.926 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-12-13T06:29:00.927Z,1765607340.927 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T06:29:00.927Z,1765607340.927 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-12-13T06:29:00.927Z,1765607340.927 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-12-13T06:29:00.927Z,1765607340.927 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T06:29:00.927Z,1765607340.927 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-12-13T06:29:01.336Z,1765607341.336 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-12-13T06:29:01.336Z,1765607341.336 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T06:29:01.337Z,1765607341.337 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-12-13T06:29:01.337Z,1765607341.337 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T06:29:01.337Z,1765607341.337 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T06:29:01.337Z,1765607341.337 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-12-13T06:29:01.337Z,1765607341.337 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T06:29:01.337Z,1765607341.337 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-12-13T06:29:01.338Z,1765607341.338 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-13T06:29:01.338Z,1765607341.338 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-12-13T06:29:01.338Z,1765607341.338 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-12-13T06:29:01.338Z,1765607341.338 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-12-13T06:29:01.737Z,1765607341.737 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-13T06:29:01.737Z,1765607341.737 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-13T06:29:01.737Z,1765607341.737 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-13T06:29:01.737Z,1765607341.737 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-13T06:29:01.737Z,1765607341.737 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-13T06:29:01.737Z,1765607341.737 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-13T06:29:01.738Z,1765607341.738 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-13T06:29:01.738Z,1765607341.738 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-12-13T06:29:29.144Z,1765607369.144 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735168
2025-12-13T06:35:19.392Z,1765607719.392 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-13T06:35:23.750Z,1765607723.750 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.010144
CHAN A1 (24V): 0.038242
CHAN A2 (12V): -0.003742
CHAN A3 (5V): -0.015935
CHAN B0 (3.3V): -0.004650
CHAN B1 (3.15aV): -0.001515
CHAN B2 (3.15bV): -0.001028
CHAN B3 (GND): -0.009017
OPEN: 0.011801
Full Scale: +/- 1 mA
2025-12-13T06:38:10.574Z,1765607890.574 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-12-13T07:00:22.013Z,1765609222.013 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-12-13T07:06:28.787Z,1765609588.787 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T07:06:28.790Z,1765609588.790 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T07:44:16.036Z,1765611856.036 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T07:47:30.758Z,1765612050.758 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T07:47:30.762Z,1765612050.762 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T07:53:19.438Z,1765612399.438 [keepstation:SurfaceComms] Running Loop=1
2025-12-13T07:53:19.439Z,1765612399.439 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-13T07:53:19.439Z,1765612399.439 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-13T07:53:19.439Z,1765612399.439 [keepstation:SurfaceComms:A] Stopped
2025-12-13T07:53:19.439Z,1765612399.439 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-13T07:53:19.439Z,1765612399.439 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-13T07:53:19.441Z,1765612399.441 [keepstation:NeedComms] Running Loop=1
2025-12-13T07:53:19.441Z,1765612399.441 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-13T07:53:19.442Z,1765612399.442 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-13T07:53:19.442Z,1765612399.442 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-13T07:53:19.442Z,1765612399.442 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-13T07:53:19.442Z,1765612399.442 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-13T07:53:19.443Z,1765612399.443 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-13T07:53:19.443Z,1765612399.443 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-13T07:53:19.443Z,1765612399.443 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-13T07:53:19.444Z,1765612399.444 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-13T07:53:19.444Z,1765612399.444 [keepstation:NeedComms:A] Running Loop=1
2025-12-13T07:53:19.446Z,1765612399.446 [keepstation:NeedComms:A](INFO): last time_fix was: 1765606997.000000 second since 1970/01/01T00:00:00Z
2025-12-13T07:53:19.446Z,1765612399.446 [keepstation:NeedComms:A] Stopped
2025-12-13T07:53:19.446Z,1765612399.446 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-13T07:53:19.446Z,1765612399.446 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-13T07:53:19.446Z,1765612399.446 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-13T07:53:19.855Z,1765612399.855 [keepstation:KeepStation:Dive] Stopped
2025-12-13T07:53:19.855Z,1765612399.855 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-12-13T07:53:19.855Z,1765612399.855 [keepstation:KeepStation:Dive:A] Stopped
2025-12-13T07:53:19.855Z,1765612399.855 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-12-13T07:53:19.856Z,1765612399.856 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-13T07:53:19.856Z,1765612399.856 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-13T07:53:19.856Z,1765612399.856 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-13T07:53:19.856Z,1765612399.856 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-13T07:53:19.856Z,1765612399.856 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-13T07:53:19.856Z,1765612399.856 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-13T07:53:19.859Z,1765612399.859 [keepstation:NeedComms:setActive:A] Stopped
2025-12-13T07:53:19.859Z,1765612399.859 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-13T07:53:20.269Z,1765612400.269 [keepstation:NeedComms:setActive:B] Stopped
2025-12-13T07:53:20.270Z,1765612400.270 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-13T07:53:20.270Z,1765612400.270 [keepstation:NeedComms:setActive] Stopped
2025-12-13T07:53:20.270Z,1765612400.270 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-13T07:57:56.661Z,1765612676.661 [keepstation:NeedComms:D] Running Loop=1
2025-12-13T07:57:57.221Z,1765612677.221 [Radio_Surface](INFO): Powering up
2025-12-13T07:58:03.292Z,1765612683.292 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-13T07:58:29.367Z,1765612709.367 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-12-13T07:58:29.444Z,1765612709.444 [NAL9602](ERROR): received:
+CSQ:0
OK437, 2, 0, 0, 0
OK
2025-12-13T07:58:33.417Z,1765612713.417 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T07:59:02.093Z,1765612742.093 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T07:59:02.093Z,1765612742.093 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T07:59:04.576Z,1765612744.576 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T07:59:35.716Z,1765612775.716 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T07:59:44.918Z,1765612784.918 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T07:59:44.918Z,1765612784.918 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T08:00:06.848Z,1765612806.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T08:00:15.444Z,1765612815.444 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003024
2025-12-13T08:00:37.034Z,1765612837.034 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T08:00:37.034Z,1765612837.034 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T08:03:04.088Z,1765612984.088 [NAL9602](INFO): SBD MO Status=2, MOMSN=24437, MT Status=2, MTMSN=0
2025-12-13T08:03:04.088Z,1765612984.088 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T08:03:04.495Z,1765612984.495 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-12-13T08:03:05.707Z,1765612985.707 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,080303.00,A,3700.18164,N,12226.35675,W,1.127,335.54,131225,,,D*7F
2025-12-13T08:03:05.709Z,1765612985.709 [NAL9602](INFO): GPS fix at 20251213T080303: (37.003027, -122.439279)
2025-12-13T08:03:05.721Z,1765612985.721 [UniversalFixResidualReporter](INFO): Fix residual: 60.7 %DT, over the last 1676.3 m. Residual distance 1017.3 m at bearing 174.6 degrees. Fix at (37.0030, -122.4393) with 614.5 m made good.
2025-12-13T08:03:05.725Z,1765612985.725 [keepstation:NeedComms:D] Stopped
2025-12-13T08:03:05.725Z,1765612985.725 [keepstation:NeedComms:E] Running Loop=1
2025-12-13T08:03:14.535Z,1765612994.535 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20251213T011500/Courier0012.lzma
2025-12-13T08:03:15.538Z,1765612995.538 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Courier0012.lzma.bak
2025-12-13T08:03:15.538Z,1765612995.538 [DataOverHttps](INFO): SBD MOMSN=26494838
2025-12-13T08:03:31.622Z,1765613011.622 [DataOverHttps](INFO): Sending 1041 bytes from file Logs/20251213T011500/Express0013.lzma
2025-12-13T08:03:32.622Z,1765613012.622 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Express0013.lzma.bak
2025-12-13T08:03:32.622Z,1765613012.622 [DataOverHttps](INFO): SBD MOMSN=26494841
2025-12-13T08:03:34.000Z,1765613014.000 [keepstation:NeedComms:E] Stopped
2025-12-13T08:03:34.001Z,1765613014.001 [keepstation:NeedComms:F] Running Loop=1
2025-12-13T08:03:36.272Z,1765613016.272 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,080334.00,A,3700.18636,N,12226.36036,W,1.613,306.79,131225,,,D*76
2025-12-13T08:03:36.274Z,1765613016.274 [NAL9602](INFO): GPS fix at 20251213T080334: (37.003106, -122.439339)
2025-12-13T08:03:36.319Z,1765613016.319 [keepstation:NeedComms:F] Stopped
2025-12-13T08:03:36.319Z,1765613016.319 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-13T08:03:36.319Z,1765613016.319 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-13T08:03:36.319Z,1765613016.319 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-13T08:03:36.511Z,1765613016.511 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-13T08:03:36.511Z,1765613016.511 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-13T08:03:36.511Z,1765613016.511 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-13T08:03:36.511Z,1765613016.511 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-13T08:03:36.511Z,1765613016.511 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-13T08:03:36.511Z,1765613016.511 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-13T08:03:36.511Z,1765613016.511 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-13T08:03:36.856Z,1765613016.856 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-13T08:03:36.857Z,1765613016.857 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-13T08:03:36.857Z,1765613016.857 [keepstation:NeedComms:setInactive] Stopped
2025-12-13T08:03:36.857Z,1765613016.857 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-13T08:03:36.857Z,1765613016.857 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-13T08:03:36.858Z,1765613016.858 [keepstation:NeedComms] Stopped
2025-12-13T08:03:36.858Z,1765613016.858 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-13T08:03:36.858Z,1765613016.858 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-13T08:03:36.858Z,1765613016.858 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-13T08:03:37.272Z,1765613017.272 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-13T08:03:37.272Z,1765613017.272 [keepstation:SurfaceComms:B] Stopped
2025-12-13T08:03:37.273Z,1765613017.273 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-13T08:03:37.273Z,1765613017.273 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-13T08:03:37.273Z,1765613017.273 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-13T08:03:37.273Z,1765613017.273 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-13T08:03:37.273Z,1765613017.273 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T08:03:37.273Z,1765613017.273 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-13T08:03:37.652Z,1765613017.652 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-13T08:03:37.653Z,1765613017.653 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-13T08:03:37.653Z,1765613017.653 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-13T08:03:37.653Z,1765613017.653 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T08:03:37.653Z,1765613017.653 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-13T08:03:37.653Z,1765613017.653 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-13T08:03:37.653Z,1765613017.653 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-13T08:03:37.653Z,1765613017.653 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-13T08:03:38.059Z,1765613018.059 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-13T08:03:38.059Z,1765613018.059 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-13T08:03:38.059Z,1765613018.059 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-13T08:03:38.059Z,1765613018.059 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-13T08:03:38.059Z,1765613018.059 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-13T08:03:38.059Z,1765613018.059 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-13T08:03:38.457Z,1765613018.457 [keepstation:SurfaceComms:setStation] Stopped
2025-12-13T08:03:38.457Z,1765613018.457 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-13T08:03:38.457Z,1765613018.457 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-13T08:03:38.457Z,1765613018.457 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-13T08:03:38.458Z,1765613018.458 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-13T08:03:38.458Z,1765613018.458 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-13T08:03:38.849Z,1765613018.849 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-13T08:03:38.849Z,1765613018.849 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-12-13T08:03:38.849Z,1765613018.849 [keepstation:SurfaceComms:setSink] Stopped
2025-12-13T08:03:38.849Z,1765613018.849 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-13T08:03:38.850Z,1765613018.850 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-13T08:03:38.850Z,1765613018.850 [keepstation:SurfaceComms] Stopped
2025-12-13T08:03:38.850Z,1765613018.850 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-13T08:03:39.262Z,1765613019.262 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-13T08:03:39.263Z,1765613019.263 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-12-13T08:03:39.263Z,1765613019.263 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-12-13T08:03:39.263Z,1765613019.263 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-12-13T08:03:39.263Z,1765613019.263 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-12-13T08:03:39.263Z,1765613019.263 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-12-13T08:03:39.263Z,1765613019.263 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-12-13T08:03:39.268Z,1765613019.268 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T08:03:39.268Z,1765613019.268 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-12-13T08:03:39.268Z,1765613019.268 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-13T08:03:39.268Z,1765613019.268 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-12-13T08:03:39.268Z,1765613019.268 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-12-13T08:03:39.268Z,1765613019.268 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-12-13T08:03:39.269Z,1765613019.269 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m
2025-12-13T08:03:39.269Z,1765613019.269 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-12-13T08:03:39.269Z,1765613019.269 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-12-13T08:03:39.269Z,1765613019.269 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-12-13T08:04:08.726Z,1765613048.726 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-13T08:06:06.676Z,1765613166.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T08:06:37.804Z,1765613197.804 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T08:06:57.824Z,1765613217.824 [Radio_Surface](INFO): Powering down
2025-12-13T08:07:08.944Z,1765613228.944 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T08:07:09.948Z,1765613229.948 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-13T08:07:53.359Z,1765613273.359 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-12-13T08:07:53.359Z,1765613273.359 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-12-13T08:07:53.359Z,1765613273.359 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T08:07:53.359Z,1765613273.359 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-12-13T08:07:53.767Z,1765613273.767 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-12-13T08:07:53.767Z,1765613273.767 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T08:07:53.767Z,1765613273.767 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-12-13T08:07:53.767Z,1765613273.767 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-12-13T08:07:53.767Z,1765613273.767 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T08:07:53.767Z,1765613273.767 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-12-13T08:07:54.169Z,1765613274.169 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-12-13T08:07:54.169Z,1765613274.169 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T08:07:54.169Z,1765613274.169 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-12-13T08:07:54.169Z,1765613274.169 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T08:07:54.170Z,1765613274.170 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T08:07:54.170Z,1765613274.170 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-12-13T08:07:54.170Z,1765613274.170 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T08:07:54.170Z,1765613274.170 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-12-13T08:07:54.170Z,1765613274.170 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-13T08:07:54.170Z,1765613274.170 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-12-13T08:07:54.170Z,1765613274.170 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-12-13T08:07:54.170Z,1765613274.170 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-12-13T08:07:54.581Z,1765613274.581 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-13T08:07:54.581Z,1765613274.581 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-13T08:07:54.581Z,1765613274.581 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-13T08:07:54.581Z,1765613274.581 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-13T08:07:54.581Z,1765613274.581 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-13T08:07:54.582Z,1765613274.582 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-13T08:07:54.582Z,1765613274.582 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-13T08:07:54.582Z,1765613274.582 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-12-13T08:08:14.208Z,1765613294.208 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755310
2025-12-13T08:09:32.366Z,1765613372.366 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T08:19:24.338Z,1765613964.338 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T08:28:32.826Z,1765614512.826 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T08:28:32.839Z,1765614512.839 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T08:31:40.715Z,1765614700.715 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-12-13T08:31:41.117Z,1765614701.117 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.17 mm.
2025-12-13T09:00:26.595Z,1765616426.595 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T09:07:09.391Z,1765616829.391 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T09:09:34.825Z,1765616974.825 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T09:09:34.828Z,1765616974.828 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T09:33:27.417Z,1765618407.417 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T09:33:36.313Z,1765618416.313 [keepstation:SurfaceComms] Running Loop=1
2025-12-13T09:33:36.313Z,1765618416.313 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-13T09:33:36.313Z,1765618416.313 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-13T09:33:36.313Z,1765618416.313 [keepstation:SurfaceComms:A] Stopped
2025-12-13T09:33:36.313Z,1765618416.313 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-13T09:33:36.314Z,1765618416.314 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-13T09:33:36.315Z,1765618416.315 [keepstation:NeedComms] Running Loop=1
2025-12-13T09:33:36.315Z,1765618416.315 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-13T09:33:36.315Z,1765618416.315 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-13T09:33:36.315Z,1765618416.315 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-13T09:33:36.316Z,1765618416.316 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-13T09:33:36.317Z,1765618416.317 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-13T09:33:36.317Z,1765618416.317 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-13T09:33:36.317Z,1765618416.317 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-13T09:33:36.318Z,1765618416.318 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-13T09:33:36.318Z,1765618416.318 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-13T09:33:36.318Z,1765618416.318 [keepstation:NeedComms:A] Running Loop=1
2025-12-13T09:33:36.320Z,1765618416.320 [keepstation:NeedComms:A](INFO): last time_fix was: 1765613014.000000 second since 1970/01/01T00:00:00Z
2025-12-13T09:33:36.320Z,1765618416.320 [keepstation:NeedComms:A] Stopped
2025-12-13T09:33:36.320Z,1765618416.320 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-13T09:33:36.320Z,1765618416.320 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-13T09:33:36.320Z,1765618416.320 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-13T09:33:36.721Z,1765618416.721 [keepstation:KeepStation:Dive] Stopped
2025-12-13T09:33:36.721Z,1765618416.721 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-12-13T09:33:36.721Z,1765618416.721 [keepstation:KeepStation:Dive:A] Stopped
2025-12-13T09:33:36.721Z,1765618416.721 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-12-13T09:33:36.721Z,1765618416.721 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-13T09:33:36.721Z,1765618416.721 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-13T09:33:36.722Z,1765618416.722 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-13T09:33:36.722Z,1765618416.722 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-13T09:33:36.722Z,1765618416.722 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-13T09:33:36.722Z,1765618416.722 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-13T09:33:36.725Z,1765618416.725 [keepstation:NeedComms:setActive:A] Stopped
2025-12-13T09:33:36.725Z,1765618416.725 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-13T09:33:37.130Z,1765618417.130 [keepstation:NeedComms:setActive:B] Stopped
2025-12-13T09:33:37.130Z,1765618417.130 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-13T09:33:37.130Z,1765618417.130 [keepstation:NeedComms:setActive] Stopped
2025-12-13T09:33:37.130Z,1765618417.130 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-13T09:36:47.834Z,1765618607.834 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-12-13T09:38:17.451Z,1765618697.451 [keepstation:NeedComms:D] Running Loop=1
2025-12-13T09:38:17.844Z,1765618697.844 [Radio_Surface](INFO): Powering up
2025-12-13T09:38:23.504Z,1765618703.504 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-13T09:38:53.624Z,1765618733.624 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T09:39:00.095Z,1765618740.095 [NAL9602](INFO): SBD MO Status=0, MOMSN=24437, MT Status=0, MTMSN=0
2025-12-13T09:39:00.095Z,1765618740.095 [NAL9602](INFO): No messages in MT queue
2025-12-13T09:39:01.305Z,1765618741.305 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,093859.00,A,3659.78069,N,12226.40155,W,1.225,344.02,131225,,,A*78
2025-12-13T09:39:01.308Z,1765618741.308 [NAL9602](INFO): GPS fix at 20251213T093859: (36.996345, -122.440026)
2025-12-13T09:39:01.319Z,1765618741.319 [UniversalFixResidualReporter](INFO): Fix residual: 33.1 %DT, over the last 1320.3 m. Residual distance 436.8 m at bearing 171.5 degrees. Fix at (36.9963, -122.4400) with 754.3 m made good.
2025-12-13T09:39:01.322Z,1765618741.322 [keepstation:NeedComms:D] Stopped
2025-12-13T09:39:01.323Z,1765618741.323 [keepstation:NeedComms:E] Running Loop=1
2025-12-13T09:39:24.748Z,1765618764.748 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T09:39:25.669Z,1765618765.669 [NAL9602](INFO): SBD MO Status=2, MOMSN=24438, MT Status=2, MTMSN=0
2025-12-13T09:39:25.670Z,1765618765.670 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T09:39:45.517Z,1765618785.517 [NAL9602](INFO): SBD MO Status=2, MOMSN=24438, MT Status=2, MTMSN=0
2025-12-13T09:39:45.517Z,1765618785.517 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T09:39:55.872Z,1765618795.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T09:40:26.996Z,1765618826.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T09:40:31.215Z,1765618831.215 [NAL9602](INFO): SBD MO Status=1, MOMSN=24438, MT Status=0, MTMSN=0
2025-12-13T09:40:31.268Z,1765618831.268 [NAL9602](INFO): Sent 100 bytes from file Logs/20251213T011500/Courier0015.lzma
2025-12-13T09:40:31.268Z,1765618831.268 [NAL9602](INFO): Packets left to send: 0
2025-12-13T09:40:35.719Z,1765618835.719 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006118
2025-12-13T09:40:43.165Z,1765618843.165 [DataOverHttps](INFO): Sending 805 bytes from file Logs/20251213T011500/Express0016.lzma
2025-12-13T09:40:44.166Z,1765618844.166 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Express0016.lzma.bak
2025-12-13T09:40:44.166Z,1765618844.166 [DataOverHttps](INFO): SBD MOMSN=26494852
2025-12-13T09:40:46.010Z,1765618846.010 [keepstation:NeedComms:E] Stopped
2025-12-13T09:40:46.010Z,1765618846.010 [keepstation:NeedComms:F] Running Loop=1
2025-12-13T09:40:47.227Z,1765618847.227 [NAL9602](INFO): SBD MO Status=2, MOMSN=24439, MT Status=2, MTMSN=0
2025-12-13T09:40:47.227Z,1765618847.227 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T09:40:48.428Z,1765618848.428 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,094046.00,A,3659.79652,N,12226.41025,W,0.914,5.84,131225,,,A*71
2025-12-13T09:40:48.431Z,1765618848.431 [NAL9602](INFO): GPS fix at 20251213T094046: (36.996609, -122.440171)
2025-12-13T09:40:48.512Z,1765618848.512 [keepstation:NeedComms:F] Stopped
2025-12-13T09:40:48.512Z,1765618848.512 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-13T09:40:48.512Z,1765618848.512 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-13T09:40:48.512Z,1765618848.512 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-13T09:40:48.866Z,1765618848.866 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-13T09:40:48.866Z,1765618848.866 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-13T09:40:48.867Z,1765618848.867 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-13T09:40:48.867Z,1765618848.867 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-13T09:40:48.867Z,1765618848.867 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-13T09:40:48.867Z,1765618848.867 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-13T09:40:48.867Z,1765618848.867 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-13T09:40:49.262Z,1765618849.262 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-13T09:40:49.262Z,1765618849.262 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-13T09:40:49.262Z,1765618849.262 [keepstation:NeedComms:setInactive] Stopped
2025-12-13T09:40:49.263Z,1765618849.263 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-13T09:40:49.263Z,1765618849.263 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-13T09:40:49.263Z,1765618849.263 [keepstation:NeedComms] Stopped
2025-12-13T09:40:49.263Z,1765618849.263 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-13T09:40:49.268Z,1765618849.268 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-13T09:40:49.268Z,1765618849.268 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-13T09:40:49.669Z,1765618849.669 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-13T09:40:49.669Z,1765618849.669 [keepstation:SurfaceComms:B] Stopped
2025-12-13T09:40:49.669Z,1765618849.669 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-13T09:40:49.669Z,1765618849.669 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-13T09:40:49.669Z,1765618849.669 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-13T09:40:49.670Z,1765618849.670 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-13T09:40:49.670Z,1765618849.670 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T09:40:49.670Z,1765618849.670 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-13T09:40:50.088Z,1765618850.088 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-13T09:40:50.088Z,1765618850.088 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-13T09:40:50.088Z,1765618850.088 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-13T09:40:50.088Z,1765618850.088 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T09:40:50.088Z,1765618850.088 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-13T09:40:50.088Z,1765618850.088 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-13T09:40:50.088Z,1765618850.088 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-13T09:40:50.089Z,1765618850.089 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-13T09:40:50.466Z,1765618850.466 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-13T09:40:50.466Z,1765618850.466 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-13T09:40:50.466Z,1765618850.466 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-13T09:40:50.466Z,1765618850.466 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-13T09:40:50.466Z,1765618850.466 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-13T09:40:50.466Z,1765618850.466 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-13T09:40:50.871Z,1765618850.871 [keepstation:SurfaceComms:setStation] Stopped
2025-12-13T09:40:50.871Z,1765618850.871 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-13T09:40:50.871Z,1765618850.871 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-13T09:40:50.871Z,1765618850.871 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-13T09:40:50.876Z,1765618850.876 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-13T09:40:50.876Z,1765618850.876 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-13T09:40:51.257Z,1765618851.257 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-13T09:40:51.257Z,1765618851.257 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-12-13T09:40:51.257Z,1765618851.257 [keepstation:SurfaceComms:setSink] Stopped
2025-12-13T09:40:51.257Z,1765618851.257 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-13T09:40:51.257Z,1765618851.257 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-13T09:40:51.257Z,1765618851.257 [keepstation:SurfaceComms] Stopped
2025-12-13T09:40:51.258Z,1765618851.258 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-13T09:40:51.661Z,1765618851.661 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-13T09:40:51.661Z,1765618851.661 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-12-13T09:40:51.661Z,1765618851.661 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-12-13T09:40:51.661Z,1765618851.661 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-12-13T09:40:51.662Z,1765618851.662 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-12-13T09:40:51.662Z,1765618851.662 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-12-13T09:40:51.662Z,1765618851.662 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-12-13T09:40:51.662Z,1765618851.662 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T09:40:51.662Z,1765618851.662 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-12-13T09:40:51.662Z,1765618851.662 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-13T09:40:51.662Z,1765618851.662 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-12-13T09:40:51.662Z,1765618851.662 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-12-13T09:40:51.663Z,1765618851.663 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-12-13T09:40:51.663Z,1765618851.663 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m
2025-12-13T09:40:51.663Z,1765618851.663 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-12-13T09:40:51.663Z,1765618851.663 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-12-13T09:40:51.663Z,1765618851.663 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-12-13T09:41:20.746Z,1765618880.746 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-13T09:41:29.214Z,1765618889.214 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T09:42:47.400Z,1765618967.400 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T09:43:16.124Z,1765618996.124 [Radio_Surface](INFO): Powering down
2025-12-13T09:43:18.528Z,1765618998.528 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T09:43:19.532Z,1765618999.532 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-13T09:44:26.179Z,1765619066.179 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-12-13T09:44:26.179Z,1765619066.179 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-12-13T09:44:26.180Z,1765619066.180 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T09:44:26.180Z,1765619066.180 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-12-13T09:44:26.587Z,1765619066.587 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-12-13T09:44:26.587Z,1765619066.587 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T09:44:26.587Z,1765619066.587 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-12-13T09:44:26.587Z,1765619066.587 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-12-13T09:44:26.587Z,1765619066.587 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T09:44:26.587Z,1765619066.587 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-12-13T09:44:26.993Z,1765619066.993 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-12-13T09:44:26.993Z,1765619066.993 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T09:44:26.993Z,1765619066.993 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-12-13T09:44:26.993Z,1765619066.993 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T09:44:26.994Z,1765619066.994 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T09:44:26.994Z,1765619066.994 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-12-13T09:44:26.994Z,1765619066.994 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T09:44:26.994Z,1765619066.994 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-12-13T09:44:26.994Z,1765619066.994 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-13T09:44:26.994Z,1765619066.994 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-12-13T09:44:26.994Z,1765619066.994 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-12-13T09:44:26.995Z,1765619066.995 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-12-13T09:44:27.402Z,1765619067.402 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-13T09:44:27.402Z,1765619067.402 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-13T09:44:27.402Z,1765619067.402 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-13T09:44:27.402Z,1765619067.402 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-13T09:44:27.402Z,1765619067.402 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-13T09:44:27.403Z,1765619067.403 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-13T09:44:27.403Z,1765619067.403 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-13T09:44:27.404Z,1765619067.404 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-12-13T09:44:27.793Z,1765619067.793 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.17 mm (1 active estimators).
2025-12-13T09:44:54.924Z,1765619094.924 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735291
2025-12-13T09:45:29.285Z,1765619129.285 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T09:50:36.607Z,1765619436.607 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T09:50:36.610Z,1765619436.610 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T09:54:34.197Z,1765619674.197 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-12-13T10:17:05.584Z,1765621025.584 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T10:22:29.676Z,1765621349.676 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T10:27:20.906Z,1765621640.906 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-12-13T10:31:38.667Z,1765621898.667 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T10:31:38.670Z,1765621898.670 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T10:53:54.363Z,1765623234.363 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T11:03:32.243Z,1765623812.243 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T11:06:15.022Z,1765623975.022 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T11:10:48.666Z,1765624248.666 [keepstation:SurfaceComms] Running Loop=1
2025-12-13T11:10:48.666Z,1765624248.666 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-13T11:10:48.666Z,1765624248.666 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-13T11:10:48.667Z,1765624248.667 [keepstation:SurfaceComms:A] Stopped
2025-12-13T11:10:48.667Z,1765624248.667 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-13T11:10:48.667Z,1765624248.667 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-13T11:10:48.669Z,1765624248.669 [keepstation:NeedComms] Running Loop=1
2025-12-13T11:10:48.669Z,1765624248.669 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-13T11:10:48.669Z,1765624248.669 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-13T11:10:48.669Z,1765624248.669 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-13T11:10:48.670Z,1765624248.670 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-13T11:10:48.670Z,1765624248.670 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-13T11:10:48.670Z,1765624248.670 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-13T11:10:48.671Z,1765624248.671 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-13T11:10:48.671Z,1765624248.671 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-13T11:10:48.671Z,1765624248.671 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-13T11:10:48.671Z,1765624248.671 [keepstation:NeedComms:A] Running Loop=1
2025-12-13T11:10:48.673Z,1765624248.673 [keepstation:NeedComms:A](INFO): last time_fix was: 1765618846.000000 second since 1970/01/01T00:00:00Z
2025-12-13T11:10:48.673Z,1765624248.673 [keepstation:NeedComms:A] Stopped
2025-12-13T11:10:48.673Z,1765624248.673 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-13T11:10:48.673Z,1765624248.673 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-13T11:10:48.674Z,1765624248.674 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-13T11:10:49.074Z,1765624249.074 [keepstation:KeepStation:Dive] Stopped
2025-12-13T11:10:49.075Z,1765624249.075 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-12-13T11:10:49.075Z,1765624249.075 [keepstation:KeepStation:Dive:A] Stopped
2025-12-13T11:10:49.075Z,1765624249.075 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-12-13T11:10:49.075Z,1765624249.075 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-13T11:10:49.075Z,1765624249.075 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-13T11:10:49.075Z,1765624249.075 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-13T11:10:49.075Z,1765624249.075 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-13T11:10:49.075Z,1765624249.075 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-13T11:10:49.076Z,1765624249.076 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-13T11:10:49.079Z,1765624249.079 [keepstation:NeedComms:setActive:A] Stopped
2025-12-13T11:10:49.079Z,1765624249.079 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-13T11:10:49.474Z,1765624249.474 [keepstation:NeedComms:setActive:B] Stopped
2025-12-13T11:10:49.475Z,1765624249.475 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-13T11:10:49.475Z,1765624249.475 [keepstation:NeedComms:setActive] Stopped
2025-12-13T11:10:49.475Z,1765624249.475 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-13T11:12:40.548Z,1765624360.548 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T11:12:40.551Z,1765624360.551 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T11:15:12.361Z,1765624512.361 [keepstation:NeedComms:D] Running Loop=1
2025-12-13T11:15:13.049Z,1765624513.049 [Radio_Surface](INFO): Powering up
2025-12-13T11:15:19.300Z,1765624519.300 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-13T11:15:49.428Z,1765624549.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T11:16:15.249Z,1765624575.249 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T11:16:20.552Z,1765624580.552 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T11:16:51.676Z,1765624611.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T11:16:52.010Z,1765624612.010 [NAL9602](INFO): SBD MO Status=2, MOMSN=24439, MT Status=2, MTMSN=0
2025-12-13T11:16:52.011Z,1765624612.011 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T11:17:22.804Z,1765624642.804 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T11:17:31.300Z,1765624651.300 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003024
2025-12-13T11:17:37.657Z,1765624657.657 [NAL9602](INFO): SBD MO Status=2, MOMSN=24439, MT Status=2, MTMSN=0
2025-12-13T11:17:37.657Z,1765624657.657 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T11:17:50.993Z,1765624670.993 [NAL9602](INFO): SBD MO Status=2, MOMSN=24439, MT Status=2, MTMSN=0
2025-12-13T11:17:50.993Z,1765624670.993 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T11:18:43.123Z,1765624723.123 [NAL9602](INFO): SBD MO Status=2, MOMSN=24439, MT Status=2, MTMSN=0
2025-12-13T11:18:43.123Z,1765624723.123 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T11:20:16.427Z,1765624816.427 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-12-13T11:20:24.914Z,1765624824.914 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112023.00,A,3659.74790,N,12226.77773,W,0.564,30.99,131225,,,A*4F
2025-12-13T11:20:24.917Z,1765624824.917 [NAL9602](INFO): GPS fix at 20251213T112023: (36.995798, -122.446296)
2025-12-13T11:20:24.929Z,1765624824.929 [UniversalFixResidualReporter](INFO): Fix residual: 111.0 %DT, over the last 658.2 m. Residual distance 730.4 m at bearing 138.6 degrees. Fix at (36.9958, -122.4463) with 551.3 m made good.
2025-12-13T11:20:24.932Z,1765624824.932 [keepstation:NeedComms:D] Stopped
2025-12-13T11:20:24.933Z,1765624824.933 [keepstation:NeedComms:E] Running Loop=1
2025-12-13T11:20:32.940Z,1765624832.940 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20251213T011500/Courier0018.lzma
2025-12-13T11:20:33.942Z,1765624833.942 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Courier0018.lzma.bak
2025-12-13T11:20:33.942Z,1765624833.942 [DataOverHttps](INFO): SBD MOMSN=26494861
2025-12-13T11:20:49.997Z,1765624849.997 [DataOverHttps](INFO): Sending 790 bytes from file Logs/20251213T011500/Express0019.lzma
2025-12-13T11:20:50.998Z,1765624850.998 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Express0019.lzma.bak
2025-12-13T11:20:50.998Z,1765624850.998 [DataOverHttps](INFO): SBD MOMSN=26494864
2025-12-13T11:20:52.406Z,1765624852.406 [keepstation:NeedComms:E] Stopped
2025-12-13T11:20:52.406Z,1765624852.406 [keepstation:NeedComms:F] Running Loop=1
2025-12-13T11:20:54.407Z,1765624854.407 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112052.00,A,3659.75184,N,12226.77967,W,0.311,342.29,131225,,,A*79
2025-12-13T11:20:54.409Z,1765624854.409 [NAL9602](INFO): GPS fix at 20251213T112052: (36.995864, -122.446328)
2025-12-13T11:20:54.447Z,1765624854.447 [keepstation:NeedComms:F] Stopped
2025-12-13T11:20:54.447Z,1765624854.447 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-13T11:20:54.447Z,1765624854.447 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-13T11:20:54.447Z,1765624854.447 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-13T11:20:54.858Z,1765624854.858 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-13T11:20:54.858Z,1765624854.858 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-13T11:20:54.858Z,1765624854.858 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-13T11:20:54.858Z,1765624854.858 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-13T11:20:54.858Z,1765624854.858 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-13T11:20:54.858Z,1765624854.858 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-13T11:20:54.859Z,1765624854.859 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-13T11:20:55.234Z,1765624855.234 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-13T11:20:55.235Z,1765624855.235 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-13T11:20:55.235Z,1765624855.235 [keepstation:NeedComms:setInactive] Stopped
2025-12-13T11:20:55.235Z,1765624855.235 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-13T11:20:55.235Z,1765624855.235 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-13T11:20:55.235Z,1765624855.235 [keepstation:NeedComms] Stopped
2025-12-13T11:20:55.240Z,1765624855.240 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-13T11:20:55.240Z,1765624855.240 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-13T11:20:55.240Z,1765624855.240 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-13T11:20:55.647Z,1765624855.647 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-13T11:20:55.647Z,1765624855.647 [keepstation:SurfaceComms:B] Stopped
2025-12-13T11:20:55.647Z,1765624855.647 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-13T11:20:56.402Z,1765624856.402 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-13T11:20:56.402Z,1765624856.402 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-13T11:20:56.403Z,1765624856.403 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-13T11:20:56.403Z,1765624856.403 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T11:20:56.403Z,1765624856.403 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-13T11:20:56.500Z,1765624856.500 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-13T11:20:56.501Z,1765624856.501 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-13T11:20:56.501Z,1765624856.501 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-13T11:20:56.501Z,1765624856.501 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T11:20:56.501Z,1765624856.501 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-13T11:20:56.501Z,1765624856.501 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-13T11:20:56.501Z,1765624856.501 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-13T11:20:56.501Z,1765624856.501 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-13T11:20:56.879Z,1765624856.879 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-13T11:20:56.879Z,1765624856.879 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-13T11:20:56.884Z,1765624856.884 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-13T11:20:56.884Z,1765624856.884 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-13T11:20:56.884Z,1765624856.884 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-13T11:20:56.884Z,1765624856.884 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-13T11:20:57.292Z,1765624857.292 [keepstation:SurfaceComms:setStation] Stopped
2025-12-13T11:20:57.292Z,1765624857.292 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-13T11:20:57.292Z,1765624857.292 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-13T11:20:57.292Z,1765624857.292 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-13T11:20:57.292Z,1765624857.292 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-13T11:20:57.293Z,1765624857.293 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-13T11:20:57.683Z,1765624857.683 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-13T11:20:57.704Z,1765624857.704 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-12-13T11:20:57.704Z,1765624857.704 [keepstation:SurfaceComms:setSink] Stopped
2025-12-13T11:20:57.704Z,1765624857.704 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-13T11:20:57.704Z,1765624857.704 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-13T11:20:57.704Z,1765624857.704 [keepstation:SurfaceComms] Stopped
2025-12-13T11:20:57.704Z,1765624857.704 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-13T11:20:58.085Z,1765624858.085 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-13T11:20:58.085Z,1765624858.085 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-12-13T11:20:58.085Z,1765624858.085 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-12-13T11:20:58.085Z,1765624858.085 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-12-13T11:20:58.085Z,1765624858.085 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-12-13T11:20:58.086Z,1765624858.086 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-12-13T11:20:58.086Z,1765624858.086 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-12-13T11:20:58.086Z,1765624858.086 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T11:20:58.086Z,1765624858.086 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-12-13T11:20:58.086Z,1765624858.086 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-13T11:20:58.086Z,1765624858.086 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-12-13T11:20:58.086Z,1765624858.086 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-12-13T11:20:58.086Z,1765624858.086 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-12-13T11:20:58.087Z,1765624858.087 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m
2025-12-13T11:20:58.087Z,1765624858.087 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-12-13T11:20:58.087Z,1765624858.087 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-12-13T11:20:58.087Z,1765624858.087 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-12-13T11:21:27.141Z,1765624887.141 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-13T11:22:53.402Z,1765624973.402 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T11:23:24.425Z,1765625004.425 [Radio_Surface](INFO): Powering down
2025-12-13T11:23:24.524Z,1765625004.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T11:23:25.528Z,1765625005.528 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-13T11:24:36.631Z,1765625076.631 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-12-13T11:24:36.648Z,1765625076.648 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-12-13T11:24:36.648Z,1765625076.648 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T11:24:36.648Z,1765625076.648 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-12-13T11:24:37.037Z,1765625077.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-12-13T11:24:37.037Z,1765625077.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T11:24:37.037Z,1765625077.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-12-13T11:24:37.037Z,1765625077.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-12-13T11:24:37.037Z,1765625077.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T11:24:37.037Z,1765625077.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-12-13T11:24:37.442Z,1765625077.442 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-12-13T11:24:37.442Z,1765625077.442 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T11:24:37.442Z,1765625077.442 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-12-13T11:24:37.442Z,1765625077.442 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T11:24:37.442Z,1765625077.442 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T11:24:37.443Z,1765625077.443 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-12-13T11:24:37.443Z,1765625077.443 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T11:24:37.443Z,1765625077.443 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-12-13T11:24:37.443Z,1765625077.443 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-13T11:24:37.443Z,1765625077.443 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-12-13T11:24:37.443Z,1765625077.443 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-12-13T11:24:37.443Z,1765625077.443 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-12-13T11:24:37.851Z,1765625077.851 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-13T11:24:37.851Z,1765625077.851 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-13T11:24:37.851Z,1765625077.851 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-13T11:24:37.851Z,1765625077.851 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-13T11:24:37.851Z,1765625077.851 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-13T11:24:37.851Z,1765625077.851 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-13T11:24:37.851Z,1765625077.851 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-13T11:24:37.852Z,1765625077.852 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-12-13T11:25:00.932Z,1765625100.932 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.759232
2025-12-13T11:26:15.390Z,1765625175.390 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T11:28:31.967Z,1765625311.967 [BPC1](ERROR): BPC1A: No match for serial number FFFF in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-12-13T11:36:15.595Z,1765625775.595 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T11:37:01.637Z,1765625821.637 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-12-13T11:44:32.890Z,1765626272.890 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T11:46:15.975Z,1765626375.975 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T11:53:42.331Z,1765626822.331 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T11:53:42.334Z,1765626822.334 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T11:56:16.254Z,1765626976.254 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T12:06:16.612Z,1765627576.612 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T12:15:57.976Z,1765628157.976 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T12:16:16.962Z,1765628176.962 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T12:26:17.315Z,1765628777.315 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T12:34:44.326Z,1765629284.326 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T12:34:44.330Z,1765629284.330 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T12:35:19.516Z,1765629319.516 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-12-13T12:35:23.948Z,1765629323.948 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006911
CHAN A1 (24V): 0.024941
CHAN A2 (12V): 0.001127
CHAN A3 (5V): -0.010620
CHAN B0 (3.3V): -0.003477
CHAN B1 (3.15aV): -0.001858
CHAN B2 (3.15bV): -0.000776
CHAN B3 (GND): -0.006187
OPEN: 0.011986
Full Scale: +/- 1 mA
2025-12-13T12:36:17.659Z,1765629377.659 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T12:46:17.998Z,1765629977.998 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T12:50:54.747Z,1765630254.747 [keepstation:SurfaceComms] Running Loop=1
2025-12-13T12:50:54.748Z,1765630254.748 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-13T12:50:54.748Z,1765630254.748 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-13T12:50:54.748Z,1765630254.748 [keepstation:SurfaceComms:A] Stopped
2025-12-13T12:50:54.749Z,1765630254.749 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-13T12:50:54.749Z,1765630254.749 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-13T12:50:54.750Z,1765630254.750 [keepstation:NeedComms] Running Loop=1
2025-12-13T12:50:54.750Z,1765630254.750 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-13T12:50:54.750Z,1765630254.750 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-13T12:50:54.751Z,1765630254.751 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-13T12:50:54.751Z,1765630254.751 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-13T12:50:54.751Z,1765630254.751 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-13T12:50:54.753Z,1765630254.753 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-13T12:50:54.753Z,1765630254.753 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-13T12:50:54.753Z,1765630254.753 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-13T12:50:54.754Z,1765630254.754 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-13T12:50:54.754Z,1765630254.754 [keepstation:NeedComms:A] Running Loop=1
2025-12-13T12:50:54.755Z,1765630254.755 [keepstation:NeedComms:A](INFO): last time_fix was: 1765624852.000000 second since 1970/01/01T00:00:00Z
2025-12-13T12:50:54.755Z,1765630254.755 [keepstation:NeedComms:A] Stopped
2025-12-13T12:50:54.756Z,1765630254.756 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-13T12:50:54.756Z,1765630254.756 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-13T12:50:54.756Z,1765630254.756 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-13T12:50:55.158Z,1765630255.158 [keepstation:KeepStation:Dive] Stopped
2025-12-13T12:50:55.158Z,1765630255.158 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-12-13T12:50:55.158Z,1765630255.158 [keepstation:KeepStation:Dive:A] Stopped
2025-12-13T12:50:55.158Z,1765630255.158 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-12-13T12:50:55.159Z,1765630255.159 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-13T12:50:55.159Z,1765630255.159 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-13T12:50:55.159Z,1765630255.159 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-13T12:50:55.159Z,1765630255.159 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-13T12:50:55.159Z,1765630255.159 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-13T12:50:55.159Z,1765630255.159 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-13T12:50:55.162Z,1765630255.162 [keepstation:NeedComms:setActive:A] Stopped
2025-12-13T12:50:55.162Z,1765630255.162 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-13T12:50:55.558Z,1765630255.558 [keepstation:NeedComms:setActive:B] Stopped
2025-12-13T12:50:55.558Z,1765630255.558 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-13T12:50:55.558Z,1765630255.558 [keepstation:NeedComms:setActive] Stopped
2025-12-13T12:50:55.558Z,1765630255.558 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2025-12-13T12:55:22.958Z,1765630522.958 [keepstation:NeedComms:D] Running Loop=1
2025-12-13T12:55:23.642Z,1765630523.642 [Radio_Surface](INFO): Powering up
2025-12-13T12:55:29.560Z,1765630529.560 [DataOverHttps](INFO): Radio surface powered ON.
2025-12-13T12:55:55.805Z,1765630555.805 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-12-13T12:55:55.888Z,1765630555.888 [NAL9602](ERROR): received:
+CSQ:1
OK439, 2, 0, 0, 0
OK
2025-12-13T12:55:59.681Z,1765630559.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T12:56:18.024Z,1765630578.024 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T12:56:30.805Z,1765630590.805 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T12:57:01.928Z,1765630621.928 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T12:57:31.955Z,1765630651.955 [NAL9602](INFO): SBD MO Status=2, MOMSN=24439, MT Status=2, MTMSN=0
2025-12-13T12:57:31.955Z,1765630651.955 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T12:57:33.053Z,1765630653.053 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T12:57:41.512Z,1765630661.512 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002987
2025-12-13T12:57:46.527Z,1765630666.527 [NAL9602](INFO): SBD MO Status=2, MOMSN=24439, MT Status=2, MTMSN=0
2025-12-13T12:57:46.527Z,1765630666.527 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-12-13T12:58:09.523Z,1765630689.523 [NAL9602](INFO): SBD MO Status=1, MOMSN=24439, MT Status=0, MTMSN=0
2025-12-13T12:58:09.523Z,1765630689.523 [NAL9602](INFO): No messages in MT queue
2025-12-13T12:58:10.734Z,1765630690.734 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,125808.00,A,3659.65576,N,12226.88583,W,0.836,327.93,131225,,,A*78
2025-12-13T12:58:10.736Z,1765630690.736 [NAL9602](INFO): GPS fix at 20251213T125808: (36.994263, -122.448097)
2025-12-13T12:58:10.748Z,1765630690.748 [UniversalFixResidualReporter](INFO): Fix residual: 91.0 %DT, over the last 768.5 m. Residual distance 699.2 m at bearing 110.1 degrees. Fix at (36.9943, -122.4481) with 237.5 m made good.
2025-12-13T12:58:10.751Z,1765630690.751 [keepstation:NeedComms:D] Stopped
2025-12-13T12:58:10.752Z,1765630690.752 [keepstation:NeedComms:E] Running Loop=1
2025-12-13T12:58:19.751Z,1765630699.751 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20251213T011500/Courier0021.lzma
2025-12-13T12:58:20.754Z,1765630700.754 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Courier0021.lzma.bak
2025-12-13T12:58:20.754Z,1765630700.754 [DataOverHttps](INFO): SBD MOMSN=26494872
2025-12-13T12:58:38.945Z,1765630718.945 [DataOverHttps](INFO): Sending 923 bytes from file Logs/20251213T011500/Express0022.lzma
2025-12-13T12:58:39.946Z,1765630719.946 [DataOverHttps](INFO): Moved sent file to Logs/20251213T011500/Express0022.lzma.bak
2025-12-13T12:58:39.946Z,1765630719.946 [DataOverHttps](INFO): SBD MOMSN=26494875
2025-12-13T12:58:41.473Z,1765630721.473 [keepstation:NeedComms:E] Stopped
2025-12-13T12:58:41.474Z,1765630721.474 [keepstation:NeedComms:F] Running Loop=1
2025-12-13T12:58:43.479Z,1765630723.479 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,125841.00,A,3659.65918,N,12226.88713,W,1.166,324.88,131225,,,A*7E
2025-12-13T12:58:43.481Z,1765630723.481 [NAL9602](INFO): GPS fix at 20251213T125841: (36.994320, -122.448119)
2025-12-13T12:58:43.522Z,1765630723.522 [keepstation:NeedComms:F] Stopped
2025-12-13T12:58:43.522Z,1765630723.522 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2025-12-13T12:58:43.523Z,1765630723.523 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2025-12-13T12:58:43.523Z,1765630723.523 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2025-12-13T12:58:43.934Z,1765630723.934 [keepstation:NeedComms:setRestoreMode:A] Stopped
2025-12-13T12:58:43.934Z,1765630723.934 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2025-12-13T12:58:43.934Z,1765630723.934 [keepstation:NeedComms:setRestoreMode] Stopped
2025-12-13T12:58:43.934Z,1765630723.934 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2025-12-13T12:58:43.934Z,1765630723.934 [keepstation:NeedComms:setInactive] Running Loop=1
2025-12-13T12:58:43.934Z,1765630723.934 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2025-12-13T12:58:43.935Z,1765630723.935 [keepstation:NeedComms:setInactive:A] Running Loop=1
2025-12-13T12:58:44.321Z,1765630724.321 [keepstation:NeedComms:setInactive:A] Stopped
2025-12-13T12:58:44.321Z,1765630724.321 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2025-12-13T12:58:44.322Z,1765630724.322 [keepstation:NeedComms:setInactive] Stopped
2025-12-13T12:58:44.322Z,1765630724.322 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2025-12-13T12:58:44.322Z,1765630724.322 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-12-13T12:58:44.322Z,1765630724.322 [keepstation:NeedComms] Stopped
2025-12-13T12:58:44.323Z,1765630724.323 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-12-13T12:58:44.323Z,1765630724.323 [keepstation:NeedComms:C.GoToSurface] Stopped
2025-12-13T12:58:44.323Z,1765630724.323 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-12-13T12:58:44.716Z,1765630724.716 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-12-13T12:58:44.717Z,1765630724.717 [keepstation:SurfaceComms:B] Stopped
2025-12-13T12:58:44.717Z,1765630724.717 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-12-13T12:58:44.717Z,1765630724.717 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2025-12-13T12:58:44.717Z,1765630724.717 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2025-12-13T12:58:44.717Z,1765630724.717 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2025-12-13T12:58:44.717Z,1765630724.717 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T12:58:44.717Z,1765630724.717 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2025-12-13T12:58:45.142Z,1765630725.142 [keepstation:SurfaceComms:HandleNan] Stopped
2025-12-13T12:58:45.142Z,1765630725.142 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2025-12-13T12:58:45.142Z,1765630725.142 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2025-12-13T12:58:45.142Z,1765630725.142 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2025-12-13T12:58:45.142Z,1765630725.142 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2025-12-13T12:58:45.142Z,1765630725.142 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-12-13T12:58:45.142Z,1765630725.142 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-12-13T12:58:45.142Z,1765630725.142 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-12-13T12:58:45.517Z,1765630725.517 [keepstation:SurfaceComms:setTransit] Stopped
2025-12-13T12:58:45.517Z,1765630725.517 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-12-13T12:58:45.517Z,1765630725.517 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-12-13T12:58:45.517Z,1765630725.517 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-12-13T12:58:45.518Z,1765630725.518 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-12-13T12:58:45.518Z,1765630725.518 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-12-13T12:58:45.935Z,1765630725.935 [keepstation:SurfaceComms:setStation] Stopped
2025-12-13T12:58:45.935Z,1765630725.935 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-12-13T12:58:45.935Z,1765630725.935 [keepstation:SurfaceComms:setStation:A] Stopped
2025-12-13T12:58:45.935Z,1765630725.935 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-12-13T12:58:45.935Z,1765630725.935 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-12-13T12:58:45.935Z,1765630725.935 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-12-13T12:58:46.337Z,1765630726.337 [keepstation:SurfaceComms:setSink:A] Stopped
2025-12-13T12:58:46.337Z,1765630726.337 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-12-13T12:58:46.337Z,1765630726.337 [keepstation:SurfaceComms:setSink] Stopped
2025-12-13T12:58:46.337Z,1765630726.337 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-12-13T12:58:46.338Z,1765630726.338 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-12-13T12:58:46.338Z,1765630726.338 [keepstation:SurfaceComms] Stopped
2025-12-13T12:58:46.338Z,1765630726.338 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-12-13T12:58:46.735Z,1765630726.735 [keepstation:KeepStation:Dive] Running Loop=1
2025-12-13T12:58:46.735Z,1765630726.735 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-12-13T12:58:46.735Z,1765630726.735 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-12-13T12:58:46.735Z,1765630726.735 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-12-13T12:58:46.736Z,1765630726.736 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-12-13T12:58:46.736Z,1765630726.736 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-12-13T12:58:46.736Z,1765630726.736 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-12-13T12:58:46.736Z,1765630726.736 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T12:58:46.737Z,1765630726.737 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-12-13T12:58:46.737Z,1765630726.737 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-12-13T12:58:46.737Z,1765630726.737 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-12-13T12:58:46.737Z,1765630726.737 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-12-13T12:58:46.737Z,1765630726.737 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-12-13T12:58:46.737Z,1765630726.737 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m
2025-12-13T12:58:46.738Z,1765630726.738 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-12-13T12:58:46.738Z,1765630726.738 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-12-13T12:58:46.738Z,1765630726.738 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-12-13T12:59:15.797Z,1765630755.797 [NAL9602](INFO): Not Powering down - fast GPS
2025-12-13T13:00:43.296Z,1765630843.296 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T13:01:14.420Z,1765630874.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T13:01:16.760Z,1765630876.760 [Radio_Surface](INFO): Powering down
2025-12-13T13:01:45.544Z,1765630905.544 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-12-13T13:01:46.548Z,1765630906.548 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-12-13T13:02:16.401Z,1765630936.401 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-12-13T13:02:16.401Z,1765630936.401 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-12-13T13:02:16.401Z,1765630936.401 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T13:02:16.401Z,1765630936.401 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-12-13T13:02:16.809Z,1765630936.809 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-12-13T13:02:16.809Z,1765630936.809 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-12-13T13:02:16.809Z,1765630936.809 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-12-13T13:02:16.809Z,1765630936.809 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-12-13T13:02:16.810Z,1765630936.810 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T13:02:16.810Z,1765630936.810 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-12-13T13:02:17.207Z,1765630937.207 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-12-13T13:02:17.224Z,1765630937.224 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T13:02:17.224Z,1765630937.224 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-12-13T13:02:17.224Z,1765630937.224 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-12-13T13:02:17.225Z,1765630937.225 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T13:02:17.225Z,1765630937.225 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-12-13T13:02:17.225Z,1765630937.225 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-12-13T13:02:17.225Z,1765630937.225 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-12-13T13:02:17.225Z,1765630937.225 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-12-13T13:02:17.225Z,1765630937.225 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-12-13T13:02:17.225Z,1765630937.225 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-12-13T13:02:17.225Z,1765630937.225 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-12-13T13:02:17.607Z,1765630937.607 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-12-13T13:02:17.608Z,1765630937.608 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-12-13T13:02:17.608Z,1765630937.608 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-12-13T13:02:17.608Z,1765630937.608 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-12-13T13:02:17.608Z,1765630937.608 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-12-13T13:02:17.608Z,1765630937.608 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-12-13T13:02:17.608Z,1765630937.608 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-12-13T13:02:17.609Z,1765630937.609 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-12-13T13:02:50.817Z,1765630970.817 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735413
2025-12-13T13:06:18.195Z,1765631178.195 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T13:11:44.729Z,1765631504.729 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-12-13T13:15:46.275Z,1765631746.275 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T13:15:46.278Z,1765631746.278 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T13:16:18.202Z,1765631778.202 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T13:18:56.995Z,1765631936.995 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-12-13T13:26:18.589Z,1765632378.589 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T13:33:09.927Z,1765632789.927 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-12-13T13:36:18.938Z,1765632978.938 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T13:46:19.280Z,1765633579.280 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T13:56:19.633Z,1765634179.633 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T13:56:48.306Z,1765634208.306 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-12-13T13:56:48.310Z,1765634208.310 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-12-13T14:06:19.994Z,1765634779.994 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T14:16:20.346Z,1765635380.346 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T14:26:20.695Z,1765635980.695 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-12-13T14:28:41.280Z,1765636121.280 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-12-13T14:28:43.714Z,1765636123.714 [keepstation:SurfaceComms] Running Loop=1
2025-12-13T14:28:43.714Z,1765636123.714 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-12-13T14:28:43.714Z,1765636123.714 [keepstation:SurfaceComms:A] Running Loop=1
2025-12-13T14:28:43.715Z,1765636123.715 [keepstation:SurfaceComms:A] Stopped
2025-12-13T14:28:43.715Z,1765636123.715 [keepstation:SurfaceComms:B] Running Loop=1
2025-12-13T14:28:43.715Z,1765636123.715 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-12-13T14:28:43.717Z,1765636123.717 [keepstation:NeedComms] Running Loop=1
2025-12-13T14:28:43.717Z,1765636123.717 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-12-13T14:28:43.717Z,1765636123.717 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2025-12-13T14:28:43.717Z,1765636123.717 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-12-13T14:28:43.717Z,1765636123.717 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-12-13T14:28:43.718Z,1765636123.718 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-12-13T14:28:43.718Z,1765636123.718 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-12-13T14:28:43.718Z,1765636123.718 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2025-12-13T14:28:43.719Z,1765636123.719 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-12-13T14:28:43.719Z,1765636123.719 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-12-13T14:28:43.719Z,1765636123.719 [keepstation:NeedComms:A] Running Loop=1
2025-12-13T14:28:43.721Z,1765636123.721 [keepstation:NeedComms:A](INFO): last time_fix was: 1765630721.000000 second since 1970/01/01T00:00:00Z
2025-12-13T14:28:43.721Z,1765636123.721 [keepstation:NeedComms:A] Stopped
2025-12-13T14:28:43.721Z,1765636123.721 [keepstation:NeedComms:setActive] Running Loop=1
2025-12-13T14:28:43.721Z,1765636123.721 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2025-12-13T14:28:43.721Z,1765636123.721 [keepstation:NeedComms:setActive:A] Running Loop=1
2025-12-13T14:28:44.119Z,1765636124.119 [keepstation:KeepStation:Dive] Stopped
2025-12-13T14:28:44.121Z,1765636124.121 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-12-13T14:28:44.121Z,1765636124.121 [keepstation:KeepStation:Dive:A] Stopped
2025-12-13T14:28:44.121Z,1765636124.121 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-12-13T14:28:44.122Z,1765636124.122 [keepstation:DiveCmd:StationKeep] Stopped
2025-12-13T14:28:44.122Z,1765636124.122 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-12-13T14:28:44.122Z,1765636124.122 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-12-13T14:28:44.122Z,1765636124.122 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-12-13T14:28:44.122Z,1765636124.122 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-12-13T14:28:44.122Z,1765636124.122 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-12-13T14:28:44.125Z,1765636124.125 [keepstation:NeedComms:setActive:A] Stopped
2025-12-13T14:28:44.125Z,1765636124.125 [keepstation:NeedComms:setActive:B] Running Loop=1
2025-12-13T14:28:44.534Z,1765636124.534 [keepstation:NeedComms:setActive:B] Stopped
2025-12-13T14:28:44.534Z,1765636124.534 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2025-12-13T14:28:44.534Z,1765636124.534 [keepstation:NeedComms:setActive] Stopped
2025-12-13T14:28:44.534Z,1765636124.534 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive