2025-06-10T04:00:00.444Z,1749528000.444 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist 2025-06-10T04:00:00.455Z,1749528000.455 [CommandExec](IMPORTANT): got command restart logs 2025-06-10T04:01:56.729Z,1749528116.729 [DVL_micro](ERROR): DVL uart error: serial timeout 2025-06-10T04:01:56.729Z,1749528116.729 [DVL_micro] Communications Fault, FailCount= 1 2025-06-10T04:01:56.729Z,1749528116.729 [DVL_micro](ERROR): Communications Fault 2025-06-10T04:01:56.730Z,1749528116.730 [DVL_micro](ERROR): Failed to parse: 2025-06-10T04:01:56.864Z,1749528116.864 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-06-10T04:01:57.165Z,1749528117.165 [DVL_micro](INFO): Powering down 2025-06-10T04:01:58.020Z,1749528118.020 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-06-10T04:01:58.020Z,1749528118.020 [DVL_micro] No Fault, FailCount= 1 2025-06-10T04:06:28.584Z,1749528388.584 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T04:11:35.080Z,1749528695.080 [sci2_vtyoyo:SurfaceComms] Running Loop=1 2025-06-10T04:11:35.080Z,1749528695.080 [sci2_vtyoyo:SurfaceComms](DEBUG): Aggregate::initialize sci2_vtyoyo:SurfaceComms 2025-06-10T04:11:35.081Z,1749528695.081 [sci2_vtyoyo:SurfaceComms:A] Running Loop=1 2025-06-10T04:11:35.083Z,1749528695.083 [sci2_vtyoyo:SurfaceComms:A] Stopped 2025-06-10T04:11:35.083Z,1749528695.083 [sci2_vtyoyo:SurfaceComms:B] Running Loop=1 2025-06-10T04:11:35.085Z,1749528695.085 [sci2_vtyoyo:SurfaceComms:B](DEBUG): Aggregate::initialize sci2_vtyoyo:SurfaceComms:B 2025-06-10T04:11:35.499Z,1749528695.499 [sci2_vtyoyo:Lap:Dive] Stopped 2025-06-10T04:11:35.499Z,1749528695.499 [sci2_vtyoyo:Lap:Dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive 2025-06-10T04:11:35.499Z,1749528695.499 [sci2_vtyoyo:Lap:Dive:A] Stopped 2025-06-10T04:11:35.499Z,1749528695.499 [sci2_vtyoyo:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink] Stopped 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:SurfaceSink 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:YoYoDive] Stopped 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:YoYoDive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive] Stopped 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:A.Pitch] Stopped 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy] Stopped 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed] Stopped 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope] Stopped 2025-06-10T04:11:35.500Z,1749528695.500 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2025-06-10T04:11:35.501Z,1749528695.501 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2025-06-10T04:11:35.501Z,1749528695.501 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-06-10T04:11:35.501Z,1749528695.501 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor] Stopped 2025-06-10T04:11:35.501Z,1749528695.501 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor](DEBUG): Uninitialize Value-Terminated YoYo. 2025-06-10T04:11:35.502Z,1749528695.502 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo] Stopped 2025-06-10T04:11:35.503Z,1749528695.503 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo](DEBUG): Uninitialize YoYoComponent. 2025-06-10T04:11:35.504Z,1749528695.504 [sci2_vtyoyo:NeedComms] Running Loop=1 2025-06-10T04:11:35.504Z,1749528695.504 [sci2_vtyoyo:NeedComms](DEBUG): Aggregate::initialize sci2_vtyoyo:NeedComms 2025-06-10T04:11:35.504Z,1749528695.504 [sci2_vtyoyo:NeedComms:B.GoToSurface] Running Loop=1 2025-06-10T04:11:35.504Z,1749528695.504 [sci2_vtyoyo:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-10T04:11:35.505Z,1749528695.505 [sci2_vtyoyo:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-10T04:11:35.505Z,1749528695.505 [sci2_vtyoyo:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-10T04:11:35.506Z,1749528695.506 [sci2_vtyoyo:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-10T04:11:35.506Z,1749528695.506 [sci2_vtyoyo:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-10T04:11:35.506Z,1749528695.506 [sci2_vtyoyo:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-10T04:11:35.507Z,1749528695.507 [sci2_vtyoyo:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-10T04:11:35.507Z,1749528695.507 [sci2_vtyoyo:NeedComms:A] Running Loop=1 2025-06-10T04:11:35.508Z,1749528695.508 [sci2_vtyoyo:NeedComms:A](INFO): last time_fix was: 1749525089.000000 second since 1970/01/01T00:00:00Z 2025-06-10T04:11:35.509Z,1749528695.509 [sci2_vtyoyo:NeedComms:A] Stopped 2025-06-10T04:11:59.076Z,1749528719.076 [sci2_vtyoyo:NeedComms:C] Running Loop=1 2025-06-10T04:11:59.634Z,1749528719.634 [Radio_Surface](INFO): Powering up 2025-06-10T04:12:05.061Z,1749528725.061 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-10T04:12:35.181Z,1749528755.181 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T04:12:45.144Z,1749528765.144 [NAL9602](INFO): SBD MO Status=2, MOMSN=21471, MT Status=2, MTMSN=0 2025-06-10T04:12:45.144Z,1749528765.144 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T04:13:06.313Z,1749528786.313 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T04:13:35.236Z,1749528815.236 [NAL9602](INFO): SBD MO Status=2, MOMSN=21471, MT Status=2, MTMSN=0 2025-06-10T04:13:35.236Z,1749528815.236 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T04:13:37.437Z,1749528817.437 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T04:14:01.119Z,1749528841.119 [NAL9602](INFO): SBD MO Status=2, MOMSN=21471, MT Status=2, MTMSN=0 2025-06-10T04:14:01.120Z,1749528841.120 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T04:14:08.565Z,1749528848.565 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T04:14:17.890Z,1749528857.890 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003404 2025-06-10T04:14:56.875Z,1749528896.875 [NAL9602](INFO): SBD MO Status=2, MOMSN=21471, MT Status=2, MTMSN=0 2025-06-10T04:14:56.876Z,1749528896.876 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T04:15:23.150Z,1749528923.150 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=21471, MT Status=1, MTMSN=4392 2025-06-10T04:15:23.150Z,1749528923.150 [NAL9602](INFO): Data available in MT queue 2025-06-10T04:15:23.834Z,1749528923.834 [NAL9602](INFO): Received command: sched 20250610T04 "set keepstation.Speed 1 m/s;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run" 2iuk1 2 2 2025-06-10T04:15:23.879Z,1749528923.879 [CommandExec](IMPORTANT): got command schedule 20250610T040000 "set keepstation.Speed 1 m/s;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run" 2iuk1 2 2.000000 2025-06-10T04:15:23.880Z,1749528923.880 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=2iuk1 2025-06-10T04:15:23.881Z,1749528923.881 [CommandExec](IMPORTANT): Scheduled #12 (#2 of 2 with id='2iuk1'): "set keepstation.Speed 1 m/s;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run", AT TIME: 2025-06-10T04:00:00Z 2025-06-10T04:15:23.995Z,1749528923.995 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-06-10T04:15:23.996Z,1749528923.996 [MissionManager](INFO): Uninitializing Mission sci2_vtyoyo 2025-06-10T04:15:23.996Z,1749528923.996 [sci2_vtyoyo] Stopped 2025-06-10T04:15:23.996Z,1749528923.996 [sci2_vtyoyo](DEBUG): Aggregate::uninitialize sci2_vtyoyo 2025-06-10T04:15:23.996Z,1749528923.996 [sci2_vtyoyo:Science] Stopped 2025-06-10T04:15:23.996Z,1749528923.996 [sci2_vtyoyo:Science](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Science 2025-06-10T04:15:23.996Z,1749528923.996 [sci2_vtyoyo:Science:B] Stopped 2025-06-10T04:15:23.996Z,1749528923.996 [sci2_vtyoyo:Science:C] Stopped 2025-06-10T04:15:23.996Z,1749528923.996 [sci2_vtyoyo:Science:E] Stopped 2025-06-10T04:15:23.996Z,1749528923.996 [sci2_vtyoyo:Science:OceanCurrent] Stopped 2025-06-10T04:15:23.996Z,1749528923.996 [sci2_vtyoyo:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Science:OceanCurrent 2025-06-10T04:15:23.996Z,1749528923.996 [sci2_vtyoyo:Science:OceanCurrent:A.] Stopped 2025-06-10T04:15:23.996Z,1749528923.996 [sci2_vtyoyo:NeedComms] Stopped 2025-06-10T04:15:23.997Z,1749528923.997 [sci2_vtyoyo:NeedComms](DEBUG): Aggregate::uninitialize sci2_vtyoyo:NeedComms 2025-06-10T04:15:23.997Z,1749528923.997 [sci2_vtyoyo:NeedComms:B.GoToSurface] Stopped 2025-06-10T04:15:23.997Z,1749528923.997 [sci2_vtyoyo:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-10T04:15:23.997Z,1749528923.997 [sci2_vtyoyo:NeedComms:C] Stopped 2025-06-10T04:15:23.997Z,1749528923.997 [sci2_vtyoyo:StandardEnvelopes] Stopped 2025-06-10T04:15:23.997Z,1749528923.997 [sci2_vtyoyo:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2_vtyoyo:StandardEnvelopes 2025-06-10T04:15:23.997Z,1749528923.997 [sci2_vtyoyo:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-06-10T04:15:23.997Z,1749528923.997 [sci2_vtyoyo:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-06-10T04:15:23.998Z,1749528923.997 [sci2_vtyoyo:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-06-10T04:15:23.998Z,1749528923.998 [sci2_vtyoyo:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-06-10T04:15:23.998Z,1749528923.998 [sci2_vtyoyo:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-06-10T04:15:23.998Z,1749528923.998 [sci2_vtyoyo:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-06-10T04:15:23.998Z,1749528923.998 [sci2_vtyoyo:BackseatDriver] Stopped 2025-06-10T04:15:23.998Z,1749528923.998 [sci2_vtyoyo:BackseatDriver](DEBUG): Aggregate::uninitialize sci2_vtyoyo:BackseatDriver 2025-06-10T04:15:23.998Z,1749528923.998 [sci2_vtyoyo:BackseatDriver:A.BackseatDriver] Stopped 2025-06-10T04:15:23.998Z,1749528923.998 [sci2_vtyoyo:PowerOnly] Stopped 2025-06-10T04:15:23.998Z,1749528923.998 [sci2_vtyoyo:PowerOnly](DEBUG): Aggregate::uninitialize sci2_vtyoyo:PowerOnly 2025-06-10T04:15:23.998Z,1749528923.998 [sci2_vtyoyo:PowerOnly:E.Wait] Stopped 2025-06-10T04:15:23.998Z,1749528923.998 [sci2_vtyoyo:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-06-10T04:15:23.998Z,1749528923.998 [sci2_vtyoyo:SurfaceComms] Stopped 2025-06-10T04:15:23.999Z,1749528923.999 [sci2_vtyoyo:SurfaceComms](DEBUG): Aggregate::uninitialize sci2_vtyoyo:SurfaceComms 2025-06-10T04:15:23.999Z,1749528923.999 [sci2_vtyoyo:SurfaceComms:B] Stopped 2025-06-10T04:15:23.999Z,1749528923.999 [sci2_vtyoyo:SurfaceComms:B](DEBUG): Aggregate::uninitialize sci2_vtyoyo:SurfaceComms:B 2025-06-10T04:15:23.999Z,1749528923.999 [sci2_vtyoyo:Lap] Stopped 2025-06-10T04:15:23.999Z,1749528923.999 [sci2_vtyoyo:Lap](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap 2025-06-10T04:15:23.999Z,1749528923.999 [sci2_vtyoyo:Lap:Wpt7] Stopped 2025-06-10T04:15:23.999Z,1749528923.999 [sci2_vtyoyo:Lap:Wpt7](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Wpt7 2025-06-10T04:15:23.999Z,1749528923.999 [sci2_vtyoyo:Lap:Wpt7:A.Waypoint] Stopped 2025-06-10T04:15:23.999Z,1749528923.999 [sci2_vtyoyo:Lap:Wpt7:A.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-06-10T04:15:24.157Z,1749528924.157 [WetLabsBB2FL](INFO): Powering down 2025-06-10T04:15:24.258Z,1749528924.258 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-06-10T04:15:24.258Z,1749528924.258 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-06-10T04:15:24.283Z,1749528924.283 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-06-10T04:15:24.748Z,1749528924.748 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,041519.00,A,3642.07819,N,12151.83002,W,1.205,136.81,100625,,,A*74 2025-06-10T04:15:24.750Z,1749528924.750 [NAL9602](INFO): GPS fix at 20250610T041519: (36.701303, -121.863834) 2025-06-10T04:15:24.771Z,1749528924.771 [UniversalFixResidualReporter](INFO): Fix residual: 3.6 %DT, over the last 2886.3 m. Residual distance 105.0 m at bearing 78.2 degrees. Fix at (36.7013, -121.8638) with 2728.6 m made good. 2025-06-10T04:15:25.782Z,1749528925.782 [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-06-10T04:15:25.791Z,1749528925.791 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-06-10T04:15:25.795Z,1749528925.795 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-06-10T04:15:25.798Z,1749528925.798 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-06-10T04:15:25.801Z,1749528925.801 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-06-10T04:15:25.804Z,1749528925.804 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-06-10T04:15:25.808Z,1749528925.808 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-06-10T04:15:25.811Z,1749528925.811 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-06-10T04:15:25.815Z,1749528925.815 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-06-10T04:15:25.818Z,1749528925.818 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-06-10T04:15:25.841Z,1749528925.841 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool 2025-06-10T04:15:25.844Z,1749528925.844 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m 2025-06-10T04:15:25.852Z,1749528925.852 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min 2025-06-10T04:15:25.856Z,1749528925.856 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-06-10T04:15:25.868Z,1749528925.868 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-06-10T04:15:25.875Z,1749528925.875 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count 2025-06-10T04:15:25.879Z,1749528925.879 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count 2025-06-10T04:15:25.895Z,1749528925.895 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count 2025-06-10T04:15:25.913Z,1749528925.913 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count 2025-06-10T04:15:25.921Z,1749528925.921 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count 2025-06-10T04:15:25.924Z,1749528925.924 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool 2025-06-10T04:15:25.924Z,1749528925.924 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-06-10T04:15:25.973Z,1749528925.973 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-06-10T04:15:25.974Z,1749528925.974 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-06-10T04:15:26.499Z,1749528926.499 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2025-06-10T04:15:26.507Z,1749528926.507 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-06-10T04:15:26.510Z,1749528926.510 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-06-10T04:15:26.514Z,1749528926.514 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-06-10T04:15:26.529Z,1749528926.529 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-06-10T04:15:26.532Z,1749528926.532 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-06-10T04:15:26.540Z,1749528926.540 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s 2025-06-10T04:15:26.552Z,1749528926.552 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-06-10T04:15:26.567Z,1749528926.567 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-06-10T04:15:26.583Z,1749528926.583 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-06-10T04:15:26.634Z,1749528926.634 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-06-10T04:15:26.634Z,1749528926.634 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-06-10T04:15:26.635Z,1749528926.635 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-06-10T04:15:26.857Z,1749528926.857 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2025-06-10T04:15:26.867Z,1749528926.867 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-06-10T04:15:26.878Z,1749528926.878 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2025-06-10T04:15:26.886Z,1749528926.886 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-06-10T04:15:26.893Z,1749528926.893 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-06-10T04:15:26.894Z,1749528926.894 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-06-10T04:15:26.912Z,1749528926.912 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-06-10T04:15:26.946Z,1749528926.946 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-06-10T04:15:26.979Z,1749528926.979 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-06-10T04:15:26.979Z,1749528926.979 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-06-10T04:15:26.980Z,1749528926.980 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-06-10T04:15:27.023Z,1749528927.023 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-06-10T04:15:27.031Z,1749528927.031 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-06-10T04:15:27.038Z,1749528927.038 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-06-10T04:15:27.045Z,1749528927.045 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-06-10T04:15:27.045Z,1749528927.045 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-06-10T04:15:27.047Z,1749528927.047 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-06-10T04:15:27.325Z,1749528927.325 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-06-10T04:15:27.334Z,1749528927.334 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-06-10T04:15:27.341Z,1749528927.341 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-06-10T04:15:27.344Z,1749528927.344 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-06-10T04:15:27.382Z,1749528927.382 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-06-10T04:15:27.386Z,1749528927.386 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool 2025-06-10T04:15:27.412Z,1749528927.412 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-06-10T04:15:27.418Z,1749528927.418 [keepstation:H.Pitch](DEBUG): Construct. 2025-06-10T04:15:27.468Z,1749528927.468 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-06-10T04:15:27.482Z,1749528927.482 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct. 2025-06-10T04:15:27.490Z,1749528927.490 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct. 2025-06-10T04:15:27.535Z,1749528927.535 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-06-10T04:15:27.573Z,1749528927.573 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct. 2025-06-10T04:15:27.579Z,1749528927.579 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct. 2025-06-10T04:15:27.598Z,1749528927.598 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct. 2025-06-10T04:15:27.616Z,1749528927.616 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation. 2025-06-10T04:15:27.647Z,1749528927.647 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-06-10T04:15:27.694Z,1749528927.694 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait. 2025-06-10T04:15:27.733Z,1749528927.733 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission keepstation { """ Vehicle transits to desired waypoint (or stays put if none is commanded) and stays within the specified radius. """ arguments { MissionTimeout = 4 hour """ Maximum duration of mission """ NeedCommsTime = 45 minute """ How often to surface for communications """ Latitude = NaN degree """ Latitude of waypoint to hold. If NaN, hold the latitude at start of mission. """ Longitude = NaN degree """ Longitude of waypoint to hold. If NaN, hold the longitude at start of mission. """ Depth = 30 meter """ Depth held during drift mode """ ApproachDepth = 15 meter """ Depth for initial approach to station. """ DepthDeadband = 4 meter """ How much vertical drift from the specified depth is allowed in drift mode """ Speed = 0.75 meter_per_second """ Vehicle transit speed. """ Radius = 200 meter """ Radius of circle around waypoint to hold. """ SinkOffSurface = false """ If true, use buoyancy only to decend to SinkDepth before driving to first target depth """ SinkDepth = 10 meter """ Initial depth to decend using only buoyancy before driving """ SinkDepthTimeout = 10 minute """ Max wait time for the vehicle to reach the target depth """ MaxDepth = 45 meter """ Maximum allowable depth during the mission. """ MinOffshore = 2 kilometer """ Minimum distance from the shoreline to maintain """ } output { DiveMode = 0 count """ Mission variable (don't change). The mission sets this variable to switch between dive modes. Initialized to DoingComms. """ DoingComms = 0 count """ Mission variable (don't change). The mission will run in this mode when doing surface comms. """ SurfaceSink = 1 count """ Mission variable (don't change). The mission will run in this mode when sinking from the surface using only buoyancy. """ TransitToStation = 2 count """ Mission variable (don't change). The mission will run in this mode when performing the transit to station """ StationKeep = 3 count """ Mission variable (don't change). The mission will run in this mode when keeping station """ OnStation = false """ Mission variable (don't change). Determines last mode (Transit or StationKeep) to resume after needcomms """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Pitch { run in parallel set massPosition = Control:VerticalControl.massDefault } aggregate SurfaceComms { run when ( called or ( elapsed ( Universal:time_fix ) > NeedCommsTime ) ) assign in sequence DiveMode = DoingComms call priorityHere=false refId="NeedComms" aggregate setTransit { run in sequence break if ( SinkOffSurface or OnStation ) assign in sequence DiveMode = TransitToStation } aggregate setStation { run in sequence break if ( SinkOffSurface or not OnStation ) assign in sequence DiveMode = StationKeep } aggregate setSink { run in sequence break if ( not SinkOffSurface ) assign in sequence DiveMode = SurfaceSink } } call id="StartingMission" priorityHere=false refId="SurfaceComms" aggregate DiveCmd { run when ( called ) aggregate SurfaceSink { run while ( DiveMode == SurfaceSink ) aggregate sink { run in sequence behavior Guidance:Buoyancy id="BuoyancyHold" { run in parallel set position = NaN cc } behavior Guidance:SetSpeed { run in parallel set speed = 0 m/s } syslog info "Sinking to " + SinkDepth~meter behavior Guidance:Pitch { run in sequence timeout duration=SinkDepthTimeout { syslog important "Timed out while trying to reach target sink depth at current depth of " + Universal:depth~meter } set depth = SinkDepth } # Once we've sunk, either resume transit or station keeping depths and speeds aggregate sinkToTransit { run in sequence break if ( OnStation ) assign in sequence DiveMode = TransitToStation } aggregate sinkToStation { run in sequence break if ( not OnStation ) assign in sequence DiveMode = StationKeep } } } aggregate TransitToStation { run while ( DiveMode == TransitToStation ) aggregate dive { run in sequence behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch { run in parallel set depth = ApproachDepth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } } } aggregate StationKeep { run while ( DiveMode == StationKeep ) assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:KeepStation { run in parallel set latitude = Latitude set longitude = Longitude set radius = Radius set speed = Speed } } } aggregate TransitToStation { """ Need a separate aggregate for transit if we want to specify a different depth for the approach. """ run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude } assign in sequence DiveMode = StationKeep assign in sequence OnStation = true } call id="OnStation" priorityHere=false refId="NeedComms" aggregate KeepStation { run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Wait { """ Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. """ run in sequence set duration = MissionTimeout } } } 2025-06-10T04:15:27.734Z,1749528927.734 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-06-10T04:15:28.017Z,1749528928.017 [MissionManager](IMPORTANT): Started mission Default 2025-06-10T04:15:28.017Z,1749528928.017 [Default] Running Loop=1 2025-06-10T04:15:28.018Z,1749528928.018 [Default](DEBUG): Aggregate::initialize Default 2025-06-10T04:15:28.018Z,1749528928.018 [Default:B.GoToSurface] Running Loop=1 2025-06-10T04:15:28.018Z,1749528928.018 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-10T04:15:28.018Z,1749528928.018 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-06-10T04:15:28.018Z,1749528928.018 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-06-10T04:15:28.019Z,1749528928.019 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-06-10T04:15:28.019Z,1749528928.019 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-06-10T04:15:28.019Z,1749528928.019 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-10T04:15:28.020Z,1749528928.020 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-10T04:15:28.020Z,1749528928.020 [Default:A.Wait] Running Loop=1 2025-06-10T04:15:28.020Z,1749528928.020 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-06-10T04:15:41.167Z,1749528941.167 [Default:A.Wait](INFO): Done Waiting. 2025-06-10T04:15:41.167Z,1749528941.167 [Default:A.Wait] Stopped 2025-06-10T04:15:41.167Z,1749528941.167 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-06-10T04:15:41.434Z,1749528941.434 [Default:CheckIn] Running Loop=1 2025-06-10T04:15:41.434Z,1749528941.434 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-10T04:15:41.434Z,1749528941.434 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-10T04:15:43.378Z,1749528943.378 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,041538.00,A,3642.07744,N,12151.82868,W,0.467,129.57,100625,,,A*73 2025-06-10T04:15:43.380Z,1749528943.380 [NAL9602](INFO): GPS fix at 20250610T041538: (36.701291, -121.863811) 2025-06-10T04:15:43.435Z,1749528943.435 [Default:CheckIn:Read_GPS] Stopped 2025-06-10T04:15:43.436Z,1749528943.436 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-10T04:15:50.685Z,1749528950.685 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20250609T212601/Courier0046.lzma 2025-06-10T04:15:51.227Z,1749528951.227 [DataOverHttps](INFO): Moved sent file to Logs/20250609T212601/Courier0046.lzma.bak 2025-06-10T04:15:51.227Z,1749528951.227 [DataOverHttps](INFO): SBD MOMSN=25169498 2025-06-10T04:15:55.247Z,1749528955.247 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 11 hour 2025-06-10T04:15:55.248Z,1749528955.248 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute 2025-06-10T04:15:55.249Z,1749528955.249 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.71935 degree 2025-06-10T04:15:55.302Z,1749528955.302 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.89812 degree 2025-06-10T04:15:55.302Z,1749528955.302 [CommandExec](IMPORTANT): got command set keepstation.Depth 20 meter 2025-06-10T04:16:14.962Z,1749528974.962 [DataOverHttps](INFO): Sending 327 bytes from file Logs/20250610T040000/Courier0000.lzma 2025-06-10T04:16:15.879Z,1749528975.879 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-10T04:16:15.963Z,1749528975.963 [DataOverHttps](INFO): Moved sent file to Logs/20250610T040000/Courier0000.lzma.bak 2025-06-10T04:16:15.963Z,1749528975.963 [DataOverHttps](INFO): SBD MOMSN=25169506 2025-06-10T04:16:28.813Z,1749528988.813 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T04:16:29.693Z,1749528989.693 [CommandExec](IMPORTANT): got command set keepstation.Speed 1 meter_per_second 2025-06-10T04:16:29.694Z,1749528989.694 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool 2025-06-10T04:16:29.695Z,1749528989.695 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 45 meter 2025-06-10T04:16:29.696Z,1749528989.696 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second 2025-06-10T04:16:29.696Z,1749528989.696 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 10 minute 2025-06-10T04:16:29.697Z,1749528989.697 [CommandExec](IMPORTANT): got command run 2025-06-10T04:16:29.699Z,1749528989.699 [CommandExec](IMPORTANT): Running 2025-06-10T04:16:30.031Z,1749528990.031 [Default] Stopped 2025-06-10T04:16:30.031Z,1749528990.031 [Default](DEBUG): Aggregate::uninitialize Default 2025-06-10T04:16:30.031Z,1749528990.031 [Default:B.GoToSurface] Stopped 2025-06-10T04:16:30.032Z,1749528990.032 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-10T04:16:30.032Z,1749528990.032 [Default:CheckIn] Stopped 2025-06-10T04:16:30.032Z,1749528990.032 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-10T04:16:30.032Z,1749528990.032 [Default:CheckIn:Read_Iridium] Stopped 2025-06-10T04:16:30.032Z,1749528990.032 [MissionManager](IMPORTANT): Started mission keepstation 2025-06-10T04:16:30.032Z,1749528990.032 [keepstation] Running Loop=1 2025-06-10T04:16:30.032Z,1749528990.032 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-06-10T04:16:30.032Z,1749528990.032 [keepstation:StandardEnvelopes] Running Loop=1 2025-06-10T04:16:30.033Z,1749528990.033 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-06-10T04:16:30.049Z,1749528990.049 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-10T04:16:30.049Z,1749528990.049 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-06-10T04:16:30.049Z,1749528990.049 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-10T04:16:30.049Z,1749528990.049 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-06-10T04:16:30.049Z,1749528990.049 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-10T04:16:30.049Z,1749528990.049 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-06-10T04:16:30.050Z,1749528990.050 [keepstation:BackseatDriver] Running Loop=1 2025-06-10T04:16:30.050Z,1749528990.050 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-06-10T04:16:30.050Z,1749528990.050 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-06-10T04:16:30.050Z,1749528990.050 [keepstation:PowerOnly] Running Loop=1 2025-06-10T04:16:30.050Z,1749528990.050 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-06-10T04:16:30.050Z,1749528990.050 [keepstation:PowerOnly:A] Running Loop=1 2025-06-10T04:16:30.050Z,1749528990.050 [keepstation:PowerOnly:B] Running Loop=1 2025-06-10T04:16:30.050Z,1749528990.050 [keepstation:PowerOnly:C] Running Loop=1 2025-06-10T04:16:30.050Z,1749528990.050 [keepstation:PowerOnly:D] Running Loop=1 2025-06-10T04:16:30.050Z,1749528990.050 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-06-10T04:16:30.050Z,1749528990.050 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-06-10T04:16:30.051Z,1749528990.051 [keepstation:H.Pitch] Running Loop=1 2025-06-10T04:16:30.051Z,1749528990.051 [keepstation:H.Pitch](DEBUG): Initialize. 2025-06-10T04:16:30.051Z,1749528990.051 [keepstation:B] Running Loop=1 2025-06-10T04:16:30.052Z,1749528990.052 [keepstation:H.Pitch] Running Loop=1 2025-06-10T04:16:30.052Z,1749528990.052 [keepstation:PowerOnly] Running Loop=1 2025-06-10T04:16:30.052Z,1749528990.052 [keepstation:PowerOnly:D] Stopped 2025-06-10T04:16:30.052Z,1749528990.052 [keepstation:PowerOnly:C] Stopped 2025-06-10T04:16:30.052Z,1749528990.052 [keepstation:PowerOnly:B] Stopped 2025-06-10T04:16:30.052Z,1749528990.052 [keepstation:PowerOnly:A] Stopped 2025-06-10T04:16:30.052Z,1749528990.052 [keepstation:BackseatDriver] Running Loop=1 2025-06-10T04:16:30.057Z,1749528990.057 [keepstation:StandardEnvelopes] Running Loop=1 2025-06-10T04:16:30.057Z,1749528990.057 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-10T04:16:30.057Z,1749528990.057 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-10T04:16:30.058Z,1749528990.058 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-10T04:16:30.058Z,1749528990.058 [keepstation:B] Stopped 2025-06-10T04:16:30.058Z,1749528990.058 [keepstation:D] Running Loop=1 2025-06-10T04:16:30.448Z,1749528990.448 [keepstation:D] Stopped 2025-06-10T04:16:30.448Z,1749528990.448 [keepstation:E] Running Loop=1 2025-06-10T04:16:30.846Z,1749528990.846 [keepstation:E] Stopped 2025-06-10T04:16:30.846Z,1749528990.846 [keepstation:StartingMission] Running Loop=1 2025-06-10T04:16:30.846Z,1749528990.846 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-06-10T04:16:31.235Z,1749528991.235 [keepstation:SurfaceComms] Running Loop=1 2025-06-10T04:16:31.235Z,1749528991.235 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-10T04:16:31.236Z,1749528991.236 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-10T04:16:31.236Z,1749528991.236 [keepstation:SurfaceComms:A] Stopped 2025-06-10T04:16:31.236Z,1749528991.236 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-10T04:16:31.236Z,1749528991.236 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-10T04:16:31.688Z,1749528991.688 [keepstation:NeedComms] Running Loop=1 2025-06-10T04:16:31.688Z,1749528991.688 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-10T04:16:31.688Z,1749528991.688 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-10T04:16:31.688Z,1749528991.688 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-10T04:16:31.688Z,1749528991.688 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-10T04:16:31.693Z,1749528991.693 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-10T04:16:31.694Z,1749528991.694 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-10T04:16:31.694Z,1749528991.694 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-10T04:16:31.694Z,1749528991.694 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-10T04:16:31.695Z,1749528991.695 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-10T04:16:31.695Z,1749528991.695 [keepstation:NeedComms:A] Running Loop=1 2025-06-10T04:16:31.696Z,1749528991.696 [keepstation:NeedComms:A](INFO): last time_fix was: 1749528938.000000 second since 1970/01/01T00:00:00Z 2025-06-10T04:16:31.696Z,1749528991.696 [keepstation:NeedComms:A] Stopped 2025-06-10T04:16:32.056Z,1749528992.056 [keepstation:NeedComms:C] Running Loop=1 2025-06-10T04:16:32.487Z,1749528992.487 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-06-10T04:16:34.060Z,1749528994.060 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,041628.00,A,3642.07585,N,12151.82328,W,0.875,183.31,100625,,,A*7E 2025-06-10T04:16:34.067Z,1749528994.067 [NAL9602](INFO): GPS fix at 20250610T041628: (36.701264, -121.863721) 2025-06-10T04:16:34.138Z,1749528994.138 [keepstation:NeedComms:C] Stopped 2025-06-10T04:16:34.139Z,1749528994.139 [keepstation:NeedComms:D] Running Loop=1 2025-06-10T04:16:34.532Z,1749528994.532 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-06-10T04:16:36.917Z,1749528996.917 [DataOverHttps](INFO): Sending 1839 bytes from file Logs/20250609T212601/Express0047.lzma 2025-06-10T04:16:37.915Z,1749528997.915 [DataOverHttps](INFO): Moved sent file to Logs/20250609T212601/Express0047.lzma.bak 2025-06-10T04:16:37.915Z,1749528997.915 [DataOverHttps](INFO): SBD MOMSN=25169526 2025-06-10T04:16:48.182Z,1749529008.182 [NAL9602](INFO): SBD MO Status=0, MOMSN=21472, MT Status=0, MTMSN=0 2025-06-10T04:16:48.182Z,1749529008.182 [NAL9602](INFO): No messages in MT queue 2025-06-10T04:16:54.358Z,1749529014.358 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250610T040000/Courier0003.lzma 2025-06-10T04:16:55.359Z,1749529015.359 [DataOverHttps](INFO): Moved sent file to Logs/20250610T040000/Courier0003.lzma.bak 2025-06-10T04:16:55.359Z,1749529015.359 [DataOverHttps](INFO): SBD MOMSN=25169540 2025-06-10T04:17:13.652Z,1749529033.652 [DataOverHttps](INFO): Sending 1347 bytes from file Logs/20250610T040000/Express0001.lzma 2025-06-10T04:17:14.652Z,1749529034.652 [DataOverHttps](INFO): Moved sent file to Logs/20250610T040000/Express0001.lzma.bak 2025-06-10T04:17:14.652Z,1749529034.652 [DataOverHttps](INFO): SBD MOMSN=25169547 2025-06-10T04:17:18.858Z,1749529038.858 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-10T04:17:31.368Z,1749529051.368 [DataOverHttps](INFO): Sending 384 bytes from file Logs/20250610T040000/Express0004.lzma 2025-06-10T04:17:32.363Z,1749529052.363 [DataOverHttps](INFO): Moved sent file to Logs/20250610T040000/Express0004.lzma.bak 2025-06-10T04:17:32.363Z,1749529052.363 [DataOverHttps](INFO): SBD MOMSN=25169572 2025-06-10T04:17:33.847Z,1749529053.847 [keepstation:NeedComms:D] Stopped 2025-06-10T04:17:33.848Z,1749529053.848 [keepstation:NeedComms:E] Running Loop=1 2025-06-10T04:17:34.238Z,1749529054.238 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-06-10T04:17:35.844Z,1749529055.844 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,041730.00,A,3642.07504,N,12151.81751,W,1.089,61.39,100625,,,A*49 2025-06-10T04:17:35.851Z,1749529055.851 [NAL9602](INFO): GPS fix at 20250610T041730: (36.701251, -121.863625) 2025-06-10T04:17:35.900Z,1749529055.900 [keepstation:NeedComms:E] Stopped 2025-06-10T04:17:35.909Z,1749529055.909 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-10T04:17:35.909Z,1749529055.909 [keepstation:NeedComms] Stopped 2025-06-10T04:17:35.909Z,1749529055.909 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-10T04:17:35.909Z,1749529055.909 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-10T04:17:35.909Z,1749529055.909 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-10T04:17:36.278Z,1749529056.278 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-10T04:17:36.278Z,1749529056.278 [keepstation:SurfaceComms:B] Stopped 2025-06-10T04:17:36.278Z,1749529056.278 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-10T04:17:36.278Z,1749529056.278 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-10T04:17:36.278Z,1749529056.278 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-10T04:17:36.278Z,1749529056.278 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-10T04:17:36.664Z,1749529056.664 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-10T04:17:36.669Z,1749529056.669 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-10T04:17:36.669Z,1749529056.669 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-10T04:17:36.669Z,1749529056.669 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-10T04:17:36.669Z,1749529056.669 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-10T04:17:36.669Z,1749529056.669 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-10T04:17:37.058Z,1749529057.058 [keepstation:SurfaceComms:setStation] Stopped 2025-06-10T04:17:37.058Z,1749529057.058 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-10T04:17:37.059Z,1749529057.059 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-10T04:17:37.059Z,1749529057.059 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-10T04:17:37.059Z,1749529057.059 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-10T04:17:37.059Z,1749529057.059 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-10T04:17:37.503Z,1749529057.503 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-10T04:17:37.503Z,1749529057.503 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-10T04:17:37.503Z,1749529057.503 [keepstation:SurfaceComms:setSink] Stopped 2025-06-10T04:17:37.503Z,1749529057.503 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-10T04:17:37.503Z,1749529057.503 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-10T04:17:37.503Z,1749529057.503 [keepstation:SurfaceComms] Stopped 2025-06-10T04:17:37.503Z,1749529057.503 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-10T04:17:37.889Z,1749529057.889 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-06-10T04:17:37.889Z,1749529057.889 [keepstation:StartingMission] Stopped 2025-06-10T04:17:37.889Z,1749529057.889 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-06-10T04:17:37.890Z,1749529057.890 [keepstation:TransitToStation] Running Loop=1 2025-06-10T04:17:37.890Z,1749529057.890 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-06-10T04:17:37.890Z,1749529057.890 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-10T04:17:37.890Z,1749529057.890 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-06-10T04:17:37.890Z,1749529057.890 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-06-10T04:17:37.890Z,1749529057.890 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-06-10T04:17:37.890Z,1749529057.890 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-06-10T04:17:37.890Z,1749529057.890 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-06-10T04:17:37.891Z,1749529057.891 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.719349,-121.898117 2025-06-10T04:17:38.340Z,1749529058.340 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.719349,-121.898117 2025-06-10T04:17:38.340Z,1749529058.340 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-10T04:17:38.341Z,1749529058.341 [keepstation:DiveCmd] Running Loop=1 2025-06-10T04:17:38.345Z,1749529058.345 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2025-06-10T04:17:38.345Z,1749529058.345 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-10T04:17:38.345Z,1749529058.345 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-10T04:17:38.345Z,1749529058.345 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-10T04:17:38.345Z,1749529058.345 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T04:17:38.345Z,1749529058.345 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-10T04:17:38.346Z,1749529058.346 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-10T04:17:38.346Z,1749529058.346 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-10T04:17:38.346Z,1749529058.346 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-10T04:17:38.346Z,1749529058.346 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-10T04:17:38.346Z,1749529058.346 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-10T04:17:38.346Z,1749529058.346 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-06-10T04:17:38.346Z,1749529058.346 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-06-10T04:17:38.346Z,1749529058.346 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-06-10T04:17:38.346Z,1749529058.346 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-10T04:17:38.346Z,1749529058.346 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-10T04:17:38.346Z,1749529058.346 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-10T04:17:38.347Z,1749529058.347 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-06-10T04:17:38.347Z,1749529058.347 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-10T04:17:38.347Z,1749529058.347 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-06-10T04:17:38.347Z,1749529058.347 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-06-10T04:17:38.347Z,1749529058.347 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-06-10T04:17:38.347Z,1749529058.347 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-06-10T04:17:38.347Z,1749529058.347 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-06-10T04:17:38.347Z,1749529058.347 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-06-10T04:17:38.347Z,1749529058.347 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-06-10T04:17:38.348Z,1749529058.348 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-06-10T04:17:38.348Z,1749529058.348 [keepstation:DiveCmd:StationKeep] Stopped 2025-06-10T04:17:38.348Z,1749529058.348 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-06-10T04:17:38.349Z,1749529058.349 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-06-10T04:17:38.353Z,1749529058.353 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-06-10T04:17:38.353Z,1749529058.353 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-06-10T04:17:38.353Z,1749529058.353 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-06-10T04:17:38.353Z,1749529058.353 [keepstation:DiveCmd:TransitToStation] Stopped 2025-06-10T04:17:38.353Z,1749529058.353 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-06-10T04:17:38.353Z,1749529058.353 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-06-10T04:17:38.353Z,1749529058.353 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-06-10T04:17:38.353Z,1749529058.353 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-06-10T04:17:38.353Z,1749529058.353 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T04:17:38.354Z,1749529058.354 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-06-10T04:17:38.354Z,1749529058.354 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-06-10T04:17:38.354Z,1749529058.354 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-06-10T04:17:38.354Z,1749529058.354 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-10T04:17:38.354Z,1749529058.354 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-10T04:17:38.354Z,1749529058.354 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-10T04:17:38.355Z,1749529058.355 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-10T04:17:38.355Z,1749529058.355 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-10T04:17:38.355Z,1749529058.355 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-10T04:17:38.355Z,1749529058.355 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-10T04:17:47.153Z,1749529067.153 [NAL9602](INFO): SBD MO Status=0, MOMSN=21473, MT Status=0, MTMSN=0 2025-06-10T04:17:47.153Z,1749529067.153 [NAL9602](INFO): No messages in MT queue 2025-06-10T04:18:17.847Z,1749529097.847 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-10T04:18:36.026Z,1749529116.026 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-10T04:18:36.027Z,1749529116.027 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-06-10T04:18:36.030Z,1749529116.030 [BPC1](INFO): Received data from all battery sticks. 2025-06-10T04:19:34.917Z,1749529174.917 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T04:20:06.042Z,1749529206.042 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T04:20:23.977Z,1749529223.977 [Radio_Surface](INFO): Powering down 2025-06-10T04:20:37.165Z,1749529237.165 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T04:20:38.169Z,1749529238.169 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-10T04:21:26.534Z,1749529286.534 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-10T04:21:26.534Z,1749529286.534 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-10T04:21:26.534Z,1749529286.534 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T04:21:26.535Z,1749529286.535 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-10T04:21:26.946Z,1749529286.946 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-10T04:21:26.946Z,1749529286.946 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T04:21:26.946Z,1749529286.946 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-10T04:21:26.946Z,1749529286.946 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T04:21:26.947Z,1749529286.947 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-10T04:21:26.947Z,1749529286.947 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T04:21:26.947Z,1749529286.947 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-10T04:21:27.348Z,1749529287.348 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-10T04:21:27.348Z,1749529287.348 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-06-10T04:21:27.348Z,1749529287.348 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-06-10T04:21:27.348Z,1749529287.348 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-06-10T04:21:27.348Z,1749529287.348 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-10T04:21:27.348Z,1749529287.348 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-10T04:21:27.348Z,1749529287.348 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-10T04:21:27.348Z,1749529287.348 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-06-10T04:21:27.350Z,1749529287.350 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-10T04:21:27.350Z,1749529287.350 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-06-10T04:21:27.350Z,1749529287.350 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-10T04:21:27.350Z,1749529287.350 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-10T04:21:27.350Z,1749529287.350 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-10T04:21:27.351Z,1749529287.351 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-10T04:21:27.351Z,1749529287.351 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-06-10T04:21:27.351Z,1749529287.351 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-06-10T04:21:27.351Z,1749529287.351 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-10T04:21:27.351Z,1749529287.351 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T04:21:27.351Z,1749529287.351 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-10T04:21:27.351Z,1749529287.351 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T04:21:27.351Z,1749529287.351 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-10T04:21:27.351Z,1749529287.351 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-10T04:21:27.351Z,1749529287.351 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-10T04:21:27.352Z,1749529287.352 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T04:21:27.352Z,1749529287.352 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-10T04:21:45.437Z,1749529305.437 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.718887 2025-06-10T04:26:28.895Z,1749529588.895 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T04:33:26.353Z,1749530006.353 [DVL_micro](ERROR): DVL uart error: serial timeout 2025-06-10T04:33:26.353Z,1749530006.353 [DVL_micro] Communications Fault, FailCount= 1 2025-06-10T04:33:26.353Z,1749530006.353 [DVL_micro](ERROR): Communications Fault 2025-06-10T04:33:26.354Z,1749530006.354 [DVL_micro](ERROR): Failed to parse: 2025-06-10T04:33:26.452Z,1749530006.452 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-06-10T04:33:26.758Z,1749530006.758 [DVL_micro](INFO): Powering down 2025-06-10T04:33:27.596Z,1749530007.596 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-06-10T04:33:27.596Z,1749530007.596 [DVL_micro] No Fault, FailCount= 1 2025-06-10T04:36:13.520Z,1749530173.520 [DVL_micro](ERROR): Failed to parse: :WI,-00134,+01015,+00070,+00000,A 2025-06-10T04:36:29.283Z,1749530189.283 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T04:46:29.284Z,1749530789.284 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T04:54:38.218Z,1749531278.218 [DVL_micro](ERROR): Failed to parse: :,+00031,+00000,I 2025-06-10T04:56:17.641Z,1749531377.641 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,+00057,+00794,+00038,+00 2025-06-10T04:56:17.641Z,1749531377.641 [DVL_micro](ERROR): Failed to parse: :BI,+00057,+00794,+00038,+00 2025-06-10T04:56:20.020Z,1749531380.020 [DVL_micro](ERROR): Failed to parse: :SA,-00.29,+01.97,283.7 2025-06-10T04:56:29.313Z,1749531389.313 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T05:06:29.709Z,1749531989.709 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T05:16:29.724Z,1749532589.724 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T05:17:35.998Z,1749532655.998 [keepstation:SurfaceComms] Running Loop=1 2025-06-10T05:17:35.999Z,1749532655.999 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-10T05:17:35.999Z,1749532655.999 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-10T05:17:35.000Z,1749532656.000 [keepstation:SurfaceComms:A] Stopped 2025-06-10T05:17:35.000Z,1749532656.000 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-10T05:17:36.002Z,1749532656.002 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-10T05:17:36.399Z,1749532656.399 [keepstation:TransitToStation:Dive] Stopped 2025-06-10T05:17:36.399Z,1749532656.399 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-06-10T05:17:36.399Z,1749532656.399 [keepstation:TransitToStation:Dive:A] Stopped 2025-06-10T05:17:36.399Z,1749532656.399 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-06-10T05:17:36.399Z,1749532656.399 [keepstation:DiveCmd:TransitToStation] Stopped 2025-06-10T05:17:36.400Z,1749532656.400 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-06-10T05:17:36.400Z,1749532656.400 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-06-10T05:17:36.400Z,1749532656.400 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-06-10T05:17:36.400Z,1749532656.400 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-06-10T05:17:36.400Z,1749532656.400 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T05:17:36.400Z,1749532656.400 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-06-10T05:17:36.400Z,1749532656.400 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-06-10T05:17:36.400Z,1749532656.400 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-06-10T05:17:36.402Z,1749532656.402 [keepstation:NeedComms] Running Loop=1 2025-06-10T05:17:36.402Z,1749532656.402 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-10T05:17:36.402Z,1749532656.402 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-10T05:17:36.402Z,1749532656.402 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-10T05:17:36.403Z,1749532656.403 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-10T05:17:36.403Z,1749532656.403 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-10T05:17:36.403Z,1749532656.403 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-10T05:17:36.404Z,1749532656.404 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-10T05:17:36.404Z,1749532656.404 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-10T05:17:36.404Z,1749532656.404 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-10T05:17:36.405Z,1749532656.405 [keepstation:NeedComms:A] Running Loop=1 2025-06-10T05:17:36.407Z,1749532656.407 [keepstation:NeedComms:A](INFO): last time_fix was: 1749529050.000000 second since 1970/01/01T00:00:00Z 2025-06-10T05:17:36.407Z,1749532656.407 [keepstation:NeedComms:A] Stopped 2025-06-10T05:18:26.890Z,1749532706.890 [keepstation:NeedComms:C] Running Loop=1 2025-06-10T05:18:27.409Z,1749532707.409 [Radio_Surface](INFO): Powering up 2025-06-10T05:18:33.609Z,1749532713.609 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-10T05:18:52.716Z,1749532732.716 [NAL9602](INFO): SBD MO Status=0, MOMSN=21474, MT Status=0, MTMSN=0 2025-06-10T05:18:52.716Z,1749532732.716 [NAL9602](INFO): No messages in MT queue 2025-06-10T05:18:53.932Z,1749532733.932 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,051848.00,A,3642.97628,N,12153.40642,W,0.505,209.49,100625,,,A*7B 2025-06-10T05:18:53.935Z,1749532733.935 [NAL9602](INFO): GPS fix at 20250610T051848: (36.716271, -121.890107) 2025-06-10T05:18:53.952Z,1749532733.952 [UniversalFixResidualReporter](INFO): Fix residual: 4.2 %DT, over the last 2993.8 m. Residual distance 124.7 m at bearing -153.8 degrees. Fix at (36.7163, -121.8901) with 2891.8 m made good. 2025-06-10T05:18:53.954Z,1749532733.954 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.719349,-121.898117 2025-06-10T05:18:53.957Z,1749532733.957 [keepstation:NeedComms:C] Stopped 2025-06-10T05:18:53.957Z,1749532733.957 [keepstation:NeedComms:D] Running Loop=1 2025-06-10T05:19:03.742Z,1749532743.742 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:19:11.954Z,1749532751.954 [NAL9602](INFO): SBD MO Status=1, MOMSN=21475, MT Status=0, MTMSN=0 2025-06-10T05:19:12.001Z,1749532752.001 [NAL9602](INFO): Sent 215 bytes from file Logs/20250610T040000/Courier0006.lzma 2025-06-10T05:19:12.001Z,1749532752.001 [NAL9602](INFO): Packets left to send: 0 2025-06-10T05:19:22.796Z,1749532762.796 [NAL9602](INFO): SBD MO Status=1, MOMSN=21476, MT Status=0, MTMSN=0 2025-06-10T05:19:22.857Z,1749532762.857 [NAL9602](INFO): Sent 332 bytes from file Logs/20250610T040000/Express0007.lzma 2025-06-10T05:19:22.857Z,1749532762.857 [NAL9602](INFO): Packets left to send: 2 2025-06-10T05:19:34.877Z,1749532774.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:19:36.639Z,1749532776.639 [NAL9602](INFO): SBD MO Status=1, MOMSN=21477, MT Status=0, MTMSN=0 2025-06-10T05:19:36.693Z,1749532776.693 [NAL9602](INFO): Sent 332 bytes from file Logs/20250610T040000/Express0007.lzma 2025-06-10T05:19:36.693Z,1749532776.693 [NAL9602](INFO): Packets left to send: 1 2025-06-10T05:19:44.700Z,1749532784.700 [NAL9602](INFO): SBD MO Status=1, MOMSN=21478, MT Status=0, MTMSN=0 2025-06-10T05:19:44.761Z,1749532784.761 [NAL9602](INFO): Sent 81 bytes from file Logs/20250610T040000/Express0007.lzma 2025-06-10T05:19:44.762Z,1749532784.762 [NAL9602](INFO): Packets left to send: 0 2025-06-10T05:19:53.214Z,1749532793.214 [NAL9602](INFO): SBD MO Status=0, MOMSN=21479, MT Status=0, MTMSN=0 2025-06-10T05:19:53.296Z,1749532793.296 [keepstation:NeedComms:D] Stopped 2025-06-10T05:19:53.297Z,1749532793.297 [keepstation:NeedComms:E] Running Loop=1 2025-06-10T05:19:55.705Z,1749532795.705 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,051950.00,A,3642.97406,N,12153.40659,W,0.622,166.33,100625,,,A*76 2025-06-10T05:19:55.724Z,1749532795.724 [NAL9602](INFO): GPS fix at 20250610T051950: (36.716234, -121.890110) 2025-06-10T05:19:55.743Z,1749532795.743 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.719349,-121.898117 2025-06-10T05:19:55.748Z,1749532795.748 [keepstation:NeedComms:E] Stopped 2025-06-10T05:19:55.751Z,1749532795.751 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-10T05:19:55.751Z,1749532795.751 [keepstation:NeedComms] Stopped 2025-06-10T05:19:55.751Z,1749532795.751 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-10T05:19:55.751Z,1749532795.751 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-10T05:19:55.751Z,1749532795.751 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-10T05:19:56.118Z,1749532796.118 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-10T05:19:56.119Z,1749532796.119 [keepstation:SurfaceComms:B] Stopped 2025-06-10T05:19:56.119Z,1749532796.119 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-10T05:19:56.119Z,1749532796.119 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-10T05:19:56.119Z,1749532796.119 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-10T05:19:56.119Z,1749532796.119 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-10T05:19:56.465Z,1749532796.465 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-10T05:19:56.466Z,1749532796.466 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-10T05:19:56.466Z,1749532796.466 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-10T05:19:56.466Z,1749532796.466 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-10T05:19:56.466Z,1749532796.466 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-10T05:19:56.466Z,1749532796.466 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-10T05:19:56.876Z,1749532796.876 [keepstation:SurfaceComms:setStation] Stopped 2025-06-10T05:19:56.876Z,1749532796.876 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-10T05:19:56.876Z,1749532796.876 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-10T05:19:56.876Z,1749532796.876 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-10T05:19:56.876Z,1749532796.876 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-10T05:19:56.876Z,1749532796.876 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-10T05:19:57.272Z,1749532797.272 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-10T05:19:57.272Z,1749532797.272 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-10T05:19:57.272Z,1749532797.272 [keepstation:SurfaceComms:setSink] Stopped 2025-06-10T05:19:57.272Z,1749532797.272 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-10T05:19:57.272Z,1749532797.272 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-10T05:19:57.272Z,1749532797.272 [keepstation:SurfaceComms] Stopped 2025-06-10T05:19:57.272Z,1749532797.272 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-10T05:19:57.667Z,1749532797.667 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-10T05:19:57.667Z,1749532797.667 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-06-10T05:19:57.668Z,1749532797.668 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-06-10T05:19:57.668Z,1749532797.668 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-06-10T05:19:57.668Z,1749532797.668 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-10T05:19:57.668Z,1749532797.668 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-10T05:19:57.668Z,1749532797.668 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-10T05:19:57.668Z,1749532797.668 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T05:19:57.669Z,1749532797.669 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-10T05:19:57.669Z,1749532797.669 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-10T05:19:57.669Z,1749532797.669 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-10T05:19:57.669Z,1749532797.669 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-10T05:19:57.669Z,1749532797.669 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-10T05:19:57.670Z,1749532797.670 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-10T05:19:57.670Z,1749532797.670 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-10T05:19:57.670Z,1749532797.670 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-10T05:19:57.670Z,1749532797.670 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-10T05:20:06.001Z,1749532806.001 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:20:27.951Z,1749532827.951 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-10T05:20:37.141Z,1749532837.141 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:20:46.286Z,1749532846.286 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003277 2025-06-10T05:21:38.490Z,1749532898.490 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:22:06.781Z,1749532926.781 [Radio_Surface](INFO): Powering down 2025-06-10T05:22:09.613Z,1749532929.613 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:22:10.617Z,1749532930.617 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-10T05:23:15.218Z,1749532995.218 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-10T05:23:15.218Z,1749532995.218 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-10T05:23:15.218Z,1749532995.218 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T05:23:15.218Z,1749532995.218 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-10T05:23:15.621Z,1749532995.621 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-10T05:23:15.621Z,1749532995.621 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T05:23:15.622Z,1749532995.622 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-10T05:23:15.622Z,1749532995.622 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T05:23:15.622Z,1749532995.622 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-10T05:23:15.622Z,1749532995.622 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T05:23:15.622Z,1749532995.622 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-10T05:23:16.034Z,1749532996.034 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-10T05:23:16.034Z,1749532996.034 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-06-10T05:23:16.034Z,1749532996.034 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-06-10T05:23:16.034Z,1749532996.034 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-06-10T05:23:16.034Z,1749532996.034 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-10T05:23:16.034Z,1749532996.034 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-10T05:23:16.035Z,1749532996.035 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-10T05:23:16.035Z,1749532996.035 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-06-10T05:23:16.035Z,1749532996.035 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-10T05:23:16.035Z,1749532996.035 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-06-10T05:23:16.036Z,1749532996.036 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-06-10T05:23:16.036Z,1749532996.036 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-06-10T05:23:16.036Z,1749532996.036 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-10T05:23:16.036Z,1749532996.036 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T05:23:16.036Z,1749532996.036 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-10T05:23:16.036Z,1749532996.036 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T05:23:16.036Z,1749532996.036 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-10T05:23:16.036Z,1749532996.036 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-10T05:23:16.036Z,1749532996.036 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-10T05:23:16.037Z,1749532996.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T05:23:16.037Z,1749532996.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-10T05:23:47.021Z,1749533027.021 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739151 2025-06-10T05:24:28.738Z,1749533068.738 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-06-10T05:26:15.798Z,1749533175.798 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-10T05:26:15.799Z,1749533175.799 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-06-10T05:26:15.802Z,1749533175.802 [BPC1](INFO): Received data from all battery sticks. 2025-06-10T05:26:29.935Z,1749533189.935 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T05:35:02.724Z,1749533702.724 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+0074.37,+9999.99,+0074.58 2025-06-10T05:35:59.306Z,1749533759.306 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.719349,-121.898117 2025-06-10T05:35:59.306Z,1749533759.306 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-06-10T05:35:59.306Z,1749533759.306 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-06-10T05:35:59.307Z,1749533759.307 [keepstation:TransitToStation:C] Running Loop=1 2025-06-10T05:35:59.723Z,1749533759.723 [keepstation:TransitToStation:C] Stopped 2025-06-10T05:35:59.723Z,1749533759.723 [keepstation:TransitToStation:D] Running Loop=1 2025-06-10T05:35:59.723Z,1749533759.723 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-06-10T05:35:59.723Z,1749533759.723 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-06-10T05:35:59.724Z,1749533759.724 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-06-10T05:35:59.724Z,1749533759.724 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-06-10T05:35:59.724Z,1749533759.724 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-06-10T05:35:59.724Z,1749533759.724 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-06-10T05:35:59.724Z,1749533759.724 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-06-10T05:35:59.724Z,1749533759.724 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-06-10T05:35:59.724Z,1749533759.724 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-06-10T05:35:59.725Z,1749533759.725 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-06-10T05:35:59.726Z,1749533759.726 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-06-10T05:35:59.726Z,1749533759.726 [keepstation:DiveCmd:TransitToStation] Stopped 2025-06-10T05:35:59.726Z,1749533759.726 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-06-10T05:35:59.726Z,1749533759.726 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-06-10T05:35:59.726Z,1749533759.726 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-06-10T05:35:59.727Z,1749533759.727 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-06-10T05:35:59.727Z,1749533759.727 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T05:35:59.727Z,1749533759.727 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-06-10T05:35:59.727Z,1749533759.727 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-06-10T05:35:59.727Z,1749533759.727 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-06-10T05:36:00.117Z,1749533760.117 [keepstation:TransitToStation:D] Stopped 2025-06-10T05:36:00.117Z,1749533760.117 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-06-10T05:36:00.117Z,1749533760.117 [keepstation:TransitToStation] Stopped 2025-06-10T05:36:00.117Z,1749533760.117 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-06-10T05:36:00.117Z,1749533760.117 [keepstation:TransitToStation:Dive] Stopped 2025-06-10T05:36:00.117Z,1749533760.117 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-06-10T05:36:00.117Z,1749533760.117 [keepstation:TransitToStation:Dive:A] Stopped 2025-06-10T05:36:00.118Z,1749533760.118 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-06-10T05:36:00.118Z,1749533760.118 [keepstation:OnStation] Running Loop=1 2025-06-10T05:36:00.118Z,1749533760.118 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-06-10T05:36:00.528Z,1749533760.528 [keepstation:NeedComms] Running Loop=1 2025-06-10T05:36:00.528Z,1749533760.528 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-10T05:36:00.528Z,1749533760.528 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-10T05:36:00.528Z,1749533760.528 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-10T05:36:00.528Z,1749533760.528 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-10T05:36:00.529Z,1749533760.529 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-10T05:36:00.529Z,1749533760.529 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-10T05:36:00.530Z,1749533760.530 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-10T05:36:00.530Z,1749533760.530 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-10T05:36:00.530Z,1749533760.530 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-10T05:36:00.531Z,1749533760.531 [keepstation:NeedComms:A] Running Loop=1 2025-06-10T05:36:00.532Z,1749533760.532 [keepstation:NeedComms:A](INFO): last time_fix was: 1749532790.000000 second since 1970/01/01T00:00:00Z 2025-06-10T05:36:00.532Z,1749533760.532 [keepstation:NeedComms:A] Stopped 2025-06-10T05:36:30.176Z,1749533790.176 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T05:36:49.227Z,1749533809.227 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE. Altitude:1.820000m. At depth:2.227889m. 2025-06-10T05:36:53.234Z,1749533813.234 [keepstation:NeedComms:C] Running Loop=1 2025-06-10T05:36:53.973Z,1749533813.973 [Radio_Surface](INFO): Powering up 2025-06-10T05:36:59.290Z,1749533819.290 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-10T05:37:09.798Z,1749533829.798 [NAL9602](INFO): SBD MO Status=0, MOMSN=21480, MT Status=0, MTMSN=0 2025-06-10T05:37:09.799Z,1749533829.799 [NAL9602](INFO): No messages in MT queue 2025-06-10T05:37:11.011Z,1749533831.011 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053705.00,A,3643.13546,N,12153.80692,W,0.136,252.46,100625,,,A*7D 2025-06-10T05:37:11.014Z,1749533831.014 [NAL9602](INFO): GPS fix at 20250610T053705: (36.718924, -121.896782) 2025-06-10T05:37:11.042Z,1749533831.042 [UniversalFixResidualReporter](INFO): Fix residual: 22.3 %DT, over the last 946.9 m. Residual distance 210.9 m at bearing -83.7 degrees. Fix at (36.7189, -121.8968) with 665.7 m made good. 2025-06-10T05:37:11.046Z,1749533831.046 [keepstation:NeedComms:C] Stopped 2025-06-10T05:37:11.046Z,1749533831.046 [keepstation:NeedComms:D] Running Loop=1 2025-06-10T05:37:21.345Z,1749533841.345 [NAL9602](INFO): SBD MO Status=1, MOMSN=21481, MT Status=0, MTMSN=0 2025-06-10T05:37:21.405Z,1749533841.405 [NAL9602](INFO): Sent 96 bytes from file Logs/20250610T040000/Courier0009.lzma 2025-06-10T05:37:21.406Z,1749533841.406 [NAL9602](INFO): Packets left to send: 0 2025-06-10T05:37:29.409Z,1749533849.409 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:37:31.881Z,1749533851.881 [NAL9602](INFO): SBD MO Status=1, MOMSN=21482, MT Status=0, MTMSN=0 2025-06-10T05:37:31.929Z,1749533851.929 [NAL9602](INFO): Sent 332 bytes from file Logs/20250610T040000/Express0010.lzma 2025-06-10T05:37:31.929Z,1749533851.929 [NAL9602](INFO): Packets left to send: 1 2025-06-10T05:37:38.599Z,1749533858.599 [NAL9602](INFO): SBD MO Status=1, MOMSN=21483, MT Status=0, MTMSN=0 2025-06-10T05:37:38.657Z,1749533858.657 [NAL9602](INFO): Sent 202 bytes from file Logs/20250610T040000/Express0010.lzma 2025-06-10T05:37:38.657Z,1749533858.657 [NAL9602](INFO): Packets left to send: 0 2025-06-10T05:37:41.977Z,1749533861.977 [BuoyancyServo](ERROR): getPosition uart error serial timeout 2025-06-10T05:37:41.977Z,1749533861.977 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout 2025-06-10T05:37:41.978Z,1749533861.978 [BuoyancyServo] Communications Fault, FailCount= 1 2025-06-10T05:37:41.978Z,1749533861.978 [BuoyancyServo](ERROR): Communications Fault 2025-06-10T05:37:41.986Z,1749533861.986 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2025-06-10T05:37:43.932Z,1749533863.932 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-06-10T05:37:43.932Z,1749533863.932 [BuoyancyServo](INFO): Powering down 2025-06-10T05:37:44.741Z,1749533864.741 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2025-06-10T05:37:44.742Z,1749533864.742 [BuoyancyServo] No Fault, FailCount= 1 2025-06-10T05:37:45.136Z,1749533865.136 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-06-10T05:37:45.138Z,1749533865.138 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-06-10T05:37:48.335Z,1749533868.335 [NAL9602](INFO): SBD MO Status=0, MOMSN=21484, MT Status=0, MTMSN=0 2025-06-10T05:37:48.479Z,1749533868.479 [keepstation:NeedComms:D] Stopped 2025-06-10T05:37:48.480Z,1749533868.480 [keepstation:NeedComms:E] Running Loop=1 2025-06-10T05:37:50.760Z,1749533870.760 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053745.00,A,3643.13451,N,12153.80961,W,0.700,342.23,100625,,,A*7D 2025-06-10T05:37:50.762Z,1749533870.762 [NAL9602](INFO): GPS fix at 20250610T053745: (36.718908, -121.896827) 2025-06-10T05:37:50.777Z,1749533870.777 [keepstation:NeedComms:E] Stopped 2025-06-10T05:37:50.778Z,1749533870.778 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-10T05:37:50.778Z,1749533870.778 [keepstation:NeedComms] Stopped 2025-06-10T05:37:50.778Z,1749533870.778 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-10T05:37:50.778Z,1749533870.778 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-10T05:37:50.779Z,1749533870.779 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-10T05:37:51.198Z,1749533871.198 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-06-10T05:37:51.198Z,1749533871.198 [keepstation:OnStation] Stopped 2025-06-10T05:37:51.198Z,1749533871.198 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-06-10T05:37:51.198Z,1749533871.198 [keepstation:KeepStation] Running Loop=1 2025-06-10T05:37:51.198Z,1749533871.198 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-06-10T05:37:51.198Z,1749533871.198 [keepstation:KeepStation:Dive] Running Loop=1 2025-06-10T05:37:51.198Z,1749533871.198 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-06-10T05:37:51.198Z,1749533871.198 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-06-10T05:37:51.199Z,1749533871.199 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-06-10T05:37:51.199Z,1749533871.199 [keepstation:KeepStation:B.Wait] Running Loop=1 2025-06-10T05:37:51.199Z,1749533871.199 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component. 2025-06-10T05:37:51.576Z,1749533871.576 [keepstation:KeepStation:Dive] Running Loop=1 2025-06-10T05:38:00.533Z,1749533880.533 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:38:23.088Z,1749533903.088 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-10T05:38:31.657Z,1749533911.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:39:02.781Z,1749533942.781 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:39:33.905Z,1749533973.905 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:39:41.055Z,1749533981.055 [DVL_micro](ERROR): Failed to parse: :BI,+00012,+00054,-00173,+00000,I 2025-06-10T05:39:53.753Z,1749533993.753 [Radio_Surface](INFO): Powering down 2025-06-10T05:40:05.029Z,1749534005.029 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T05:40:06.033Z,1749534006.033 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-10T05:46:30.317Z,1749534390.317 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T05:56:30.656Z,1749534990.656 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T06:06:30.995Z,1749535590.995 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T06:16:31.343Z,1749536191.343 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T06:25:08.079Z,1749536708.079 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-06-10T06:26:31.725Z,1749536791.725 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T06:33:54.600Z,1749537234.600 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-10T06:33:54.600Z,1749537234.600 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-06-10T06:33:54.603Z,1749537234.603 [BPC1](INFO): Received data from all battery sticks. 2025-06-10T06:34:06.727Z,1749537246.727 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-06-10T06:36:31.761Z,1749537391.761 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T06:37:50.959Z,1749537470.959 [keepstation:SurfaceComms] Running Loop=1 2025-06-10T06:37:50.959Z,1749537470.959 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-10T06:37:50.960Z,1749537470.960 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-10T06:37:50.960Z,1749537470.960 [keepstation:SurfaceComms:A] Stopped 2025-06-10T06:37:50.960Z,1749537470.960 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-10T06:37:50.960Z,1749537470.960 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-10T06:37:50.962Z,1749537470.962 [keepstation:NeedComms] Running Loop=1 2025-06-10T06:37:50.962Z,1749537470.962 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-10T06:37:50.962Z,1749537470.962 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-10T06:37:50.962Z,1749537470.962 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-10T06:37:50.963Z,1749537470.963 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-10T06:37:50.963Z,1749537470.963 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-10T06:37:50.963Z,1749537470.963 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-10T06:37:50.964Z,1749537470.964 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-10T06:37:50.964Z,1749537470.964 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-10T06:37:50.964Z,1749537470.964 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-10T06:37:50.965Z,1749537470.965 [keepstation:NeedComms:A] Running Loop=1 2025-06-10T06:37:50.966Z,1749537470.966 [keepstation:NeedComms:A](INFO): last time_fix was: 1749533865.000000 second since 1970/01/01T00:00:00Z 2025-06-10T06:37:50.967Z,1749537470.967 [keepstation:NeedComms:A] Stopped 2025-06-10T06:37:51.343Z,1749537471.343 [keepstation:KeepStation:Dive] Stopped 2025-06-10T06:37:51.343Z,1749537471.343 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-06-10T06:37:51.344Z,1749537471.344 [keepstation:KeepStation:Dive:A] Stopped 2025-06-10T06:37:51.344Z,1749537471.344 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-06-10T06:37:51.344Z,1749537471.344 [keepstation:DiveCmd:StationKeep] Stopped 2025-06-10T06:37:51.344Z,1749537471.344 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-06-10T06:37:51.344Z,1749537471.344 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-06-10T06:37:51.344Z,1749537471.344 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-06-10T06:37:51.344Z,1749537471.344 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-06-10T06:37:51.344Z,1749537471.344 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-06-10T06:38:48.146Z,1749537528.146 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-06-10T06:38:48.146Z,1749537528.146 [ThrusterHE](INFO): Powering down 2025-06-10T06:38:48.946Z,1749537528.946 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-06-10T06:38:49.066Z,1749537529.066 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-06-10T06:39:21.632Z,1749537561.632 [keepstation:NeedComms:C] Running Loop=1 2025-06-10T06:39:22.041Z,1749537562.041 [Radio_Surface](INFO): Powering up 2025-06-10T06:39:26.894Z,1749537566.894 [DVL_micro](ERROR): Failed to parse: :SA,-07.34,+13.65,212.8 2025-06-10T06:39:27.662Z,1749537567.662 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-10T06:39:57.781Z,1749537597.781 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T06:40:04.851Z,1749537604.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=21485, MT Status=2, MTMSN=0 2025-06-10T06:40:04.851Z,1749537604.851 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T06:40:23.456Z,1749537623.456 [NAL9602](INFO): SBD MO Status=2, MOMSN=21485, MT Status=2, MTMSN=0 2025-06-10T06:40:23.456Z,1749537623.456 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T06:40:28.914Z,1749537628.914 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T06:40:40.407Z,1749537640.407 [NAL9602](INFO): SBD MO Status=2, MOMSN=21485, MT Status=2, MTMSN=0 2025-06-10T06:40:40.407Z,1749537640.407 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T06:40:56.975Z,1749537656.975 [NAL9602](INFO): SBD MO Status=2, MOMSN=21485, MT Status=2, MTMSN=0 2025-06-10T06:40:56.975Z,1749537656.975 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T06:41:00.037Z,1749537660.037 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T06:41:08.299Z,1749537668.299 [NAL9602](INFO): SBD MO Status=0, MOMSN=21485, MT Status=0, MTMSN=0 2025-06-10T06:41:08.299Z,1749537668.299 [NAL9602](INFO): No messages in MT queue 2025-06-10T06:41:09.493Z,1749537669.493 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,064104.00,A,3643.12862,N,12154.03033,W,0.389,69.21,100625,,,A*48 2025-06-10T06:41:09.495Z,1749537669.495 [NAL9602](INFO): GPS fix at 20250610T064104: (36.718810, -121.900505) 2025-06-10T06:41:09.507Z,1749537669.507 [UniversalFixResidualReporter](INFO): Fix residual: 1.8 %DT, over the last 770.6 m. Residual distance 13.9 m at bearing -145.4 degrees. Fix at (36.7188, -121.9005) with 328.1 m made good. 2025-06-10T06:41:09.510Z,1749537669.510 [keepstation:NeedComms:C] Stopped 2025-06-10T06:41:09.511Z,1749537669.511 [keepstation:NeedComms:D] Running Loop=1 2025-06-10T06:41:31.177Z,1749537691.177 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T06:41:33.192Z,1749537693.192 [NAL9602](INFO): SBD MO Status=2, MOMSN=21486, MT Status=2, MTMSN=0 2025-06-10T06:41:33.192Z,1749537693.192 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T06:41:41.161Z,1749537701.161 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003296 2025-06-10T06:41:49.113Z,1749537709.113 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20250610T040000/Courier0012.lzma 2025-06-10T06:41:50.115Z,1749537710.115 [DataOverHttps](INFO): Moved sent file to Logs/20250610T040000/Courier0012.lzma.bak 2025-06-10T06:41:50.115Z,1749537710.115 [DataOverHttps](INFO): SBD MOMSN=25171028 2025-06-10T06:41:59.302Z,1749537719.302 [NAL9602](INFO): SBD MO Status=2, MOMSN=21486, MT Status=2, MTMSN=0 2025-06-10T06:41:59.303Z,1749537719.303 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T06:42:06.510Z,1749537726.510 [DataOverHttps](INFO): Sending 516 bytes from file Logs/20250610T040000/Express0013.lzma 2025-06-10T06:42:07.511Z,1749537727.511 [DataOverHttps](INFO): Moved sent file to Logs/20250610T040000/Express0013.lzma.bak 2025-06-10T06:42:07.511Z,1749537727.511 [DataOverHttps](INFO): SBD MOMSN=25171031 2025-06-10T06:42:09.023Z,1749537729.023 [keepstation:NeedComms:D] Stopped 2025-06-10T06:42:09.023Z,1749537729.023 [keepstation:NeedComms:E] Running Loop=1 2025-06-10T06:42:11.012Z,1749537731.012 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,064205.00,A,3643.12805,N,12154.03013,W,0.544,193.54,100625,,,A*78 2025-06-10T06:42:11.014Z,1749537731.014 [NAL9602](INFO): GPS fix at 20250610T064205: (36.718801, -121.900502) 2025-06-10T06:42:11.060Z,1749537731.060 [keepstation:NeedComms:E] Stopped 2025-06-10T06:42:11.069Z,1749537731.069 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-10T06:42:11.069Z,1749537731.069 [keepstation:NeedComms] Stopped 2025-06-10T06:42:11.069Z,1749537731.069 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-10T06:42:11.069Z,1749537731.069 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-10T06:42:11.069Z,1749537731.069 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-10T06:42:11.485Z,1749537731.485 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-10T06:42:11.485Z,1749537731.485 [keepstation:SurfaceComms:B] Stopped 2025-06-10T06:42:11.485Z,1749537731.485 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-10T06:42:11.485Z,1749537731.485 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-10T06:42:11.485Z,1749537731.485 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-10T06:42:11.486Z,1749537731.486 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-10T06:42:11.900Z,1749537731.900 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-10T06:42:11.900Z,1749537731.900 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-10T06:42:11.900Z,1749537731.900 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-10T06:42:11.900Z,1749537731.900 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-10T06:42:11.901Z,1749537731.901 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-10T06:42:11.909Z,1749537731.909 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-10T06:42:12.262Z,1749537732.262 [keepstation:SurfaceComms:setStation] Stopped 2025-06-10T06:42:12.262Z,1749537732.262 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-10T06:42:12.262Z,1749537732.262 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-10T06:42:12.262Z,1749537732.262 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-10T06:42:12.262Z,1749537732.262 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-10T06:42:12.262Z,1749537732.262 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-10T06:42:12.680Z,1749537732.680 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-10T06:42:12.680Z,1749537732.680 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-10T06:42:12.680Z,1749537732.680 [keepstation:SurfaceComms:setSink] Stopped 2025-06-10T06:42:12.680Z,1749537732.680 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-10T06:42:12.680Z,1749537732.680 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-10T06:42:12.680Z,1749537732.680 [keepstation:SurfaceComms] Stopped 2025-06-10T06:42:12.680Z,1749537732.680 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-10T06:42:13.086Z,1749537733.086 [keepstation:KeepStation:Dive] Running Loop=1 2025-06-10T06:42:13.086Z,1749537733.086 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-06-10T06:42:13.086Z,1749537733.086 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-06-10T06:42:13.086Z,1749537733.086 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-06-10T06:42:13.087Z,1749537733.087 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-10T06:42:13.087Z,1749537733.087 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-10T06:42:13.087Z,1749537733.087 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-10T06:42:13.087Z,1749537733.087 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T06:42:13.087Z,1749537733.087 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-10T06:42:13.087Z,1749537733.087 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-10T06:42:13.087Z,1749537733.087 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-10T06:42:13.087Z,1749537733.087 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-10T06:42:13.087Z,1749537733.087 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-10T06:42:13.088Z,1749537733.088 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-10T06:42:13.088Z,1749537733.088 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-10T06:42:13.088Z,1749537733.088 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-10T06:42:13.088Z,1749537733.088 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-10T06:42:44.151Z,1749537764.151 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-10T06:45:12.665Z,1749537912.665 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T06:45:38.637Z,1749537938.637 [Radio_Surface](INFO): Powering down 2025-06-10T06:45:39.892Z,1749537939.892 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0080.70,000.00 2025-06-10T06:45:43.797Z,1749537943.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T06:45:44.801Z,1749537944.801 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-10T06:46:31.995Z,1749537991.995 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T06:46:49.098Z,1749538009.098 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.819427 2025-06-10T06:46:55.043Z,1749538015.043 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-10T06:46:55.043Z,1749538015.043 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-10T06:46:55.043Z,1749538015.043 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T06:46:55.043Z,1749538015.043 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-10T06:46:55.442Z,1749538015.442 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-10T06:46:55.443Z,1749538015.443 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T06:46:55.443Z,1749538015.443 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-10T06:46:55.443Z,1749538015.443 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-10T06:46:55.443Z,1749538015.443 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T06:46:55.443Z,1749538015.443 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-10T06:46:55.865Z,1749538015.865 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-10T06:46:55.866Z,1749538015.866 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T06:46:55.866Z,1749538015.866 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-10T06:46:55.866Z,1749538015.866 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T06:46:55.866Z,1749538015.866 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T06:46:55.866Z,1749538015.866 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-10T06:46:55.867Z,1749538015.867 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T06:46:55.867Z,1749538015.867 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-10T06:46:55.867Z,1749538015.867 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T06:46:55.867Z,1749538015.867 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-10T06:46:55.867Z,1749538015.867 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-10T06:46:55.867Z,1749538015.867 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-06-10T06:46:56.264Z,1749538016.264 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-06-10T06:46:56.264Z,1749538016.264 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-06-10T06:46:56.264Z,1749538016.264 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-06-10T06:46:56.264Z,1749538016.264 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-06-10T06:46:56.264Z,1749538016.264 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-06-10T06:46:56.264Z,1749538016.264 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-06-10T06:46:56.265Z,1749538016.265 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-06-10T06:46:56.270Z,1749538016.270 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-06-10T06:56:32.016Z,1749538592.016 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T07:03:12.849Z,1749538992.849 [DVL_micro](ERROR): Failed to parse: :WI,+00080,+00687,+00034,+00000,A 2025-06-10T07:04:15.057Z,1749539055.057 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-06-10T07:06:32.421Z,1749539192.421 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T07:16:32.771Z,1749539792.771 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T07:26:33.092Z,1749540393.092 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T07:32:55.684Z,1749540775.684 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+10061.40,000.00 2025-06-10T07:32:55.684Z,1749540775.684 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+10061.40,000.00 2025-06-10T07:36:33.437Z,1749540993.437 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T07:41:27.558Z,1749541287.558 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-10T07:41:27.559Z,1749541287.559 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-06-10T07:41:27.562Z,1749541287.562 [BPC1](INFO): Received data from all battery sticks. 2025-06-10T07:42:11.194Z,1749541331.194 [keepstation:SurfaceComms] Running Loop=1 2025-06-10T07:42:11.194Z,1749541331.194 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-10T07:42:11.195Z,1749541331.195 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-10T07:42:11.195Z,1749541331.195 [keepstation:SurfaceComms:A] Stopped 2025-06-10T07:42:11.195Z,1749541331.195 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-10T07:42:11.195Z,1749541331.195 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-10T07:42:11.197Z,1749541331.197 [keepstation:NeedComms] Running Loop=1 2025-06-10T07:42:11.197Z,1749541331.197 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-10T07:42:11.197Z,1749541331.197 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-10T07:42:11.198Z,1749541331.198 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-10T07:42:11.198Z,1749541331.198 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-10T07:42:11.198Z,1749541331.198 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-10T07:42:11.199Z,1749541331.199 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-10T07:42:11.199Z,1749541331.199 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-10T07:42:11.199Z,1749541331.199 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-10T07:42:11.200Z,1749541331.200 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-10T07:42:11.200Z,1749541331.200 [keepstation:NeedComms:A] Running Loop=1 2025-06-10T07:42:11.202Z,1749541331.202 [keepstation:NeedComms:A](INFO): last time_fix was: 1749537725.000000 second since 1970/01/01T00:00:00Z 2025-06-10T07:42:11.202Z,1749541331.202 [keepstation:NeedComms:A] Stopped 2025-06-10T07:42:11.602Z,1749541331.602 [keepstation:KeepStation:Dive] Stopped 2025-06-10T07:42:11.602Z,1749541331.602 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-06-10T07:42:11.602Z,1749541331.602 [keepstation:KeepStation:Dive:A] Stopped 2025-06-10T07:42:11.602Z,1749541331.602 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-06-10T07:42:11.603Z,1749541331.603 [keepstation:DiveCmd:StationKeep] Stopped 2025-06-10T07:42:11.603Z,1749541331.603 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-06-10T07:42:11.603Z,1749541331.603 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-06-10T07:42:11.603Z,1749541331.603 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-06-10T07:42:11.603Z,1749541331.603 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-06-10T07:42:11.603Z,1749541331.603 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-06-10T07:43:23.203Z,1749541403.203 [keepstation:NeedComms:C] Running Loop=1 2025-06-10T07:43:23.749Z,1749541403.749 [Radio_Surface](INFO): Powering up 2025-06-10T07:43:29.281Z,1749541409.281 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-10T07:43:59.401Z,1749541439.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T07:44:30.526Z,1749541470.526 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T07:44:32.682Z,1749541472.682 [NAL9602](INFO): SBD MO Status=2, MOMSN=21486, MT Status=2, MTMSN=0 2025-06-10T07:44:32.682Z,1749541472.682 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T07:44:54.488Z,1749541494.488 [NAL9602](INFO): SBD MO Status=1, MOMSN=21486, MT Status=0, MTMSN=0 2025-06-10T07:44:54.488Z,1749541494.488 [NAL9602](INFO): No messages in MT queue 2025-06-10T07:44:55.695Z,1749541495.695 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,074450.00,A,3643.38579,N,12153.56091,W,0.447,196.48,100625,,,D*73 2025-06-10T07:44:55.697Z,1749541495.697 [NAL9602](INFO): GPS fix at 20250610T074450: (36.723096, -121.892682) 2025-06-10T07:44:55.709Z,1749541495.709 [UniversalFixResidualReporter](INFO): Fix residual: 34.3 %DT, over the last 1844.9 m. Residual distance 633.0 m at bearing -140.5 degrees. Fix at (36.7231, -121.8927) with 845.0 m made good. 2025-06-10T07:44:55.712Z,1749541495.712 [keepstation:NeedComms:C] Stopped 2025-06-10T07:44:55.713Z,1749541495.713 [keepstation:NeedComms:D] Running Loop=1 2025-06-10T07:45:01.649Z,1749541501.649 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T07:45:22.027Z,1749541522.027 [NAL9602](INFO): SBD MO Status=1, MOMSN=21487, MT Status=0, MTMSN=0 2025-06-10T07:45:22.077Z,1749541522.077 [NAL9602](INFO): Sent 97 bytes from file Logs/20250610T040000/Courier0015.lzma 2025-06-10T07:45:22.077Z,1749541522.077 [NAL9602](INFO): Packets left to send: 0 2025-06-10T07:45:32.388Z,1749541532.388 [NAL9602](INFO): SBD MO Status=1, MOMSN=21488, MT Status=0, MTMSN=0 2025-06-10T07:45:32.505Z,1749541532.505 [NAL9602](INFO): Sent 332 bytes from file Logs/20250610T040000/Express0016.lzma 2025-06-10T07:45:32.505Z,1749541532.505 [NAL9602](INFO): Packets left to send: 1 2025-06-10T07:45:32.781Z,1749541532.781 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T07:45:44.242Z,1749541544.242 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003308 2025-06-10T07:45:46.100Z,1749541546.100 [NAL9602](INFO): SBD MO Status=1, MOMSN=21489, MT Status=0, MTMSN=0 2025-06-10T07:45:46.157Z,1749541546.157 [NAL9602](INFO): Sent 239 bytes from file Logs/20250610T040000/Express0016.lzma 2025-06-10T07:45:46.157Z,1749541546.157 [NAL9602](INFO): Packets left to send: 0 2025-06-10T07:45:46.925Z,1749541546.925 [keepstation:NeedComms:D] Stopped 2025-06-10T07:45:46.925Z,1749541546.925 [keepstation:NeedComms:E] Running Loop=1 2025-06-10T07:45:48.924Z,1749541548.924 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,074543.00,A,3643.37879,N,12153.56384,W,1.050,185.97,100625,,,D*76 2025-06-10T07:45:48.927Z,1749541548.927 [NAL9602](INFO): GPS fix at 20250610T074543: (36.722980, -121.892731) 2025-06-10T07:45:48.974Z,1749541548.974 [keepstation:NeedComms:E] Stopped 2025-06-10T07:45:48.975Z,1749541548.975 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-10T07:45:48.975Z,1749541548.975 [keepstation:NeedComms] Stopped 2025-06-10T07:45:48.975Z,1749541548.975 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-10T07:45:48.975Z,1749541548.976 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-10T07:45:48.976Z,1749541548.976 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-10T07:45:49.386Z,1749541549.386 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-10T07:45:49.387Z,1749541549.387 [keepstation:SurfaceComms:B] Stopped 2025-06-10T07:45:49.387Z,1749541549.387 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-10T07:45:49.387Z,1749541549.387 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-10T07:45:49.387Z,1749541549.387 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-10T07:45:49.387Z,1749541549.387 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-10T07:45:49.786Z,1749541549.786 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-10T07:45:49.786Z,1749541549.786 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-10T07:45:49.786Z,1749541549.786 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-10T07:45:49.786Z,1749541549.786 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-10T07:45:49.786Z,1749541549.786 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-10T07:45:49.786Z,1749541549.786 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-10T07:45:50.172Z,1749541550.172 [keepstation:SurfaceComms:setStation] Stopped 2025-06-10T07:45:50.172Z,1749541550.172 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-10T07:45:50.172Z,1749541550.172 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-10T07:45:50.172Z,1749541550.172 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-10T07:45:50.172Z,1749541550.172 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-10T07:45:50.173Z,1749541550.173 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-10T07:45:50.598Z,1749541550.598 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-10T07:45:50.598Z,1749541550.598 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-10T07:45:50.598Z,1749541550.598 [keepstation:SurfaceComms:setSink] Stopped 2025-06-10T07:45:50.598Z,1749541550.598 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-10T07:45:50.598Z,1749541550.598 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-10T07:45:50.598Z,1749541550.598 [keepstation:SurfaceComms] Stopped 2025-06-10T07:45:50.599Z,1749541550.599 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-10T07:45:50.962Z,1749541550.962 [keepstation:KeepStation:Dive] Running Loop=1 2025-06-10T07:45:50.962Z,1749541550.962 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-06-10T07:45:50.962Z,1749541550.962 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-06-10T07:45:50.962Z,1749541550.962 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-06-10T07:45:50.963Z,1749541550.963 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-10T07:45:50.963Z,1749541550.963 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-10T07:45:50.963Z,1749541550.963 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-10T07:45:50.963Z,1749541550.963 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T07:45:50.963Z,1749541550.963 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-10T07:45:50.963Z,1749541550.963 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-10T07:45:50.963Z,1749541550.963 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-10T07:45:50.963Z,1749541550.963 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-10T07:45:50.964Z,1749541550.964 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-10T07:45:50.964Z,1749541550.964 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-10T07:45:50.964Z,1749541550.964 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-10T07:45:50.964Z,1749541550.964 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-10T07:45:50.964Z,1749541550.964 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-10T07:46:20.837Z,1749541580.837 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-10T07:46:33.775Z,1749541593.775 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T07:50:29.933Z,1749541829.933 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T07:51:01.081Z,1749541861.081 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T07:51:14.992Z,1749541874.992 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE. Altitude:3.150000m. At depth:5.030395m. 2025-06-10T07:51:16.053Z,1749541876.053 [Radio_Surface](INFO): Powering down 2025-06-10T07:51:32.218Z,1749541892.218 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T07:51:33.221Z,1749541893.221 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-10T07:52:37.477Z,1749541957.477 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.766617 2025-06-10T07:52:43.035Z,1749541963.035 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-10T07:52:43.035Z,1749541963.035 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-10T07:52:43.035Z,1749541963.035 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T07:52:43.035Z,1749541963.035 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-10T07:52:43.443Z,1749541963.443 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-10T07:52:43.443Z,1749541963.443 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T07:52:43.444Z,1749541963.444 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-10T07:52:43.444Z,1749541963.444 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-10T07:52:43.444Z,1749541963.444 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T07:52:43.444Z,1749541963.444 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-10T07:52:43.858Z,1749541963.858 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-10T07:52:43.858Z,1749541963.858 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T07:52:43.858Z,1749541963.858 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-10T07:52:43.858Z,1749541963.858 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T07:52:43.859Z,1749541963.859 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T07:52:43.859Z,1749541963.859 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-10T07:52:43.859Z,1749541963.859 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T07:52:43.859Z,1749541963.859 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-10T07:52:43.859Z,1749541963.859 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T07:52:43.859Z,1749541963.859 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-10T07:52:43.859Z,1749541963.859 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-10T07:52:43.859Z,1749541963.859 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-06-10T07:52:44.248Z,1749541964.248 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-06-10T07:52:44.249Z,1749541964.249 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-06-10T07:52:44.249Z,1749541964.249 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-06-10T07:52:44.249Z,1749541964.249 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-06-10T07:52:44.249Z,1749541964.249 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-06-10T07:52:44.249Z,1749541964.249 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-06-10T07:52:44.249Z,1749541964.249 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-06-10T07:52:44.250Z,1749541964.250 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-06-10T07:56:33.830Z,1749542193.830 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T08:02:39.986Z,1749542559.986 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-06-10T08:02:40.361Z,1749542560.361 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 31.28 mm. 2025-06-10T08:02:40.362Z,1749542560.362 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.28 mm. 2025-06-10T08:06:33.862Z,1749542793.862 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T08:12:48.789Z,1749543168.789 [DVL_micro](ERROR): Failed to parse: :WI,-00052,-00037,-00082,+00000,A 2025-06-10T08:12:50.798Z,1749543170.798 [DVL_micro](ERROR): Failed to parse::BI,-00034,-00153,-00014,+00000,I 2025-06-10T08:12:52.819Z,1749543172.819 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-06-10T08:12:54.846Z,1749543174.846 [DVL_micro](ERROR): Failed to parse::BD,+00000000.00,+00000000.00,+00000000.00,0067.40,000.00 2025-06-10T08:12:55.239Z,1749543175.239 [DVL_micro](ERROR): No DVL communication! Re-initializing 2025-06-10T08:12:55.239Z,1749543175.239 [DVL_micro] Communications Fault, FailCount= 1 2025-06-10T08:12:55.239Z,1749543175.239 [DVL_micro](ERROR): Communications Fault 2025-06-10T08:12:55.262Z,1749543175.262 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-06-10T08:12:55.737Z,1749543175.737 [DVL_micro](INFO): Powering down 2025-06-10T08:12:56.469Z,1749543176.469 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-06-10T08:12:56.469Z,1749543176.469 [DVL_micro] No Fault, FailCount= 1 2025-06-10T08:16:34.209Z,1749543394.209 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T08:16:46.425Z,1749543406.425 [BuoyancyServo](ERROR): getPosition uart error serial timeout 2025-06-10T08:16:46.426Z,1749543406.426 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout 2025-06-10T08:16:46.426Z,1749543406.426 [BuoyancyServo] Communications Fault, FailCount= 1 2025-06-10T08:16:46.426Z,1749543406.426 [BuoyancyServo](ERROR): Communications Fault 2025-06-10T08:16:46.455Z,1749543406.455 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2025-06-10T08:16:48.362Z,1749543408.362 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-06-10T08:16:48.362Z,1749543408.362 [BuoyancyServo](INFO): Powering down 2025-06-10T08:16:49.191Z,1749543409.191 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2025-06-10T08:16:49.191Z,1749543409.191 [BuoyancyServo] No Fault, FailCount= 1 2025-06-10T08:16:49.576Z,1749543409.576 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-06-10T08:16:49.593Z,1749543409.593 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-06-10T08:26:34.559Z,1749543994.559 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T08:35:52.486Z,1749544552.486 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,000000000000000120,-00089,-00075,+00000,A 2025-06-10T08:35:52.486Z,1749544552.486 [DVL_micro](ERROR): Failed to parse: :TS,000000000000000120,-00089,-00075,+00000,A 2025-06-10T08:36:34.905Z,1749544594.905 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-06-10T08:37:58.128Z,1749544678.128 [DVL_micro](ERROR): Failed to parse: :RD,+0067.45,+0065.93,+9999.99,+0065.39 2025-06-10T08:45:49.240Z,1749545149.240 [keepstation:SurfaceComms] Running Loop=1 2025-06-10T08:45:49.245Z,1749545149.245 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-10T08:45:49.246Z,1749545149.246 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-10T08:45:49.257Z,1749545149.257 [keepstation:SurfaceComms:A] Stopped 2025-06-10T08:45:49.257Z,1749545149.257 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-10T08:45:49.258Z,1749545149.258 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-10T08:45:49.260Z,1749545149.260 [keepstation:NeedComms] Running Loop=1 2025-06-10T08:45:49.261Z,1749545149.261 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-10T08:45:49.265Z,1749545149.265 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-10T08:45:49.265Z,1749545149.265 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-10T08:45:49.266Z,1749545149.266 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-10T08:45:49.267Z,1749545149.267 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-10T08:45:49.267Z,1749545149.267 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-10T08:45:49.268Z,1749545149.268 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-10T08:45:49.268Z,1749545149.268 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-10T08:45:49.273Z,1749545149.273 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-10T08:45:49.274Z,1749545149.274 [keepstation:NeedComms:A] Running Loop=1 2025-06-10T08:45:49.276Z,1749545149.276 [keepstation:NeedComms:A](INFO): last time_fix was: 1749541543.000000 second since 1970/01/01T00:00:00Z 2025-06-10T08:45:49.276Z,1749545149.276 [keepstation:NeedComms:A] Stopped 2025-06-10T08:45:49.618Z,1749545149.618 [keepstation:KeepStation:Dive] Stopped 2025-06-10T08:45:49.618Z,1749545149.618 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-06-10T08:45:49.619Z,1749545149.619 [keepstation:KeepStation:Dive:A] Stopped 2025-06-10T08:45:49.619Z,1749545149.619 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-06-10T08:45:49.619Z,1749545149.619 [keepstation:DiveCmd:StationKeep] Stopped 2025-06-10T08:45:49.619Z,1749545149.619 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-06-10T08:45:49.619Z,1749545149.619 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-06-10T08:45:49.619Z,1749545149.619 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-06-10T08:45:49.619Z,1749545149.619 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-06-10T08:45:49.619Z,1749545149.619 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-06-10T08:47:21.888Z,1749545241.888 [keepstation:NeedComms:C] Running Loop=1 2025-06-10T08:47:22.361Z,1749545242.361 [Radio_Surface](INFO): Powering up 2025-06-10T08:47:28.157Z,1749545248.157 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-10T08:47:34.811Z,1749545254.811 [NAL9602](INFO): SBD MO Status=0, MOMSN=21490, MT Status=0, MTMSN=0 2025-06-10T08:47:34.811Z,1749545254.811 [NAL9602](INFO): No messages in MT queue 2025-06-10T08:47:36.033Z,1749545256.033 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084730.00,A,3643.16839,N,12153.95217,W,1.302,304.21,100625,,,A*7B 2025-06-10T08:47:36.035Z,1749545256.035 [NAL9602](INFO): GPS fix at 20250610T084730: (36.719473, -121.899203) 2025-06-10T08:47:36.047Z,1749545256.047 [UniversalFixResidualReporter](INFO): Fix residual: 4.1 %DT, over the last 925.4 m. Residual distance 37.7 m at bearing -75.9 degrees. Fix at (36.7195, -121.8992) with 696.3 m made good. 2025-06-10T08:47:36.050Z,1749545256.050 [keepstation:NeedComms:C] Stopped 2025-06-10T08:47:36.050Z,1749545256.050 [keepstation:NeedComms:D] Running Loop=1 2025-06-10T08:47:56.293Z,1749545276.293 [NAL9602](INFO): SBD MO Status=1, MOMSN=21491, MT Status=0, MTMSN=0 2025-06-10T08:47:56.345Z,1749545276.345 [NAL9602](INFO): Sent 85 bytes from file Logs/20250610T040000/Courier0018.lzma 2025-06-10T08:47:56.345Z,1749545276.345 [NAL9602](INFO): Packets left to send: 0 2025-06-10T08:47:58.286Z,1749545278.286 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T08:48:09.555Z,1749545289.555 [NAL9602](INFO): SBD MO Status=1, MOMSN=21492, MT Status=0, MTMSN=0 2025-06-10T08:48:09.605Z,1749545289.605 [NAL9602](INFO): Sent 332 bytes from file Logs/20250610T040000/Express0019.lzma 2025-06-10T08:48:09.605Z,1749545289.605 [NAL9602](INFO): Packets left to send: 1 2025-06-10T08:48:25.747Z,1749545305.747 [NAL9602](INFO): SBD MO Status=1, MOMSN=21493, MT Status=0, MTMSN=0 2025-06-10T08:48:25.797Z,1749545305.797 [NAL9602](INFO): Sent 241 bytes from file Logs/20250610T040000/Express0019.lzma 2025-06-10T08:48:25.797Z,1749545305.797 [NAL9602](INFO): Packets left to send: 0 2025-06-10T08:48:29.422Z,1749545309.422 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T08:48:43.644Z,1749545323.644 [NAL9602](INFO): SBD MO Status=2, MOMSN=21494, MT Status=2, MTMSN=0 2025-06-10T08:48:43.644Z,1749545323.644 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T08:48:53.863Z,1749545333.863 [NAL9602](INFO): SBD MO Status=0, MOMSN=21494, MT Status=0, MTMSN=0 2025-06-10T08:48:53.948Z,1749545333.948 [keepstation:NeedComms:D] Stopped 2025-06-10T08:48:53.949Z,1749545333.949 [keepstation:NeedComms:E] Running Loop=1 2025-06-10T08:48:56.260Z,1749545336.260 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084851.00,A,3643.16723,N,12153.96897,W,0.408,271.47,100625,,,A*79 2025-06-10T08:48:56.263Z,1749545336.263 [NAL9602](INFO): GPS fix at 20250610T084851: (36.719454, -121.899483) 2025-06-10T08:48:56.276Z,1749545336.276 [keepstation:NeedComms:E] Stopped 2025-06-10T08:48:56.277Z,1749545336.277 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-10T08:48:56.277Z,1749545336.277 [keepstation:NeedComms] Stopped 2025-06-10T08:48:56.277Z,1749545336.277 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-10T08:48:56.277Z,1749545336.277 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-10T08:48:56.277Z,1749545336.277 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-10T08:48:56.704Z,1749545336.704 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-10T08:48:56.704Z,1749545336.704 [keepstation:SurfaceComms:B] Stopped 2025-06-10T08:48:56.704Z,1749545336.704 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-10T08:48:56.704Z,1749545336.704 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-10T08:48:56.704Z,1749545336.704 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-10T08:48:56.704Z,1749545336.704 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-10T08:48:57.069Z,1749545337.069 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-10T08:48:57.070Z,1749545337.070 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-10T08:48:57.070Z,1749545337.070 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-10T08:48:57.070Z,1749545337.070 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-10T08:48:57.070Z,1749545337.070 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-10T08:48:57.070Z,1749545337.070 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-10T08:48:57.461Z,1749545337.461 [keepstation:SurfaceComms:setStation] Stopped 2025-06-10T08:48:57.461Z,1749545337.461 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-10T08:48:57.462Z,1749545337.462 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-10T08:48:57.462Z,1749545337.462 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-10T08:48:57.462Z,1749545337.462 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-10T08:48:57.462Z,1749545337.462 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-10T08:48:57.874Z,1749545337.874 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-10T08:48:57.874Z,1749545337.874 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-10T08:48:57.874Z,1749545337.874 [keepstation:SurfaceComms:setSink] Stopped 2025-06-10T08:48:57.874Z,1749545337.874 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-10T08:48:57.874Z,1749545337.874 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-10T08:48:57.874Z,1749545337.874 [keepstation:SurfaceComms] Stopped 2025-06-10T08:48:57.874Z,1749545337.874 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-10T08:48:58.281Z,1749545338.281 [keepstation:KeepStation:Dive] Running Loop=1 2025-06-10T08:48:58.281Z,1749545338.281 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-06-10T08:48:58.282Z,1749545338.282 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-06-10T08:48:58.282Z,1749545338.282 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-06-10T08:48:58.282Z,1749545338.282 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-10T08:48:58.282Z,1749545338.282 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-10T08:48:58.282Z,1749545338.282 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-10T08:48:58.282Z,1749545338.282 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T08:48:58.283Z,1749545338.283 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-10T08:48:58.283Z,1749545338.283 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-10T08:48:58.283Z,1749545338.283 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-10T08:48:58.283Z,1749545338.283 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-10T08:48:58.283Z,1749545338.283 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-10T08:48:58.283Z,1749545338.283 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-10T08:48:58.284Z,1749545338.284 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-10T08:48:58.284Z,1749545338.284 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-10T08:48:58.284Z,1749545338.284 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-10T08:49:00.545Z,1749545340.545 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T08:49:05.542Z,1749545345.542 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-10T08:49:05.543Z,1749545345.543 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-06-10T08:49:05.553Z,1749545345.553 [BPC1](INFO): Received data from all battery sticks. 2025-06-10T08:49:28.958Z,1749545368.958 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-10T08:49:31.669Z,1749545371.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T08:49:44.130Z,1749545384.130 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003423 2025-06-10T08:51:04.062Z,1749545464.062 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T08:51:35.185Z,1749545495.185 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T08:51:40.993Z,1749545500.993 [Radio_Surface](INFO): Powering down 2025-06-10T08:52:06.310Z,1749545526.310 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T08:52:07.313Z,1749545527.313 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-10T08:52:44.134Z,1749545564.134 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-10T08:52:44.134Z,1749545564.134 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-10T08:52:44.135Z,1749545564.135 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T08:52:44.135Z,1749545564.135 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-10T08:52:44.543Z,1749545564.543 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-10T08:52:44.543Z,1749545564.543 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T08:52:44.543Z,1749545564.543 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-10T08:52:44.543Z,1749545564.543 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-10T08:52:44.543Z,1749545564.543 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T08:52:44.544Z,1749545564.544 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-10T08:52:44.929Z,1749545564.929 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-10T08:52:44.929Z,1749545564.929 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T08:52:44.929Z,1749545564.929 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-10T08:52:44.929Z,1749545564.929 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T08:52:44.930Z,1749545564.930 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T08:52:44.930Z,1749545564.930 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-10T08:52:44.930Z,1749545564.930 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T08:52:44.930Z,1749545564.930 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-10T08:52:44.930Z,1749545564.930 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T08:52:44.930Z,1749545564.930 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-10T08:52:44.930Z,1749545564.930 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-10T08:52:44.930Z,1749545564.930 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-06-10T08:52:45.355Z,1749545565.355 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-06-10T08:52:45.356Z,1749545565.356 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-06-10T08:52:45.356Z,1749545565.356 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-06-10T08:52:45.356Z,1749545565.356 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-06-10T08:52:45.356Z,1749545565.356 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-06-10T08:52:45.356Z,1749545565.356 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-06-10T08:52:45.356Z,1749545565.356 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-06-10T08:52:45.359Z,1749545565.359 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-06-10T08:52:45.746Z,1749545565.746 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.28 mm (1 active estimators). 2025-06-10T08:53:11.569Z,1749545591.569 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.718323 2025-06-10T08:58:02.532Z,1749545882.532 [DVL_micro](ERROR): Failed to parse: 2025-06-10T09:01:06.358Z,1749546066.358 [DVL_micro](ERROR): Failed to parse: :BI,+00037,+00859,+00085,+00000,A 2025-06-10T09:04:42.103Z,1749546282.103 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-06-10T09:16:49.306Z,1749547009.306 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-0023,-00040,+00000,A 2025-06-10T09:16:49.306Z,1749547009.306 [DVL_micro](ERROR): Failed to parse: :BI,-0023,-00040,+00000,A 2025-06-10T09:26:14.926Z,1749547574.926 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-06-10T09:26:19.372Z,1749547579.372 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.003516 CHAN A1 (24V): 0.003514 CHAN A2 (12V): -0.008857 CHAN A3 (5V): -0.013239 CHAN B0 (3.3V): -0.003320 CHAN B1 (3.15aV): -0.002364 CHAN B2 (3.15bV): -0.001681 CHAN B3 (GND): -0.002272 OPEN: 0.012200 Full Scale: +/- 1 mA 2025-06-10T09:36:36.692Z,1749548196.692 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging 2025-06-10T09:48:56.441Z,1749548936.441 [keepstation:SurfaceComms] Running Loop=1 2025-06-10T09:48:56.441Z,1749548936.441 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-10T09:48:56.442Z,1749548936.442 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-10T09:48:56.442Z,1749548936.442 [keepstation:SurfaceComms:A] Stopped 2025-06-10T09:48:56.442Z,1749548936.442 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-10T09:48:56.442Z,1749548936.442 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-10T09:48:56.444Z,1749548936.444 [keepstation:NeedComms] Running Loop=1 2025-06-10T09:48:56.444Z,1749548936.444 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-10T09:48:56.444Z,1749548936.444 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-10T09:48:56.444Z,1749548936.444 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-10T09:48:56.445Z,1749548936.445 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-10T09:48:56.445Z,1749548936.445 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-10T09:48:56.446Z,1749548936.446 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-10T09:48:56.446Z,1749548936.446 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-10T09:48:56.446Z,1749548936.446 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-10T09:48:56.447Z,1749548936.447 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-10T09:48:56.447Z,1749548936.447 [keepstation:NeedComms:A] Running Loop=1 2025-06-10T09:48:56.448Z,1749548936.448 [keepstation:NeedComms:A](INFO): last time_fix was: 1749545331.000000 second since 1970/01/01T00:00:00Z 2025-06-10T09:48:56.448Z,1749548936.448 [keepstation:NeedComms:A] Stopped 2025-06-10T09:48:56.855Z,1749548936.855 [keepstation:KeepStation:Dive] Stopped 2025-06-10T09:48:56.855Z,1749548936.855 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-06-10T09:48:56.855Z,1749548936.855 [keepstation:KeepStation:Dive:A] Stopped 2025-06-10T09:48:56.855Z,1749548936.855 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-06-10T09:48:56.855Z,1749548936.855 [keepstation:DiveCmd:StationKeep] Stopped 2025-06-10T09:48:56.856Z,1749548936.856 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-06-10T09:48:56.856Z,1749548936.856 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-06-10T09:48:56.856Z,1749548936.856 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-06-10T09:48:56.856Z,1749548936.856 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-06-10T09:48:56.856Z,1749548936.856 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-06-10T09:49:24.445Z,1749548964.445 [BuoyancyServo](ERROR): getPosition uart error serial timeout 2025-06-10T09:49:24.445Z,1749548964.445 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout 2025-06-10T09:49:24.445Z,1749548964.445 [BuoyancyServo] Communications Fault, FailCount= 1 2025-06-10T09:49:24.445Z,1749548964.445 [BuoyancyServo](ERROR): Communications Fault 2025-06-10T09:49:24.573Z,1749548964.573 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2025-06-10T09:49:26.377Z,1749548966.377 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-06-10T09:49:26.377Z,1749548966.377 [BuoyancyServo](INFO): Powering down 2025-06-10T09:49:27.260Z,1749548967.260 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2025-06-10T09:49:27.260Z,1749548967.260 [BuoyancyServo] No Fault, FailCount= 1 2025-06-10T09:49:27.584Z,1749548967.584 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-06-10T09:49:27.585Z,1749548967.585 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-06-10T09:49:58.426Z,1749548998.426 [keepstation:NeedComms:C] Running Loop=1 2025-06-10T09:49:58.854Z,1749548998.854 [Radio_Surface](INFO): Powering up 2025-06-10T09:50:04.821Z,1749549004.821 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-10T09:50:13.376Z,1749549013.376 [NAL9602](INFO): SBD MO Status=0, MOMSN=21495, MT Status=0, MTMSN=0 2025-06-10T09:50:13.376Z,1749549013.376 [NAL9602](INFO): No messages in MT queue 2025-06-10T09:50:14.575Z,1749549014.575 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,095009.00,A,3642.98797,N,12153.74876,W,0.603,0.00,100625,,,A*79 2025-06-10T09:50:14.579Z,1749549014.579 [NAL9602](INFO): GPS fix at 20250610T095009: (36.716466, -121.895813) 2025-06-10T09:50:14.631Z,1749549014.631 [UniversalFixResidualReporter](INFO): Fix residual: 20.7 %DT, over the last 1091.1 m. Residual distance 226.3 m at bearing -18.4 degrees. Fix at (36.7165, -121.8958) with 466.2 m made good. 2025-06-10T09:50:14.647Z,1749549014.647 [keepstation:NeedComms:C] Stopped 2025-06-10T09:50:14.647Z,1749549014.647 [keepstation:NeedComms:D] Running Loop=1 2025-06-10T09:50:32.944Z,1749549032.944 [NAL9602](INFO): SBD MO Status=1, MOMSN=21496, MT Status=0, MTMSN=0 2025-06-10T09:50:32.993Z,1749549032.993 [NAL9602](INFO): Sent 98 bytes from file Logs/20250610T040000/Courier0021.lzma 2025-06-10T09:50:32.993Z,1749549032.993 [NAL9602](INFO): Packets left to send: 0 2025-06-10T09:50:45.400Z,1749549045.400 [NAL9602](INFO): SBD MO Status=1, MOMSN=21497, MT Status=0, MTMSN=0 2025-06-10T09:50:45.449Z,1749549045.449 [NAL9602](INFO): Sent 332 bytes from file Logs/20250610T040000/Express0022.lzma 2025-06-10T09:50:45.449Z,1749549045.449 [NAL9602](INFO): Packets left to send: 2 2025-06-10T09:51:03.195Z,1749549063.195 [NAL9602](INFO): SBD MO Status=2, MOMSN=21498, MT Status=2, MTMSN=0 2025-06-10T09:51:03.195Z,1749549063.195 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T09:51:25.383Z,1749549085.383 [NAL9602](INFO): SBD MO Status=1, MOMSN=21498, MT Status=0, MTMSN=0 2025-06-10T09:51:25.437Z,1749549085.437 [NAL9602](INFO): Sent 332 bytes from file Logs/20250610T040000/Express0022.lzma 2025-06-10T09:51:25.437Z,1749549085.437 [NAL9602](INFO): Packets left to send: 1 2025-06-10T09:51:41.974Z,1749549101.974 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-06-10T09:51:42.392Z,1749549102.392 [NAL9602](INFO): SBD MO Status=2, MOMSN=21499, MT Status=2, MTMSN=0 2025-06-10T09:51:42.392Z,1749549102.392 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T09:51:59.314Z,1749549119.314 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003331 2025-06-10T09:52:07.171Z,1749549127.171 [DataOverHttps](INFO): Sending 759 bytes from file Logs/20250610T040000/Express0022.lzma 2025-06-10T09:52:07.982Z,1749549127.982 [NAL9602](INFO): SBD MO Status=2, MOMSN=21499, MT Status=2, MTMSN=0 2025-06-10T09:52:07.982Z,1749549127.982 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T09:52:08.171Z,1749549128.171 [DataOverHttps](INFO): Moved sent file to Logs/20250610T040000/Express0022.lzma.bak 2025-06-10T09:52:08.171Z,1749549128.171 [DataOverHttps](INFO): SBD MOMSN=25173303 2025-06-10T09:52:10.455Z,1749549130.455 [keepstation:NeedComms:D] Stopped 2025-06-10T09:52:10.456Z,1749549130.456 [keepstation:NeedComms:E] Running Loop=1 2025-06-10T09:52:12.452Z,1749549132.452 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,095207.00,A,3642.99013,N,12153.75500,W,1.127,346.39,100625,,,A*79 2025-06-10T09:52:12.472Z,1749549132.472 [NAL9602](INFO): GPS fix at 20250610T095207: (36.716502, -121.895917) 2025-06-10T09:52:12.577Z,1749549132.577 [keepstation:NeedComms:E] Stopped 2025-06-10T09:52:12.578Z,1749549132.578 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-10T09:52:12.578Z,1749549132.578 [keepstation:NeedComms] Stopped 2025-06-10T09:52:12.579Z,1749549132.579 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-10T09:52:12.579Z,1749549132.579 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-10T09:52:12.579Z,1749549132.579 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-10T09:52:12.906Z,1749549132.906 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-10T09:52:12.906Z,1749549132.906 [keepstation:SurfaceComms:B] Stopped 2025-06-10T09:52:12.906Z,1749549132.906 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-10T09:52:12.907Z,1749549132.907 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-10T09:52:12.907Z,1749549132.907 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-10T09:52:12.907Z,1749549132.907 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-10T09:52:13.263Z,1749549133.263 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-10T09:52:13.263Z,1749549133.263 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-10T09:52:13.263Z,1749549133.263 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-10T09:52:13.263Z,1749549133.263 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-10T09:52:13.264Z,1749549133.264 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-10T09:52:13.264Z,1749549133.264 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-10T09:52:13.742Z,1749549133.742 [keepstation:SurfaceComms:setStation] Stopped 2025-06-10T09:52:13.742Z,1749549133.742 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-10T09:52:13.742Z,1749549133.742 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-10T09:52:13.742Z,1749549133.742 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-10T09:52:13.742Z,1749549133.742 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-10T09:52:13.742Z,1749549133.742 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-10T09:52:14.076Z,1749549134.076 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-10T09:52:14.076Z,1749549134.076 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-10T09:52:14.076Z,1749549134.076 [keepstation:SurfaceComms:setSink] Stopped 2025-06-10T09:52:14.076Z,1749549134.076 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-10T09:52:14.076Z,1749549134.076 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-10T09:52:14.076Z,1749549134.076 [keepstation:SurfaceComms] Stopped 2025-06-10T09:52:14.076Z,1749549134.076 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-10T09:52:14.498Z,1749549134.498 [keepstation:KeepStation:Dive] Running Loop=1 2025-06-10T09:52:14.498Z,1749549134.498 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-06-10T09:52:14.498Z,1749549134.498 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-06-10T09:52:14.499Z,1749549134.499 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-06-10T09:52:14.499Z,1749549134.499 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-10T09:52:14.499Z,1749549134.499 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-10T09:52:14.499Z,1749549134.499 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-10T09:52:14.499Z,1749549134.499 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T09:52:14.499Z,1749549134.499 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-10T09:52:14.500Z,1749549134.500 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-10T09:52:14.500Z,1749549134.500 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-10T09:52:14.500Z,1749549134.500 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-10T09:52:14.500Z,1749549134.500 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-10T09:52:14.500Z,1749549134.500 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-10T09:52:14.500Z,1749549134.500 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-10T09:52:14.533Z,1749549134.533 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-10T09:52:14.533Z,1749549134.533 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-10T09:52:45.144Z,1749549165.144 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-10T09:54:22.129Z,1749549262.129 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T09:54:49.389Z,1749549289.389 [Radio_Surface](INFO): Powering down 2025-06-10T09:54:53.253Z,1749549293.253 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T09:54:54.257Z,1749549294.257 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-10T09:55:40.528Z,1749549340.528 [DVL_micro](ERROR): only read 2 of 4 data items for beam range. Device response is::RD,+9999.99,+0080.77,+ 2025-06-10T09:55:40.529Z,1749549340.529 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+0080.77,+ 2025-06-10T09:55:56.307Z,1749549356.307 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-10T09:55:56.307Z,1749549356.307 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-10T09:55:56.307Z,1749549356.307 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T09:55:56.307Z,1749549356.307 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-10T09:55:56.698Z,1749549356.698 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-10T09:55:56.698Z,1749549356.698 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T09:55:56.698Z,1749549356.698 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-10T09:55:56.699Z,1749549356.699 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-10T09:55:56.699Z,1749549356.699 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T09:55:56.699Z,1749549356.699 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-10T09:55:57.095Z,1749549357.095 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-10T09:55:57.096Z,1749549357.096 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T09:55:57.096Z,1749549357.096 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-10T09:55:57.096Z,1749549357.096 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T09:55:57.096Z,1749549357.096 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T09:55:57.096Z,1749549357.096 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-10T09:55:57.097Z,1749549357.097 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T09:55:57.102Z,1749549357.102 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-10T09:55:57.102Z,1749549357.102 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T09:55:57.102Z,1749549357.102 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-10T09:55:57.102Z,1749549357.102 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-10T09:55:57.102Z,1749549357.102 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-06-10T09:55:57.463Z,1749549357.463 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-06-10T09:55:57.463Z,1749549357.463 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-06-10T09:55:57.463Z,1749549357.463 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-06-10T09:55:57.463Z,1749549357.463 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-06-10T09:55:57.463Z,1749549357.463 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-06-10T09:55:57.463Z,1749549357.463 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-06-10T09:55:57.463Z,1749549357.463 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-06-10T09:55:57.464Z,1749549357.464 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-06-10T09:56:29.647Z,1749549389.647 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.740555 2025-06-10T09:56:43.910Z,1749549403.910 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-10T09:56:43.911Z,1749549403.911 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-06-10T09:56:43.914Z,1749549403.914 [BPC1](INFO): Received data from all battery sticks. 2025-06-10T10:07:21.475Z,1749550041.475 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-06-10T10:07:22.337Z,1749550042.337 [DVL_micro](ERROR): DVL uart error: serial timeout 2025-06-10T10:07:22.337Z,1749550042.337 [DVL_micro] Communications Fault, FailCount= 1 2025-06-10T10:07:22.337Z,1749550042.337 [DVL_micro](ERROR): Communications Fault 2025-06-10T10:07:22.338Z,1749550042.338 [DVL_micro](ERROR): Failed to parse: 2025-06-10T10:07:22.525Z,1749550042.525 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-06-10T10:07:22.761Z,1749550042.761 [DVL_micro](INFO): Powering down 2025-06-10T10:07:23.685Z,1749550043.685 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-06-10T10:07:23.685Z,1749550043.685 [DVL_micro] No Fault, FailCount= 1 2025-06-10T10:10:59.626Z,1749550259.626 [DVL_micro](ERROR): only read 0 of 4 data items for bottom velocity. Device response is::BI,,+0068.77 2025-06-10T10:10:59.626Z,1749550259.626 [DVL_micro](ERROR): Failed to parse: :BI,,+0068.77 2025-06-10T10:15:19.421Z,1749550519.421 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-06-10T10:16:43.152Z,1749550603.152 [DVL_micro](ERROR): Failed to parse: :SA,-01.45,+05.79,143.4 2025-06-10T10:21:22.713Z,1749550882.713 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+09.7,0+00611,+00088,+00000,I 2025-06-10T10:21:22.713Z,1749550882.713 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+09.7,0+00611,+00088,+00000,I 2025-06-10T10:23:33.641Z,1749551013.641 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-06-10T10:52:12.649Z,1749552732.649 [keepstation:SurfaceComms] Running Loop=1 2025-06-10T10:52:12.649Z,1749552732.649 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-10T10:52:12.651Z,1749552732.651 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-10T10:52:12.651Z,1749552732.651 [keepstation:SurfaceComms:A] Stopped 2025-06-10T10:52:12.651Z,1749552732.651 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-10T10:52:12.651Z,1749552732.651 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-10T10:52:12.656Z,1749552732.656 [keepstation:NeedComms] Running Loop=1 2025-06-10T10:52:12.656Z,1749552732.656 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-10T10:52:12.656Z,1749552732.656 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-10T10:52:12.656Z,1749552732.656 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-10T10:52:12.656Z,1749552732.656 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-10T10:52:12.657Z,1749552732.657 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-10T10:52:12.657Z,1749552732.657 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-10T10:52:12.658Z,1749552732.658 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-10T10:52:12.658Z,1749552732.658 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-10T10:52:12.658Z,1749552732.658 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-10T10:52:12.659Z,1749552732.659 [keepstation:NeedComms:A] Running Loop=1 2025-06-10T10:52:12.660Z,1749552732.660 [keepstation:NeedComms:A](INFO): last time_fix was: 1749549127.000000 second since 1970/01/01T00:00:00Z 2025-06-10T10:52:12.660Z,1749552732.660 [keepstation:NeedComms:A] Stopped 2025-06-10T10:52:13.051Z,1749552733.051 [keepstation:KeepStation:Dive] Stopped 2025-06-10T10:52:13.052Z,1749552733.052 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-06-10T10:52:13.052Z,1749552733.052 [keepstation:KeepStation:Dive:A] Stopped 2025-06-10T10:52:13.052Z,1749552733.052 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-06-10T10:52:13.052Z,1749552733.052 [keepstation:DiveCmd:StationKeep] Stopped 2025-06-10T10:52:13.052Z,1749552733.052 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-06-10T10:52:13.052Z,1749552733.052 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-06-10T10:52:13.052Z,1749552733.052 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-06-10T10:52:13.053Z,1749552733.053 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-06-10T10:52:13.053Z,1749552733.053 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-06-10T10:53:28.854Z,1749552808.854 [DVL_micro](ERROR): Failed to parse: :SA,+22.16,-03.30,004.2 2025-06-10T10:53:45.084Z,1749552825.084 [keepstation:NeedComms:C] Running Loop=1 2025-06-10T10:53:45.805Z,1749552825.805 [Radio_Surface](INFO): Powering up 2025-06-10T10:53:51.801Z,1749552831.801 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-10T10:54:06.472Z,1749552846.472 [NAL9602](INFO): SBD MO Status=2, MOMSN=21499, MT Status=2, MTMSN=0 2025-06-10T10:54:06.472Z,1749552846.472 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T10:54:21.921Z,1749552861.921 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T10:54:31.922Z,1749552871.922 [NAL9602](INFO): SBD MO Status=2, MOMSN=21499, MT Status=2, MTMSN=0 2025-06-10T10:54:31.923Z,1749552871.923 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T10:54:53.053Z,1749552893.053 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T10:55:24.181Z,1749552924.181 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T10:55:49.899Z,1749552949.899 [NAL9602](INFO): SBD MO Status=2, MOMSN=21499, MT Status=2, MTMSN=0 2025-06-10T10:55:49.899Z,1749552949.899 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T10:55:55.305Z,1749552955.305 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T10:56:21.815Z,1749552981.815 [NAL9602](INFO): SBD MO Status=2, MOMSN=21499, MT Status=2, MTMSN=0 2025-06-10T10:56:21.815Z,1749552981.815 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T10:56:26.433Z,1749552986.433 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T10:56:47.670Z,1749553007.670 [NAL9602](INFO): SBD MO Status=2, MOMSN=21499, MT Status=2, MTMSN=0 2025-06-10T10:56:47.670Z,1749553007.670 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T10:56:57.557Z,1749553017.557 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T10:57:05.045Z,1749553025.045 [NAL9602](INFO): SBD MO Status=2, MOMSN=21499, MT Status=2, MTMSN=0 2025-06-10T10:57:05.045Z,1749553025.045 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T10:57:22.428Z,1749553042.428 [NAL9602](INFO): SBD MO Status=2, MOMSN=21499, MT Status=2, MTMSN=0 2025-06-10T10:57:22.428Z,1749553042.428 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T10:57:28.685Z,1749553048.685 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T10:57:43.047Z,1749553063.047 [NAL9602](INFO): SBD MO Status=1, MOMSN=21499, MT Status=0, MTMSN=0 2025-06-10T10:57:43.047Z,1749553063.047 [NAL9602](INFO): No messages in MT queue 2025-06-10T10:57:44.233Z,1749553064.233 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,105739.00,A,3643.18778,N,12153.86918,W,1.186,358.22,100625,,,A*7C 2025-06-10T10:57:44.240Z,1749553064.240 [NAL9602](INFO): GPS fix at 20250610T105739: (36.719796, -121.897820) 2025-06-10T10:57:44.253Z,1749553064.253 [UniversalFixResidualReporter](INFO): Fix residual: 6.4 %DT, over the last 1506.0 m. Residual distance 95.7 m at bearing 74.2 degrees. Fix at (36.7198, -121.8978) with 403.7 m made good. 2025-06-10T10:57:44.256Z,1749553064.256 [keepstation:NeedComms:C] Stopped 2025-06-10T10:57:44.257Z,1749553064.257 [keepstation:NeedComms:D] Running Loop=1 2025-06-10T10:57:59.810Z,1749553079.810 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T10:58:08.204Z,1749553088.204 [NAL9602](INFO): SBD MO Status=2, MOMSN=21500, MT Status=2, MTMSN=0 2025-06-10T10:58:08.216Z,1749553088.216 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T10:58:27.690Z,1749553107.690 [NAL9602](INFO): SBD MO Status=1, MOMSN=21500, MT Status=0, MTMSN=0 2025-06-10T10:58:27.741Z,1749553107.741 [NAL9602](INFO): Sent 99 bytes from file Logs/20250610T040000/Courier0024.lzma 2025-06-10T10:58:27.741Z,1749553107.741 [NAL9602](INFO): Packets left to send: 0 2025-06-10T10:58:30.945Z,1749553110.945 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T10:58:33.308Z,1749553113.308 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+0000.68 2025-06-10T10:58:37.736Z,1749553117.736 [DVL_micro](ERROR): Failed to parse: :WI,+00000,+00000,+00000,+00000,I 2025-06-10T10:58:38.942Z,1749553118.942 [DVL_micro](ERROR): Failed to parse: :SA,-03.11,+04.66,295.9 2025-06-10T10:58:42.988Z,1749553122.988 [NAL9602](INFO): SBD MO Status=2, MOMSN=21501, MT Status=2, MTMSN=0 2025-06-10T10:58:42.988Z,1749553122.988 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-10T10:59:02.093Z,1749553142.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T10:59:14.360Z,1749553154.360 [NAL9602](INFO): SBD MO Status=1, MOMSN=21501, MT Status=0, MTMSN=0 2025-06-10T10:59:14.417Z,1749553154.417 [NAL9602](INFO): Sent 332 bytes from file Logs/20250610T040000/Express0025.lzma 2025-06-10T10:59:14.417Z,1749553154.417 [NAL9602](INFO): Packets left to send: 2 2025-06-10T10:59:31.256Z,1749553171.256 [NAL9602](INFO): SBD MO Status=1, MOMSN=21502, MT Status=0, MTMSN=0 2025-06-10T10:59:31.309Z,1749553171.309 [NAL9602](INFO): Sent 332 bytes from file Logs/20250610T040000/Express0025.lzma 2025-06-10T10:59:31.309Z,1749553171.309 [NAL9602](INFO): Packets left to send: 1 2025-06-10T10:59:33.213Z,1749553173.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T10:59:42.574Z,1749553182.574 [NAL9602](INFO): SBD MO Status=1, MOMSN=21503, MT Status=0, MTMSN=0 2025-06-10T10:59:42.621Z,1749553182.621 [NAL9602](INFO): Sent 6 bytes from file Logs/20250610T040000/Express0025.lzma 2025-06-10T10:59:42.621Z,1749553182.621 [NAL9602](INFO): Packets left to send: 0 2025-06-10T10:59:51.877Z,1749553191.877 [NAL9602](INFO): SBD MO Status=0, MOMSN=21504, MT Status=0, MTMSN=0 2025-06-10T10:59:51.963Z,1749553191.963 [keepstation:NeedComms:D] Stopped 2025-06-10T10:59:51.964Z,1749553191.964 [keepstation:NeedComms:E] Running Loop=1 2025-06-10T10:59:54.313Z,1749553194.313 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,105949.00,A,3643.19822,N,12153.86949,W,0.117,44.89,100625,,,A*46 2025-06-10T10:59:54.315Z,1749553194.315 [NAL9602](INFO): GPS fix at 20250610T105949: (36.719970, -121.897825) 2025-06-10T10:59:54.329Z,1749553194.329 [keepstation:NeedComms:E] Stopped 2025-06-10T10:59:54.330Z,1749553194.330 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-10T10:59:54.330Z,1749553194.330 [keepstation:NeedComms] Stopped 2025-06-10T10:59:54.330Z,1749553194.330 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-10T10:59:54.330Z,1749553194.330 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-10T10:59:54.330Z,1749553194.330 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-10T10:59:54.750Z,1749553194.750 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-10T10:59:54.750Z,1749553194.750 [keepstation:SurfaceComms:B] Stopped 2025-06-10T10:59:54.751Z,1749553194.751 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-10T10:59:54.751Z,1749553194.751 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-10T10:59:54.751Z,1749553194.751 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-10T10:59:54.751Z,1749553194.751 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-10T10:59:55.110Z,1749553195.110 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-10T10:59:55.110Z,1749553195.110 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-10T10:59:55.110Z,1749553195.110 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-10T10:59:55.110Z,1749553195.110 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-10T10:59:55.110Z,1749553195.110 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-10T10:59:55.110Z,1749553195.110 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-10T10:59:55.520Z,1749553195.520 [keepstation:SurfaceComms:setStation] Stopped 2025-06-10T10:59:55.520Z,1749553195.520 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-10T10:59:55.520Z,1749553195.520 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-10T10:59:55.520Z,1749553195.520 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-10T10:59:55.520Z,1749553195.520 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-10T10:59:55.520Z,1749553195.520 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-10T10:59:55.941Z,1749553195.941 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-10T10:59:55.941Z,1749553195.941 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-10T10:59:55.941Z,1749553195.941 [keepstation:SurfaceComms:setSink] Stopped 2025-06-10T10:59:55.941Z,1749553195.941 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-10T10:59:55.941Z,1749553195.941 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-10T10:59:55.941Z,1749553195.941 [keepstation:SurfaceComms] Stopped 2025-06-10T10:59:55.942Z,1749553195.942 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-10T10:59:56.331Z,1749553196.331 [keepstation:KeepStation:Dive] Running Loop=1 2025-06-10T10:59:56.332Z,1749553196.332 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-06-10T10:59:56.332Z,1749553196.332 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-06-10T10:59:56.332Z,1749553196.332 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-06-10T10:59:56.332Z,1749553196.332 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-10T10:59:56.332Z,1749553196.332 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-10T10:59:56.333Z,1749553196.333 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-10T10:59:56.349Z,1749553196.349 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T10:59:56.349Z,1749553196.349 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-10T10:59:56.349Z,1749553196.349 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-10T10:59:56.349Z,1749553196.349 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-10T10:59:56.349Z,1749553196.349 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-10T10:59:56.349Z,1749553196.349 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-10T10:59:56.350Z,1749553196.350 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-10T10:59:56.350Z,1749553196.350 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-10T10:59:56.350Z,1749553196.350 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-10T10:59:56.350Z,1749553196.350 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-10T11:00:04.349Z,1749553204.349 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T11:00:27.018Z,1749553227.018 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-10T11:00:35.477Z,1749553235.477 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T11:00:58.127Z,1749553258.127 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00 2025-06-10T11:01:06.617Z,1749553266.617 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T11:01:37.757Z,1749553297.757 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T11:02:08.881Z,1749553328.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T11:02:40.017Z,1749553360.017 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T11:03:04.161Z,1749553384.161 [Radio_Surface](INFO): Powering down 2025-06-10T11:03:11.147Z,1749553391.147 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T11:03:12.149Z,1749553392.149 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-10T11:04:14.897Z,1749553454.897 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-10T11:04:14.897Z,1749553454.897 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-10T11:04:14.898Z,1749553454.898 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T11:04:14.898Z,1749553454.898 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-10T11:04:15.287Z,1749553455.287 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-10T11:04:15.287Z,1749553455.287 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T11:04:15.287Z,1749553455.287 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-10T11:04:15.287Z,1749553455.287 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-10T11:04:15.288Z,1749553455.288 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T11:04:15.288Z,1749553455.288 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-10T11:04:15.705Z,1749553455.705 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-10T11:04:15.705Z,1749553455.705 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T11:04:15.705Z,1749553455.705 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-10T11:04:15.706Z,1749553455.706 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T11:04:15.706Z,1749553455.706 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T11:04:15.706Z,1749553455.706 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-10T11:04:15.706Z,1749553455.706 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T11:04:15.706Z,1749553455.706 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-10T11:04:15.706Z,1749553455.706 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T11:04:15.707Z,1749553455.707 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-10T11:04:15.707Z,1749553455.707 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-10T11:04:15.707Z,1749553455.707 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-06-10T11:04:16.154Z,1749553456.154 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-06-10T11:04:16.155Z,1749553456.155 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-06-10T11:04:16.155Z,1749553456.155 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-06-10T11:04:16.155Z,1749553456.155 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-06-10T11:04:16.155Z,1749553456.155 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-06-10T11:04:16.155Z,1749553456.155 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-06-10T11:04:16.155Z,1749553456.155 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-06-10T11:04:22.560Z,1749553462.560 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-10T11:04:22.561Z,1749553462.561 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-06-10T11:04:22.564Z,1749553462.564 [BPC1](INFO): Received data from all battery sticks. 2025-06-10T11:54:23.078Z,1749556463.078 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-06-10T11:59:54.371Z,1749556794.371 [keepstation:SurfaceComms] Running Loop=1 2025-06-10T11:59:54.371Z,1749556794.371 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-10T11:59:54.371Z,1749556794.371 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-10T11:59:54.371Z,1749556794.371 [keepstation:SurfaceComms:A] Stopped 2025-06-10T11:59:54.371Z,1749556794.371 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-10T11:59:54.372Z,1749556794.372 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-10T11:59:54.374Z,1749556794.374 [keepstation:NeedComms] Running Loop=1 2025-06-10T11:59:54.374Z,1749556794.374 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-10T11:59:54.374Z,1749556794.374 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-10T11:59:54.374Z,1749556794.374 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-10T11:59:54.374Z,1749556794.374 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-10T11:59:54.375Z,1749556794.375 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-10T11:59:54.375Z,1749556794.375 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-10T11:59:54.375Z,1749556794.375 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-10T11:59:54.376Z,1749556794.376 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-10T11:59:54.376Z,1749556794.376 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-10T11:59:54.376Z,1749556794.376 [keepstation:NeedComms:A] Running Loop=1 2025-06-10T11:59:54.382Z,1749556794.382 [keepstation:NeedComms:A](INFO): last time_fix was: 1749553189.000000 second since 1970/01/01T00:00:00Z 2025-06-10T11:59:54.383Z,1749556794.383 [keepstation:NeedComms:A] Stopped 2025-06-10T11:59:54.759Z,1749556794.759 [keepstation:KeepStation:Dive] Stopped 2025-06-10T11:59:54.759Z,1749556794.759 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-06-10T11:59:54.760Z,1749556794.760 [keepstation:KeepStation:Dive:A] Stopped 2025-06-10T11:59:54.760Z,1749556794.760 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-06-10T11:59:54.760Z,1749556794.760 [keepstation:DiveCmd:StationKeep] Stopped 2025-06-10T11:59:54.760Z,1749556794.760 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-06-10T11:59:54.760Z,1749556794.760 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-06-10T11:59:54.760Z,1749556794.760 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-06-10T11:59:54.760Z,1749556794.760 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-06-10T11:59:54.760Z,1749556794.760 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-06-10T12:01:02.824Z,1749556862.824 [keepstation:NeedComms:C] Running Loop=1 2025-06-10T12:01:03.549Z,1749556863.549 [Radio_Surface](INFO): Powering up 2025-06-10T12:01:09.641Z,1749556869.641 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-10T12:01:21.927Z,1749556881.927 [NAL9602](INFO): SBD MO Status=0, MOMSN=21505, MT Status=0, MTMSN=0 2025-06-10T12:01:21.927Z,1749556881.927 [NAL9602](INFO): No messages in MT queue 2025-06-10T12:01:23.160Z,1749556883.160 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,120117.00,A,3643.20239,N,12153.83649,W,0.914,108.16,100625,,,A*76 2025-06-10T12:01:23.163Z,1749556883.163 [NAL9602](INFO): GPS fix at 20250610T120117: (36.720040, -121.897275) 2025-06-10T12:01:23.185Z,1749556883.185 [UniversalFixResidualReporter](INFO): Fix residual: 20.1 %DT, over the last 801.9 m. Residual distance 161.1 m at bearing -165.8 degrees. Fix at (36.7200, -121.8973) with 49.6 m made good. 2025-06-10T12:01:23.190Z,1749556883.190 [keepstation:NeedComms:C] Stopped 2025-06-10T12:01:23.190Z,1749556883.190 [keepstation:NeedComms:D] Running Loop=1 2025-06-10T12:01:38.271Z,1749556898.271 [NAL9602](INFO): SBD MO Status=1, MOMSN=21506, MT Status=0, MTMSN=0 2025-06-10T12:01:38.321Z,1749556898.321 [NAL9602](INFO): Sent 99 bytes from file Logs/20250610T040000/Courier0027.lzma 2025-06-10T12:01:38.321Z,1749556898.321 [NAL9602](INFO): Packets left to send: 0 2025-06-10T12:01:39.761Z,1749556899.761 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T12:01:59.268Z,1749556919.268 [NAL9602](INFO): SBD MO Status=1, MOMSN=21507, MT Status=0, MTMSN=0 2025-06-10T12:01:59.325Z,1749556919.325 [NAL9602](INFO): Sent 332 bytes from file Logs/20250610T040000/Express0028.lzma 2025-06-10T12:01:59.325Z,1749556919.325 [NAL9602](INFO): Packets left to send: 1 2025-06-10T12:02:10.885Z,1749556930.885 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T12:02:12.130Z,1749556932.130 [NAL9602](INFO): SBD MO Status=1, MOMSN=21508, MT Status=0, MTMSN=0 2025-06-10T12:02:12.187Z,1749556932.187 [NAL9602](INFO): Sent 144 bytes from file Logs/20250610T040000/Express0028.lzma 2025-06-10T12:02:12.188Z,1749556932.188 [NAL9602](INFO): Packets left to send: 0 2025-06-10T12:02:22.746Z,1749556942.746 [NAL9602](INFO): SBD MO Status=0, MOMSN=21509, MT Status=0, MTMSN=0 2025-06-10T12:02:22.823Z,1749556942.823 [keepstation:NeedComms:D] Stopped 2025-06-10T12:02:22.824Z,1749556942.824 [keepstation:NeedComms:E] Running Loop=1 2025-06-10T12:02:25.178Z,1749556945.178 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,120219.00,A,3643.20601,N,12153.83100,W,0.952,59.11,100625,,,A*4E 2025-06-10T12:02:25.180Z,1749556945.180 [NAL9602](INFO): GPS fix at 20250610T120219: (36.720100, -121.897183) 2025-06-10T12:02:25.214Z,1749556945.214 [keepstation:NeedComms:E] Stopped 2025-06-10T12:02:25.215Z,1749556945.215 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-10T12:02:25.215Z,1749556945.215 [keepstation:NeedComms] Stopped 2025-06-10T12:02:25.215Z,1749556945.215 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-10T12:02:25.215Z,1749556945.215 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-10T12:02:25.215Z,1749556945.215 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-10T12:02:25.602Z,1749556945.602 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-10T12:02:25.602Z,1749556945.602 [keepstation:SurfaceComms:B] Stopped 2025-06-10T12:02:25.603Z,1749556945.603 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-10T12:02:25.603Z,1749556945.603 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-10T12:02:25.603Z,1749556945.603 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-10T12:02:25.603Z,1749556945.603 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-10T12:02:25.992Z,1749556945.992 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-10T12:02:25.992Z,1749556945.992 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-10T12:02:25.992Z,1749556945.992 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-10T12:02:25.992Z,1749556945.992 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-10T12:02:25.992Z,1749556945.992 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-10T12:02:25.993Z,1749556945.993 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-10T12:02:26.413Z,1749556946.413 [keepstation:SurfaceComms:setStation] Stopped 2025-06-10T12:02:26.413Z,1749556946.413 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-10T12:02:26.413Z,1749556946.413 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-10T12:02:26.413Z,1749556946.413 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-10T12:02:26.414Z,1749556946.414 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-10T12:02:26.414Z,1749556946.414 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-10T12:02:26.792Z,1749556946.792 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-10T12:02:26.794Z,1749556946.794 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-10T12:02:26.794Z,1749556946.794 [keepstation:SurfaceComms:setSink] Stopped 2025-06-10T12:02:26.794Z,1749556946.794 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-10T12:02:26.794Z,1749556946.794 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-10T12:02:26.794Z,1749556946.794 [keepstation:SurfaceComms] Stopped 2025-06-10T12:02:26.794Z,1749556946.794 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-10T12:02:27.207Z,1749556947.207 [keepstation:KeepStation:Dive] Running Loop=1 2025-06-10T12:02:27.207Z,1749556947.207 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-06-10T12:02:27.207Z,1749556947.207 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-06-10T12:02:27.207Z,1749556947.207 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-06-10T12:02:27.208Z,1749556947.208 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-10T12:02:27.208Z,1749556947.208 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-10T12:02:27.208Z,1749556947.208 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-10T12:02:27.208Z,1749556947.208 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T12:02:27.208Z,1749556947.208 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-10T12:02:27.208Z,1749556947.208 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-10T12:02:27.208Z,1749556947.208 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-10T12:02:27.208Z,1749556947.208 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-10T12:02:27.208Z,1749556947.208 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-10T12:02:27.209Z,1749556947.209 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-10T12:02:27.209Z,1749556947.209 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-10T12:02:27.210Z,1749556947.210 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-10T12:02:27.210Z,1749556947.210 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-10T12:02:42.009Z,1749556962.009 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T12:02:57.082Z,1749556977.082 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-10T12:03:13.133Z,1749556993.133 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T12:03:21.983Z,1749557001.983 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002986 2025-06-10T12:04:46.693Z,1749557086.693 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-06-10T12:05:17.817Z,1749557117.817 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T12:05:36.729Z,1749557136.729 [Radio_Surface](INFO): Powering down 2025-06-10T12:05:48.957Z,1749557148.957 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-10T12:05:49.962Z,1749557149.962 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-10T12:06:34.848Z,1749557194.848 [DVL_micro](ERROR): Failed to parse: :SA,+02.91,+01.96,149.1 2025-06-10T12:06:50.202Z,1749557210.202 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-10T12:06:50.202Z,1749557210.202 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-10T12:06:50.202Z,1749557210.202 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T12:06:50.202Z,1749557210.202 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-10T12:06:50.669Z,1749557210.669 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-10T12:06:50.669Z,1749557210.669 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-10T12:06:50.669Z,1749557210.669 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-10T12:06:50.670Z,1749557210.670 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-10T12:06:50.670Z,1749557210.670 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T12:06:50.670Z,1749557210.670 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-10T12:06:51.033Z,1749557211.033 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-10T12:06:51.034Z,1749557211.034 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T12:06:51.034Z,1749557211.034 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-10T12:06:51.034Z,1749557211.034 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-10T12:06:51.035Z,1749557211.035 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T12:06:51.035Z,1749557211.035 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-10T12:06:51.035Z,1749557211.035 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-10T12:06:51.035Z,1749557211.035 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-10T12:06:51.035Z,1749557211.035 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-10T12:06:51.035Z,1749557211.035 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-10T12:06:51.035Z,1749557211.035 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-10T12:06:51.036Z,1749557211.036 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-06-10T12:06:51.435Z,1749557211.435 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-06-10T12:06:51.435Z,1749557211.435 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-06-10T12:06:51.436Z,1749557211.436 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-06-10T12:06:51.436Z,1749557211.436 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-06-10T12:06:51.436Z,1749557211.436 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-06-10T12:06:51.436Z,1749557211.436 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-06-10T12:06:51.436Z,1749557211.436 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-06-10T12:06:51.437Z,1749557211.437 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-06-10T12:06:53.214Z,1749557213.214 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.387329 2025-06-10T12:12:00.220Z,1749557520.220 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-10T12:12:00.221Z,1749557520.221 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-06-10T12:12:00.230Z,1749557520.230 [BPC1](INFO): Received data from all battery sticks.