2025-12-08T03:03:36.479Z,1765163016.479 [CommandExec](IMPORTANT): got command restart logs 2025-12-08T03:03:41.816Z,1765163021.816 [CommandExec](IMPORTANT): got command schedule resume 2025-12-08T03:04:40.497Z,1765163080.497 [DataOverHttps](IMPORTANT): SBD MTMSN=20251208T030438 2025-12-08T03:04:47.912Z,1765163087.912 [DataOverHttps](INFO): Received command: sched 20251208T1430 "configSet CBIT.navErrorThreshold 100 percent persist;restart logs" 2025-12-08T03:04:48.011Z,1765163088.011 [CommandExec](IMPORTANT): got command schedule 20251208T143000 "configSet CBIT.navErrorThreshold 100 percent persist;restart logs" 2025-12-08T03:04:48.012Z,1765163088.012 [CommandExec](IMPORTANT): Scheduled #1: "configSet CBIT.navErrorThreshold 100 percent persist;restart logs", AT TIME: 2025-12-08T14:30:00Z 2025-12-08T03:06:20.574Z,1765163180.574 [DataOverHttps](IMPORTANT): SBD MTMSN=20251208T030618 2025-12-08T03:06:28.122Z,1765163188.122 [DataOverHttps](INFO): Received command: sched 20251208T1430 "load Science/sci2_vtyoyo.tl;set sci2_vtyoyo.MissionTimeout 14 h;set sci2_vtyoyo.NeedCommsTime 90 min;set sci2_vtyoyo.Lat1 36.50439 degree;set sci2_vtyoyo.Lon1 -122.17209 degree;set sci2_vtyoyo.Lat2 36.58176 degree" 1jslu 1 4 2025-12-08T03:06:28.186Z,1765163188.186 [CommandExec](IMPORTANT): got command schedule 20251208T143000 "load Science/sci2_vtyoyo.tl;set sci2_vtyoyo.MissionTimeout 14 h;set sci2_vtyoyo.NeedCommsTime 90 min;set sci2_vtyoyo.Lat1 36.50439 degree;set sci2_vtyoyo.Lon1 -122.17209 degree;set sci2_vtyoyo.Lat2 36.58176 degree" 1jslu 1 4.000000 2025-12-08T03:06:28.186Z,1765163188.186 [CommandExec](IMPORTANT): Scheduling command #1 of 4 with id=1jslu 2025-12-08T03:06:28.187Z,1765163188.187 [CommandExec](IMPORTANT): Scheduled #2 (#1 of 4 with id='1jslu'): "load Science/sci2_vtyoyo.tl;set sci2_vtyoyo.MissionTimeout 14 h;set sci2_vtyoyo.NeedCommsTime 90 min;set sci2_vtyoyo.Lat1 36.50439 degree;set sci2_vtyoyo.Lon1 -122.17209 degree;set sci2_vtyoyo.Lat2 36.58176 degree", AT TIME: 2025-12-08T14:30:00Z 2025-12-08T03:06:28.245Z,1765163188.245 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T03:06:29.122Z,1765163189.122 [DataOverHttps](IMPORTANT): SBD MTMSN=20251208T030627 2025-12-08T03:06:38.445Z,1765163198.445 [DataOverHttps](INFO): Received command: sched 20251208T1430 "set sci2_vtyoyo.Lon2 -122.17398 degree;set sci2_vtyoyo.Lat3 36.70806 degree;set sci2_vtyoyo.Lon3 -122.18582 degree;set sci2_vtyoyo.Lat4 36.83842 degree;set sci2_vtyoyo.Lon4 -122.17209 degree" 1jslu 2 4 2025-12-08T03:06:38.485Z,1765163198.485 [CommandExec](IMPORTANT): got command schedule 20251208T143000 "set sci2_vtyoyo.Lon2 -122.17398 degree;set sci2_vtyoyo.Lat3 36.70806 degree;set sci2_vtyoyo.Lon3 -122.18582 degree;set sci2_vtyoyo.Lat4 36.83842 degree;set sci2_vtyoyo.Lon4 -122.17209 degree" 1jslu 2 4.000000 2025-12-08T03:06:38.486Z,1765163198.486 [CommandExec](IMPORTANT): Scheduling command #2 of 4 with id=1jslu 2025-12-08T03:06:38.487Z,1765163198.487 [CommandExec](IMPORTANT): Scheduled #3 (#2 of 4 with id='1jslu'): "set sci2_vtyoyo.Lon2 -122.17398 degree;set sci2_vtyoyo.Lat3 36.70806 degree;set sci2_vtyoyo.Lon3 -122.18582 degree;set sci2_vtyoyo.Lat4 36.83842 degree;set sci2_vtyoyo.Lon4 -122.17209 degree", AT TIME: 2025-12-08T14:30:00Z 2025-12-08T03:06:39.446Z,1765163199.446 [DataOverHttps](IMPORTANT): SBD MTMSN=20251208T030637 2025-12-08T03:06:46.928Z,1765163206.928 [DataOverHttps](INFO): Received command: sched 20251208T1430 "set sci2_vtyoyo.Lat5 36.99378 degree;set sci2_vtyoyo.Lon5 -122.44675 degree;set sci2_vtyoyo.Speed .7 m/s;set sci2_vtyoyo.YoYoMinDepth 4 m;set sci2_vtyoyo.YoYoMaxDepth 100 m;set sci2_vtyoyo.MaxDepth 135 m" 1jslu 3 4 2025-12-08T03:06:46.934Z,1765163206.934 [CommandExec](IMPORTANT): got command schedule 20251208T143000 "set sci2_vtyoyo.Lat5 36.99378 degree;set sci2_vtyoyo.Lon5 -122.44675 degree;set sci2_vtyoyo.Speed .7 m/s;set sci2_vtyoyo.YoYoMinDepth 4 m;set sci2_vtyoyo.YoYoMaxDepth 100 m;set sci2_vtyoyo.MaxDepth 135 m" 1jslu 3 4.000000 2025-12-08T03:06:46.934Z,1765163206.934 [CommandExec](IMPORTANT): Scheduling command #3 of 4 with id=1jslu 2025-12-08T03:06:46.935Z,1765163206.935 [CommandExec](IMPORTANT): Scheduled #4 (#3 of 4 with id='1jslu'): "set sci2_vtyoyo.Lat5 36.99378 degree;set sci2_vtyoyo.Lon5 -122.44675 degree;set sci2_vtyoyo.Speed .7 m/s;set sci2_vtyoyo.YoYoMinDepth 4 m;set sci2_vtyoyo.YoYoMaxDepth 100 m;set sci2_vtyoyo.MaxDepth 135 m", AT TIME: 2025-12-08T14:30:00Z 2025-12-08T03:06:47.930Z,1765163207.930 [DataOverHttps](IMPORTANT): SBD MTMSN=20251208T030646 2025-12-08T03:06:55.308Z,1765163215.308 [DataOverHttps](INFO): Received command: sched 20251208T1430 "set sci2_vtyoyo.SinkOffSurface 1 bool;set sci2_vtyoyo.SinkDepthTimeout 2 min;set sci2_vtyoyo:BackseatDriver.EnableBackseat 1 bool;run" 1jslu 4 4 2025-12-08T03:06:55.389Z,1765163215.389 [CommandExec](IMPORTANT): got command schedule 20251208T143000 "set sci2_vtyoyo.SinkOffSurface 1 bool;set sci2_vtyoyo.SinkDepthTimeout 2 min;set sci2_vtyoyo:BackseatDriver.EnableBackseat 1 bool;run" 1jslu 4 4.000000 2025-12-08T03:06:55.389Z,1765163215.389 [CommandExec](IMPORTANT): Scheduling command #4 of 4 with id=1jslu 2025-12-08T03:06:55.390Z,1765163215.390 [CommandExec](IMPORTANT): Scheduled #5 (#4 of 4 with id='1jslu'): "set sci2_vtyoyo.SinkOffSurface 1 bool;set sci2_vtyoyo.SinkDepthTimeout 2 min;set sci2_vtyoyo:BackseatDriver.EnableBackseat 1 bool;run", AT TIME: 2025-12-08T14:30:00Z 2025-12-08T03:07:20.781Z,1765163240.781 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-08T03:07:20.781Z,1765163240.781 [Default:CheckIn:C.Wait] Stopped 2025-12-08T03:07:20.781Z,1765163240.781 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-08T03:07:20.782Z,1765163240.782 [Default:CheckIn:D] Running Loop=1 2025-12-08T03:07:21.187Z,1765163241.187 [Default:CheckIn:D] Stopped 2025-12-08T03:07:21.187Z,1765163241.187 [Default:CheckIn:E] Running Loop=1 2025-12-08T03:07:21.604Z,1765163241.604 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.000340 min 2025-12-08T03:07:21.604Z,1765163241.604 [Default:CheckIn:E] Stopped 2025-12-08T03:07:21.604Z,1765163241.604 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-08T03:07:21.604Z,1765163241.604 [Default:CheckIn] Stopped 2025-12-08T03:07:21.605Z,1765163241.605 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-08T03:07:21.605Z,1765163241.605 [Default:CheckIn](INFO): Running loop #2 2025-12-08T03:07:21.605Z,1765163241.605 [Default:CheckIn] Running Loop=2 2025-12-08T03:07:21.605Z,1765163241.605 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-08T03:07:21.605Z,1765163241.605 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-08T03:07:23.611Z,1765163243.611 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030722.00,A,3631.33962,N,12200.93688,W,1.030,258.94,081225,,,A*73 2025-12-08T03:07:23.634Z,1765163243.634 [NAL9602](INFO): GPS fix at 20251208T030722: (36.522327, -122.015615) 2025-12-08T03:07:23.652Z,1765163243.652 [Default:CheckIn:Read_GPS] Stopped 2025-12-08T03:07:23.652Z,1765163243.652 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-08T03:07:25.603Z,1765163245.603 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20251208T025549/Courier0007.lzma 2025-12-08T03:07:26.311Z,1765163246.311 [DataOverHttps](INFO): Moved sent file to Logs/20251208T025549/Courier0007.lzma.bak 2025-12-08T03:07:26.311Z,1765163246.311 [DataOverHttps](IMPORTANT): SBD MOMSN=26457308, MTMSN=20251208T030724 2025-12-08T03:07:33.760Z,1765163253.760 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 14 h;set keepstation.NeedCommsTime 90 min;set keepstation.Depth 20 m;set keepstation.Speed 1 m/s;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m" 1jsoa 1 2 2025-12-08T03:07:33.829Z,1765163253.829 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 14 h;set keepstation.NeedCommsTime 90 min;set keepstation.Depth 20 m;set keepstation.Speed 1 m/s;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m" 1jsoa 1 2.000000 2025-12-08T03:07:33.829Z,1765163253.829 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=1jsoa 2025-12-08T03:07:33.830Z,1765163253.830 [CommandExec](IMPORTANT): Scheduled #6 (#1 of 2 with id='1jsoa'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 14 h;set keepstation.NeedCommsTime 90 min;set keepstation.Depth 20 m;set keepstation.Speed 1 m/s;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m", ASAP 2025-12-08T03:07:35.595Z,1765163255.595 [DataOverHttps](IMPORTANT): SBD MTMSN=20251208T030732 2025-12-08T03:07:42.124Z,1765163262.124 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20251208T030336/Courier0000.lzma 2025-12-08T03:07:42.126Z,1765163262.126 [DataOverHttps](INFO): Received command: sched asap "set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 30 min;run" 1jsoa 2 2 2025-12-08T03:07:42.159Z,1765163262.159 [CommandExec](IMPORTANT): got command schedule asap "set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 30 min;run" 1jsoa 2 2.000000 2025-12-08T03:07:42.160Z,1765163262.160 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=1jsoa 2025-12-08T03:07:42.161Z,1765163262.161 [CommandExec](IMPORTANT): Scheduled #7 (#2 of 2 with id='1jsoa'): "set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 30 min;run", ASAP 2025-12-08T03:07:42.274Z,1765163262.274 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-12-08T03:07:42.274Z,1765163262.274 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-12-08T03:07:42.275Z,1765163262.275 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-12-08T03:07:42.277Z,1765163262.277 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-12-08T03:07:43.526Z,1765163263.526 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Courier0000.lzma.bak 2025-12-08T03:07:43.526Z,1765163263.526 [DataOverHttps](INFO): SBD MOMSN=26457311 2025-12-08T03:07:43.842Z,1765163263.842 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into: 4 45 NaN NaN 30 15 4 0.75 200 10 10 45 2 0 0 1 2 3 NaN 0 Sinking to Timed out while trying to reach target sink depth at current depth of 2025-12-08T03:07:43.851Z,1765163263.851 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-12-08T03:07:43.854Z,1765163263.854 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-12-08T03:07:43.881Z,1765163263.881 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-12-08T03:07:43.884Z,1765163263.884 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-12-08T03:07:43.886Z,1765163263.886 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-12-08T03:07:43.889Z,1765163263.889 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-12-08T03:07:43.892Z,1765163263.892 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-12-08T03:07:43.895Z,1765163263.895 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-12-08T03:07:43.898Z,1765163263.898 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-12-08T03:07:43.905Z,1765163263.905 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool 2025-12-08T03:07:43.937Z,1765163263.937 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m 2025-12-08T03:07:43.940Z,1765163263.940 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min 2025-12-08T03:07:43.943Z,1765163263.943 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-12-08T03:07:43.955Z,1765163263.955 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-12-08T03:07:43.958Z,1765163263.958 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count 2025-12-08T03:07:43.961Z,1765163263.961 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count 2025-12-08T03:07:44.008Z,1765163264.008 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count 2025-12-08T03:07:44.011Z,1765163264.011 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count 2025-12-08T03:07:44.018Z,1765163264.018 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count 2025-12-08T03:07:44.022Z,1765163264.022 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool 2025-12-08T03:07:44.023Z,1765163264.023 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-12-08T03:07:44.023Z,1765163264.023 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-12-08T03:07:44.037Z,1765163264.037 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-12-08T03:07:44.524Z,1765163264.524 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1500 7 30 0 0 0 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2025-12-08T03:07:44.530Z,1765163264.530 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-12-08T03:07:44.533Z,1765163264.533 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-12-08T03:07:44.552Z,1765163264.552 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-12-08T03:07:44.555Z,1765163264.555 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-12-08T03:07:44.566Z,1765163264.566 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-12-08T03:07:44.570Z,1765163264.570 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1500.000000 s 2025-12-08T03:07:44.581Z,1765163264.581 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-12-08T03:07:44.583Z,1765163264.583 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-12-08T03:07:44.599Z,1765163264.599 [MissionManager](INFO): DefineArg keepstation:NeedComms.DoingCommsMode = 0.000000 count 2025-12-08T03:07:44.602Z,1765163264.602 [MissionManager](INFO): DefineArg keepstation:NeedComms.RestoreMode = 0.000000 count 2025-12-08T03:07:44.613Z,1765163264.613 [MissionManager](INFO): DefineOutput keepstation:NeedComms.IsActive = 0 bool 2025-12-08T03:07:44.624Z,1765163264.624 [MissionManager](INFO): DefineOutput keepstation:NeedComms.RequiredDiveMode = 0.000000 count 2025-12-08T03:07:44.656Z,1765163264.656 [keepstation:NeedComms:C.GoToSurface](DEBUG): Construct GoToSurface. 2025-12-08T03:07:44.724Z,1765163264.724 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-12-08T03:07:44.724Z,1765163264.724 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-12-08T03:07:44.725Z,1765163264.725 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-12-08T03:07:44.901Z,1765163264.901 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2025-12-08T03:07:44.910Z,1765163264.910 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-12-08T03:07:44.913Z,1765163264.913 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2025-12-08T03:07:44.924Z,1765163264.924 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-12-08T03:07:44.929Z,1765163264.929 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-12-08T03:07:44.930Z,1765163264.930 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-12-08T03:07:44.942Z,1765163264.942 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-12-08T03:07:44.969Z,1765163264.969 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-12-08T03:07:45.001Z,1765163265.001 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-12-08T03:07:45.001Z,1765163265.001 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-12-08T03:07:45.002Z,1765163265.002 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-12-08T03:07:45.044Z,1765163265.044 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-12-08T03:07:45.048Z,1765163265.048 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-12-08T03:07:45.051Z,1765163265.051 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-12-08T03:07:45.054Z,1765163265.054 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-12-08T03:07:45.054Z,1765163265.054 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-12-08T03:07:45.055Z,1765163265.055 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-12-08T03:07:45.298Z,1765163265.298 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-12-08T03:07:45.305Z,1765163265.305 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-12-08T03:07:45.308Z,1765163265.308 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-12-08T03:07:45.310Z,1765163265.310 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-12-08T03:07:45.313Z,1765163265.313 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-12-08T03:07:45.318Z,1765163265.318 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool 2025-12-08T03:07:45.341Z,1765163265.341 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-12-08T03:07:45.343Z,1765163265.343 [keepstation:H.Pitch](DEBUG): Construct. 2025-12-08T03:07:45.434Z,1765163265.434 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-12-08T03:07:45.438Z,1765163265.438 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct. 2025-12-08T03:07:45.450Z,1765163265.450 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct. 2025-12-08T03:07:45.482Z,1765163265.482 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-12-08T03:07:45.485Z,1765163265.485 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct. 2025-12-08T03:07:45.499Z,1765163265.499 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct. 2025-12-08T03:07:45.514Z,1765163265.514 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct. 2025-12-08T03:07:45.519Z,1765163265.519 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation. 2025-12-08T03:07:45.545Z,1765163265.545 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-12-08T03:07:45.577Z,1765163265.577 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait. 2025-12-08T03:07:45.660Z,1765163265.660 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission keepstation { """ Vehicle transits to desired waypoint (or stays put if none is commanded) and stays within the specified radius. """ arguments { MissionTimeout = 4 hour """ Maximum duration of mission """ NeedCommsTime = 45 minute """ How often to surface for communications """ Latitude = NaN degree """ Latitude of waypoint to hold. If NaN, hold the latitude at start of mission. """ Longitude = NaN degree """ Longitude of waypoint to hold. If NaN, hold the longitude at start of mission. """ Depth = 30 meter """ Depth held during drift mode """ ApproachDepth = 15 meter """ Depth for initial approach to station. """ DepthDeadband = 4 meter """ How much vertical drift from the specified depth is allowed in drift mode """ Speed = 0.75 meter_per_second """ Vehicle transit speed. """ Radius = 200 meter """ Radius of circle around waypoint to hold. """ SinkOffSurface = false """ If true, use buoyancy only to decend to SinkDepth before driving to first target depth """ SinkDepth = 10 meter """ Initial depth to decend using only buoyancy before driving """ SinkDepthTimeout = 10 minute """ Max wait time for the vehicle to reach the target depth """ MaxDepth = 45 meter """ Maximum allowable depth during the mission. """ MinOffshore = 2 kilometer """ Minimum distance from the shoreline to maintain """ } output { DiveMode = 0 count """ Mission variable (don't change). The mission sets this variable to switch between dive modes. Initialized to DoingComms. """ DoingComms = 0 count """ Mission variable (don't change). The mission will run in this mode when doing surface comms. """ SurfaceSink = 1 count """ Mission variable (don't change). The mission will run in this mode when sinking from the surface using only buoyancy. """ TransitToStation = 2 count """ Mission variable (don't change). The mission will run in this mode when performing the transit to station """ StationKeep = 3 count """ Mission variable (don't change). The mission will run in this mode when keeping station """ OnStation = false """ Mission variable (don't change). Determines last mode (Transit or StationKeep) to resume after needcomms """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Pitch { run in parallel set massPosition = Control:VerticalControl.massDefault } aggregate SurfaceComms { run when ( called or ( elapsed ( Universal:time_fix ) > NeedCommsTime ) ) assign in sequence DiveMode = DoingComms call priorityHere=false refId="NeedComms" aggregate HandleNan { run in sequence break if ( not isNaN ( Latitude ) and not isNaN ( Longitude ) ) # If both lat and lon are nan, we are implicitly on station and should skip initial transit, so force OnStation to true aggregate SetOnStation { run in sequence break if ( not isNaN ( Latitude ) or not isNaN ( Longitude ) ) assign in sequence OnStation = true } # The "assigns" here mean we should only ever run these aggregates once # An initial NaN lat/lon should be set here during the initial surface NeedComms call # Use the most recent fix -- if there isn't one, the keepstation behavior will use Universal:lat/lon aggregate SetLat { run in sequence break if ( not isNaN ( Latitude ) ) assign in sequence Latitude = Universal:latitude_fix } aggregate SetLon { run in sequence break if ( not isNaN ( Longitude ) ) assign in sequence Longitude = Universal:longitude_fix } } aggregate setTransit { run in sequence break if ( SinkOffSurface or OnStation ) assign in sequence DiveMode = TransitToStation } aggregate setStation { run in sequence break if ( SinkOffSurface or not OnStation ) assign in sequence DiveMode = StationKeep } aggregate setSink { run in sequence break if ( not SinkOffSurface ) assign in sequence DiveMode = SurfaceSink } } call id="StartingMission" priorityHere=false refId="SurfaceComms" aggregate DiveCmd { run when ( called ) aggregate SurfaceSink { run while ( DiveMode == SurfaceSink ) aggregate sink { run in sequence behavior Guidance:Buoyancy id="BuoyancyHold" { run in parallel set position = NaN cc } behavior Guidance:SetSpeed { run in parallel set speed = 0 m/s } syslog info "Sinking to " + SinkDepth~meter behavior Guidance:Pitch { run in sequence timeout duration=SinkDepthTimeout { syslog important "Timed out while trying to reach target sink depth at current depth of " + Universal:depth~meter } set depth = SinkDepth } # Once we've sunk, either resume transit or station keeping depths and speeds aggregate sinkToTransit { run in sequence break if ( OnStation ) assign in sequence DiveMode = TransitToStation } aggregate sinkToStation { run in sequence break if ( not OnStation ) assign in sequence DiveMode = StationKeep } } } aggregate TransitToStation { run while ( DiveMode == TransitToStation ) aggregate dive { run in sequence behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch { run in parallel set depth = ApproachDepth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } } } aggregate StationKeep { run while ( DiveMode == StationKeep ) assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:KeepStation { run in parallel set latitude = Latitude set longitude = Longitude set radius = Radius set speed = Speed } } } aggregate TransitToStation { """ Need a separate aggregate for transit if we want to specify a different depth for the approach. """ run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude } assign in sequence DiveMode = StationKeep assign in sequence OnStation = true } call id="OnStation" priorityHere=false refId="NeedComms" aggregate KeepStation { run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Wait { """ Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. """ run in sequence set duration = MissionTimeout } } } 2025-12-08T03:07:45.661Z,1765163265.661 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-12-08T03:07:54.085Z,1765163274.085 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-08T03:07:54.168Z,1765163274.168 [NAL9602](ERROR): received: +CSQ:0 OK386, 2, 0, 0, 0 OK 2025-12-08T03:08:05.976Z,1765163285.976 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20251208T030336/Courier0006.lzma 2025-12-08T03:08:06.978Z,1765163286.978 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Courier0006.lzma.bak 2025-12-08T03:08:06.978Z,1765163286.978 [DataOverHttps](INFO): SBD MOMSN=26457316 2025-12-08T03:08:08.870Z,1765163288.870 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 14 hour 2025-12-08T03:08:08.871Z,1765163288.871 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 90 minute 2025-12-08T03:08:08.871Z,1765163288.871 [CommandExec](IMPORTANT): got command set keepstation.Depth 20 meter 2025-12-08T03:08:08.872Z,1765163288.872 [CommandExec](IMPORTANT): got command set keepstation.Speed 1 meter_per_second 2025-12-08T03:08:08.873Z,1765163288.873 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool 2025-12-08T03:08:08.874Z,1765163288.874 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 45 meter 2025-12-08T03:08:19.018Z,1765163299.018 [DataOverHttps](IMPORTANT): SBD MTMSN=20251208T030817 2025-12-08T03:08:20.828Z,1765163300.828 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second 2025-12-08T03:08:20.829Z,1765163300.829 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 30 minute 2025-12-08T03:08:20.829Z,1765163300.829 [CommandExec](IMPORTANT): got command run 2025-12-08T03:08:20.831Z,1765163300.831 [CommandExec](IMPORTANT): Running 2025-12-08T03:08:21.167Z,1765163301.167 [Default] Stopped 2025-12-08T03:08:21.167Z,1765163301.167 [Default](DEBUG): Aggregate::uninitialize Default 2025-12-08T03:08:21.167Z,1765163301.167 [Default:B.GoToSurface] Stopped 2025-12-08T03:08:21.167Z,1765163301.167 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-08T03:08:21.167Z,1765163301.167 [Default:CheckIn] Stopped 2025-12-08T03:08:21.167Z,1765163301.167 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-08T03:08:21.167Z,1765163301.167 [Default:CheckIn:Read_Iridium] Stopped 2025-12-08T03:08:21.167Z,1765163301.167 [MissionManager](IMPORTANT): Started mission keepstation 2025-12-08T03:08:21.184Z,1765163301.184 [keepstation] Running Loop=1 2025-12-08T03:08:21.184Z,1765163301.184 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-12-08T03:08:21.184Z,1765163301.184 [keepstation:StandardEnvelopes] Running Loop=1 2025-12-08T03:08:21.184Z,1765163301.184 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-12-08T03:08:21.184Z,1765163301.184 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-12-08T03:08:21.184Z,1765163301.184 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-12-08T03:08:21.185Z,1765163301.185 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-12-08T03:08:21.185Z,1765163301.185 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-12-08T03:08:21.185Z,1765163301.185 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-12-08T03:08:21.185Z,1765163301.185 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-12-08T03:08:21.185Z,1765163301.185 [keepstation:BackseatDriver] Running Loop=1 2025-12-08T03:08:21.186Z,1765163301.186 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-12-08T03:08:21.186Z,1765163301.186 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-12-08T03:08:21.186Z,1765163301.186 [keepstation:PowerOnly] Running Loop=1 2025-12-08T03:08:21.186Z,1765163301.186 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-12-08T03:08:21.186Z,1765163301.186 [keepstation:PowerOnly:A] Running Loop=1 2025-12-08T03:08:21.186Z,1765163301.186 [keepstation:PowerOnly:B] Running Loop=1 2025-12-08T03:08:21.186Z,1765163301.186 [keepstation:PowerOnly:C] Running Loop=1 2025-12-08T03:08:21.186Z,1765163301.186 [keepstation:PowerOnly:D] Running Loop=1 2025-12-08T03:08:21.186Z,1765163301.186 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-12-08T03:08:21.186Z,1765163301.186 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-12-08T03:08:21.187Z,1765163301.187 [keepstation:H.Pitch] Running Loop=1 2025-12-08T03:08:21.187Z,1765163301.187 [keepstation:H.Pitch](DEBUG): Initialize. 2025-12-08T03:08:21.187Z,1765163301.187 [keepstation:B] Running Loop=1 2025-12-08T03:08:21.192Z,1765163301.192 [keepstation:H.Pitch] Running Loop=1 2025-12-08T03:08:21.193Z,1765163301.193 [keepstation:PowerOnly] Running Loop=1 2025-12-08T03:08:21.193Z,1765163301.193 [keepstation:PowerOnly:D] Stopped 2025-12-08T03:08:21.193Z,1765163301.193 [keepstation:PowerOnly:C] Stopped 2025-12-08T03:08:21.193Z,1765163301.193 [keepstation:PowerOnly:B] Stopped 2025-12-08T03:08:21.193Z,1765163301.193 [keepstation:PowerOnly:A] Stopped 2025-12-08T03:08:21.193Z,1765163301.193 [keepstation:BackseatDriver] Running Loop=1 2025-12-08T03:08:21.194Z,1765163301.194 [keepstation:StandardEnvelopes] Running Loop=1 2025-12-08T03:08:21.194Z,1765163301.194 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-12-08T03:08:21.194Z,1765163301.194 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-12-08T03:08:21.195Z,1765163301.195 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-12-08T03:08:21.200Z,1765163301.200 [keepstation:B] Stopped 2025-12-08T03:08:21.200Z,1765163301.200 [keepstation:D] Running Loop=1 2025-12-08T03:08:21.574Z,1765163301.574 [keepstation:D] Stopped 2025-12-08T03:08:21.574Z,1765163301.574 [keepstation:E] Running Loop=1 2025-12-08T03:08:21.977Z,1765163301.977 [keepstation:E] Stopped 2025-12-08T03:08:21.978Z,1765163301.978 [keepstation:StartingMission] Running Loop=1 2025-12-08T03:08:21.978Z,1765163301.978 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-12-08T03:08:22.394Z,1765163302.394 [keepstation:SurfaceComms] Running Loop=1 2025-12-08T03:08:22.395Z,1765163302.395 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-08T03:08:22.395Z,1765163302.395 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-08T03:08:22.400Z,1765163302.400 [keepstation:SurfaceComms:A] Stopped 2025-12-08T03:08:22.400Z,1765163302.400 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-08T03:08:22.400Z,1765163302.400 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-08T03:08:22.923Z,1765163302.923 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2025-12-08T03:08:22.923Z,1765163302.923 [keepstation:NeedComms] Running Loop=1 2025-12-08T03:08:22.923Z,1765163302.923 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-08T03:08:22.923Z,1765163302.923 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-08T03:08:22.923Z,1765163302.923 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-08T03:08:22.923Z,1765163302.923 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-08T03:08:22.928Z,1765163302.928 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-08T03:08:22.928Z,1765163302.928 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-08T03:08:22.929Z,1765163302.929 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-08T03:08:22.929Z,1765163302.929 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-08T03:08:22.929Z,1765163302.929 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-08T03:08:22.930Z,1765163302.930 [keepstation:NeedComms:A] Running Loop=1 2025-12-08T03:08:22.931Z,1765163302.931 [keepstation:NeedComms:A](INFO): last time_fix was: 1765163242.000000 second since 1970/01/01T00:00:00Z 2025-12-08T03:08:22.931Z,1765163302.931 [keepstation:NeedComms:A] Stopped 2025-12-08T03:08:22.931Z,1765163302.931 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-08T03:08:22.931Z,1765163302.931 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-08T03:08:22.931Z,1765163302.931 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-08T03:08:23.222Z,1765163303.222 [keepstation:NeedComms:setActive:A] Stopped 2025-12-08T03:08:23.222Z,1765163303.222 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-08T03:08:23.648Z,1765163303.648 [keepstation:NeedComms:setActive:B] Stopped 2025-12-08T03:08:23.648Z,1765163303.648 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-08T03:08:23.648Z,1765163303.648 [keepstation:NeedComms:setActive] Stopped 2025-12-08T03:08:23.648Z,1765163303.648 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-08T03:08:24.052Z,1765163304.052 [keepstation:NeedComms:D] Running Loop=1 2025-12-08T03:08:24.436Z,1765163304.436 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-12-08T03:08:26.895Z,1765163306.895 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20251208T030336/Courier0009.lzma 2025-12-08T03:08:26.898Z,1765163306.898 [DataOverHttps](INFO): Received command: sched "load Science/sci2_vtyoyo.tl;set sci2_vtyoyo.MissionTimeout 20 h;set sci2_vtyoyo.NeedCommsTime 90 min;set sci2_vtyoyo.Lat2 36.797 degree;set sci2_vtyoyo.Lon2 -121.847 degree;set sci2_vtyoyo.Speed .8 m/s;set sci2_vtyoyo.YoYoMinDepth 4 m" 1jspj 1 2 2025-12-08T03:08:26.982Z,1765163306.982 [CommandExec](IMPORTANT): got command schedule "load Science/sci2_vtyoyo.tl;set sci2_vtyoyo.MissionTimeout 20 h;set sci2_vtyoyo.NeedCommsTime 90 min;set sci2_vtyoyo.Lat2 36.797 degree;set sci2_vtyoyo.Lon2 -121.847 degree;set sci2_vtyoyo.Speed .8 m/s;set sci2_vtyoyo.YoYoMinDepth 4 m" 1jspj 1 2.000000 2025-12-08T03:08:26.983Z,1765163306.983 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=1jspj 2025-12-08T03:08:26.984Z,1765163306.984 [CommandExec](IMPORTANT): Scheduled #8 (#1 of 2 with id='1jspj'): "load Science/sci2_vtyoyo.tl;set sci2_vtyoyo.MissionTimeout 20 h;set sci2_vtyoyo.NeedCommsTime 90 min;set sci2_vtyoyo.Lat2 36.797 degree;set sci2_vtyoyo.Lon2 -121.847 degree;set sci2_vtyoyo.Speed .8 m/s;set sci2_vtyoyo.YoYoMinDepth 4 m", AFTER MISSION 2025-12-08T03:08:27.898Z,1765163307.898 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Courier0009.lzma.bak 2025-12-08T03:08:27.899Z,1765163307.899 [DataOverHttps](IMPORTANT): SBD MOMSN=26457319, MTMSN=20251208T030826 2025-12-08T03:08:36.680Z,1765163316.680 [DataOverHttps](INFO): Received command: sched "set sci2_vtyoyo.YoYoMaxDepth 90 m;set sci2_vtyoyo.MaxDepth 120 m;set sci2_vtyoyo.SinkOffSurface 1 bool;set sci2_vtyoyo.SinkDepthTimeout 3 min;set sci2_vtyoyo:BackseatDriver.EnableBackseat 1 bool;run" 1jspj 2 2 2025-12-08T03:08:36.790Z,1765163316.790 [CommandExec](IMPORTANT): got command schedule "set sci2_vtyoyo.YoYoMaxDepth 90 m;set sci2_vtyoyo.MaxDepth 120 m;set sci2_vtyoyo.SinkOffSurface 1 bool;set sci2_vtyoyo.SinkDepthTimeout 3 min;set sci2_vtyoyo:BackseatDriver.EnableBackseat 1 bool;run" 1jspj 2 2.000000 2025-12-08T03:08:36.791Z,1765163316.791 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=1jspj 2025-12-08T03:08:36.802Z,1765163316.802 [CommandExec](IMPORTANT): Scheduled #9 (#2 of 2 with id='1jspj'): "set sci2_vtyoyo.YoYoMaxDepth 90 m;set sci2_vtyoyo.MaxDepth 120 m;set sci2_vtyoyo.SinkOffSurface 1 bool;set sci2_vtyoyo.SinkDepthTimeout 3 min;set sci2_vtyoyo:BackseatDriver.EnableBackseat 1 bool;run", AFTER MISSION 2025-12-08T03:11:04.435Z,1765163464.435 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T03:11:04.435Z,1765163464.435 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T03:11:57.723Z,1765163517.723 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T03:11:57.724Z,1765163517.724 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T03:12:26.406Z,1765163546.406 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-08T03:12:32.891Z,1765163552.891 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,031231.00,A,3631.30640,N,12200.96391,W,0.097,254.46,081225,,,A*7E 2025-12-08T03:12:32.893Z,1765163552.893 [NAL9602](INFO): GPS fix at 20251208T031231: (36.521773, -122.016065) 2025-12-08T03:12:32.925Z,1765163552.925 [keepstation:NeedComms:D] Stopped 2025-12-08T03:12:32.926Z,1765163552.926 [keepstation:NeedComms:E] Running Loop=1 2025-12-08T03:12:33.310Z,1765163553.310 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-12-08T03:12:40.647Z,1765163560.647 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251208T030336/Courier0012.lzma 2025-12-08T03:12:41.650Z,1765163561.650 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Courier0012.lzma.bak 2025-12-08T03:12:41.650Z,1765163561.650 [DataOverHttps](INFO): SBD MOMSN=26457375 2025-12-08T03:12:57.748Z,1765163577.748 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20251208T025549/Express0008.lzma 2025-12-08T03:12:58.750Z,1765163578.750 [DataOverHttps](INFO): Moved sent file to Logs/20251208T025549/Express0008.lzma.bak 2025-12-08T03:12:58.750Z,1765163578.750 [DataOverHttps](INFO): SBD MOMSN=26457378 2025-12-08T03:13:05.297Z,1765163585.297 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-08T03:13:14.594Z,1765163594.594 [DataOverHttps](INFO): Sending 1183 bytes from file Logs/20251208T030336/Express0001.lzma 2025-12-08T03:13:15.594Z,1765163595.594 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0001.lzma.bak 2025-12-08T03:13:15.594Z,1765163595.594 [DataOverHttps](INFO): SBD MOMSN=26457382 2025-12-08T03:13:31.575Z,1765163611.575 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20251208T030336/Express0004.lzma 2025-12-08T03:13:32.578Z,1765163612.578 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0004.lzma.bak 2025-12-08T03:13:32.578Z,1765163612.578 [DataOverHttps](INFO): SBD MOMSN=26457406 2025-12-08T03:13:48.584Z,1765163628.584 [DataOverHttps](INFO): Sending 337 bytes from file Logs/20251208T030336/Express0007.lzma 2025-12-08T03:13:49.586Z,1765163629.586 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0007.lzma.bak 2025-12-08T03:13:49.586Z,1765163629.586 [DataOverHttps](INFO): SBD MOMSN=26457408 2025-12-08T03:14:05.472Z,1765163645.472 [DataOverHttps](INFO): Sending 318 bytes from file Logs/20251208T030336/Express0010.lzma 2025-12-08T03:14:06.474Z,1765163646.474 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0010.lzma.bak 2025-12-08T03:14:06.474Z,1765163646.474 [DataOverHttps](INFO): SBD MOMSN=26457414 2025-12-08T03:14:22.499Z,1765163662.499 [DataOverHttps](INFO): Sending 824 bytes from file Logs/20251208T030336/Express0013.lzma 2025-12-08T03:14:23.498Z,1765163663.498 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0013.lzma.bak 2025-12-08T03:14:23.498Z,1765163663.498 [DataOverHttps](INFO): SBD MOMSN=26457421 2025-12-08T03:14:39.843Z,1765163679.843 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20251208T030336/Express0016.lzma 2025-12-08T03:14:40.846Z,1765163680.846 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0016.lzma.bak 2025-12-08T03:14:40.846Z,1765163680.846 [DataOverHttps](INFO): SBD MOMSN=26457444 2025-12-08T03:14:42.359Z,1765163682.359 [keepstation:NeedComms:E] Stopped 2025-12-08T03:14:42.360Z,1765163682.360 [keepstation:NeedComms:F] Running Loop=1 2025-12-08T03:14:42.749Z,1765163682.749 [keepstation:NeedComms:F](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-12-08T03:14:44.351Z,1765163684.351 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,031442.00,A,3631.29567,N,12200.97365,W,0.758,12.00,081225,,,A*4E 2025-12-08T03:14:44.353Z,1765163684.353 [NAL9602](INFO): GPS fix at 20251208T031442: (36.521594, -122.016227) 2025-12-08T03:14:44.403Z,1765163684.403 [keepstation:NeedComms:F] Stopped 2025-12-08T03:14:44.403Z,1765163684.403 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-08T03:14:44.403Z,1765163684.403 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-08T03:14:44.403Z,1765163684.403 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-08T03:14:44.816Z,1765163684.816 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-08T03:14:44.816Z,1765163684.816 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-08T03:14:44.816Z,1765163684.816 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-08T03:14:44.816Z,1765163684.816 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-08T03:14:44.817Z,1765163684.817 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-08T03:14:44.817Z,1765163684.817 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-08T03:14:44.817Z,1765163684.817 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-08T03:14:45.185Z,1765163685.185 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-08T03:14:45.185Z,1765163685.185 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-08T03:14:45.185Z,1765163685.185 [keepstation:NeedComms:setInactive] Stopped 2025-12-08T03:14:45.186Z,1765163685.186 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-08T03:14:45.186Z,1765163685.186 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-08T03:14:45.186Z,1765163685.186 [keepstation:NeedComms] Stopped 2025-12-08T03:14:45.186Z,1765163685.186 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-08T03:14:45.187Z,1765163685.187 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-08T03:14:45.187Z,1765163685.187 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-08T03:14:45.607Z,1765163685.607 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-08T03:14:45.607Z,1765163685.607 [keepstation:SurfaceComms:B] Stopped 2025-12-08T03:14:45.607Z,1765163685.607 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-08T03:14:45.607Z,1765163685.607 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-08T03:14:45.607Z,1765163685.607 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-08T03:14:45.612Z,1765163685.612 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-08T03:14:45.612Z,1765163685.612 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T03:14:45.612Z,1765163685.612 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-08T03:14:46.005Z,1765163686.005 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-08T03:14:46.005Z,1765163686.005 [keepstation:SurfaceComms:HandleNan:SetOnStation](INFO): Completed keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T03:14:46.006Z,1765163686.006 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-08T03:14:46.006Z,1765163686.006 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T03:14:46.006Z,1765163686.006 [keepstation:SurfaceComms:HandleNan:SetLat] Running Loop=1 2025-12-08T03:14:46.006Z,1765163686.006 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLat 2025-12-08T03:14:46.006Z,1765163686.006 [keepstation:SurfaceComms:HandleNan:SetLat:A] Running Loop=1 2025-12-08T03:14:46.389Z,1765163686.389 [keepstation:SurfaceComms:HandleNan:SetLat:A] Stopped 2025-12-08T03:14:46.389Z,1765163686.389 [keepstation:SurfaceComms:HandleNan:SetLat](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLat 2025-12-08T03:14:46.390Z,1765163686.390 [keepstation:SurfaceComms:HandleNan:SetLat] Stopped 2025-12-08T03:14:46.390Z,1765163686.390 [keepstation:SurfaceComms:HandleNan:SetLat](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLat 2025-12-08T03:14:46.390Z,1765163686.390 [keepstation:SurfaceComms:HandleNan:SetLon] Running Loop=1 2025-12-08T03:14:46.390Z,1765163686.390 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetLon 2025-12-08T03:14:46.390Z,1765163686.390 [keepstation:SurfaceComms:HandleNan:SetLon:A] Running Loop=1 2025-12-08T03:14:46.805Z,1765163686.805 [keepstation:SurfaceComms:HandleNan:SetLon:A] Stopped 2025-12-08T03:14:46.805Z,1765163686.805 [keepstation:SurfaceComms:HandleNan:SetLon](INFO): Completed keepstation:SurfaceComms:HandleNan:SetLon 2025-12-08T03:14:46.805Z,1765163686.805 [keepstation:SurfaceComms:HandleNan:SetLon] Stopped 2025-12-08T03:14:46.805Z,1765163686.805 [keepstation:SurfaceComms:HandleNan:SetLon](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetLon 2025-12-08T03:14:46.805Z,1765163686.805 [keepstation:SurfaceComms:HandleNan](INFO): Completed keepstation:SurfaceComms:HandleNan 2025-12-08T03:14:46.805Z,1765163686.805 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-08T03:14:46.805Z,1765163686.805 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-08T03:14:46.805Z,1765163686.805 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-08T03:14:46.806Z,1765163686.806 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-08T03:14:46.806Z,1765163686.806 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-08T03:14:47.201Z,1765163687.201 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-08T03:14:47.201Z,1765163687.201 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-08T03:14:47.201Z,1765163687.201 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-08T03:14:47.201Z,1765163687.201 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-08T03:14:47.201Z,1765163687.201 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-08T03:14:47.201Z,1765163687.201 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-08T03:14:47.626Z,1765163687.626 [keepstation:SurfaceComms:setStation] Stopped 2025-12-08T03:14:47.626Z,1765163687.626 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-08T03:14:47.626Z,1765163687.626 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-08T03:14:47.626Z,1765163687.626 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-08T03:14:47.626Z,1765163687.626 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-08T03:14:47.626Z,1765163687.626 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-08T03:14:47.995Z,1765163687.995 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-08T03:14:47.995Z,1765163687.995 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-08T03:14:47.995Z,1765163687.995 [keepstation:SurfaceComms:setSink] Stopped 2025-12-08T03:14:47.995Z,1765163687.995 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-08T03:14:47.995Z,1765163687.995 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-08T03:14:47.995Z,1765163687.995 [keepstation:SurfaceComms] Stopped 2025-12-08T03:14:47.995Z,1765163687.995 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-08T03:14:48.404Z,1765163688.404 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-12-08T03:14:48.404Z,1765163688.404 [keepstation:StartingMission] Stopped 2025-12-08T03:14:48.405Z,1765163688.405 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-12-08T03:14:48.405Z,1765163688.405 [keepstation:TransitToStation] Running Loop=1 2025-12-08T03:14:48.405Z,1765163688.405 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-12-08T03:14:48.405Z,1765163688.405 [keepstation:TransitToStation:Dive] Running Loop=1 2025-12-08T03:14:48.405Z,1765163688.405 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-12-08T03:14:48.405Z,1765163688.405 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-12-08T03:14:48.405Z,1765163688.405 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-12-08T03:14:48.405Z,1765163688.405 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-12-08T03:14:48.405Z,1765163688.405 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-12-08T03:14:48.407Z,1765163688.407 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.521594,-122.016227 2025-12-08T03:14:48.814Z,1765163688.814 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.521594,-122.016227 2025-12-08T03:14:48.814Z,1765163688.814 [keepstation:TransitToStation:Dive] Running Loop=1 2025-12-08T03:14:48.815Z,1765163688.815 [keepstation:DiveCmd] Running Loop=1 2025-12-08T03:14:48.815Z,1765163688.815 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2025-12-08T03:14:48.815Z,1765163688.815 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-08T03:14:48.815Z,1765163688.815 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-08T03:14:48.815Z,1765163688.815 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-08T03:14:48.815Z,1765163688.815 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T03:14:48.815Z,1765163688.815 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-08T03:14:48.815Z,1765163688.815 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-08T03:14:48.815Z,1765163688.815 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-08T03:14:48.815Z,1765163688.815 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-08T03:14:48.816Z,1765163688.816 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-08T03:14:48.816Z,1765163688.816 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-12-08T03:14:48.816Z,1765163688.816 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-12-08T03:14:48.816Z,1765163688.816 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-12-08T03:14:48.816Z,1765163688.816 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-12-08T03:14:48.816Z,1765163688.816 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-12-08T03:14:48.816Z,1765163688.816 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-08T03:14:48.817Z,1765163688.817 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-12-08T03:14:48.817Z,1765163688.817 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-12-08T03:14:48.817Z,1765163688.817 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-12-08T03:14:48.817Z,1765163688.817 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-12-08T03:14:48.817Z,1765163688.817 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-08T03:14:48.817Z,1765163688.817 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-08T03:14:48.817Z,1765163688.817 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-08T03:14:48.817Z,1765163688.817 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-08T03:14:48.817Z,1765163688.817 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-08T03:14:48.818Z,1765163688.818 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-08T03:14:48.818Z,1765163688.818 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-08T03:14:48.818Z,1765163688.818 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-08T03:14:48.818Z,1765163688.818 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:TransitToStation] Stopped 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-12-08T03:14:48.819Z,1765163688.819 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-08T03:14:48.820Z,1765163688.820 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-08T03:14:48.820Z,1765163688.820 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-08T03:14:48.821Z,1765163688.821 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-08T03:14:48.821Z,1765163688.821 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-08T03:14:48.821Z,1765163688.821 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-08T03:14:48.821Z,1765163688.821 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-08T03:14:48.828Z,1765163688.828 [HorizontalControl](DEBUG): kpHeading = 0.400000 2025-12-08T03:14:48.829Z,1765163688.829 [HorizontalControl](DEBUG): kiHeading = 0.001000 2025-12-08T03:14:48.829Z,1765163688.829 [HorizontalControl](DEBUG): kdHeading = 0.050000 2025-12-08T03:15:15.072Z,1765163715.072 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-08T03:15:15.148Z,1765163715.148 [NAL9602](ERROR): received: +CSQ:0 OK386, 2, 0, 0, 0 OK 2025-12-08T03:15:20.605Z,1765163720.605 [DataOverHttps](IMPORTANT): SBD MTMSN=20251208T031518 2025-12-08T03:15:28.392Z,1765163728.392 [DataOverHttps](INFO): Received command: schedule list 2025-12-08T03:15:28.470Z,1765163728.470 [CommandExec](IMPORTANT): got command schedule list 2025-12-08T03:15:28.471Z,1765163728.471 [CommandExec](IMPORTANT): Scheduled #1: "configSet CBIT.navErrorThreshold 100 percent persist;restart logs", AT TIME: 2025-12-08T14:30:00Z 2025-12-08T03:15:28.472Z,1765163728.472 [CommandExec](IMPORTANT): Scheduled #2 (#1 of 4 with id='1jslu'): "load Science/sci2_vtyoyo.tl;set sci2_vtyoyo.MissionTimeout 14 h;set sci2_vtyoyo.NeedCommsTime 90 min;set sci2_vtyoyo.Lat1 36.50439 degree;set sci2_vtyoyo.Lon1 -122.17209 degree;set sci2_vtyoyo.Lat2 36.58176 degree", AT TIME: 2025-12-08T14:30:00Z 2025-12-08T03:15:28.473Z,1765163728.473 [CommandExec](IMPORTANT): Scheduled #3 (#2 of 4 with id='1jslu'): "set sci2_vtyoyo.Lon2 -122.17398 degree;set sci2_vtyoyo.Lat3 36.70806 degree;set sci2_vtyoyo.Lon3 -122.18582 degree;set sci2_vtyoyo.Lat4 36.83842 degree;set sci2_vtyoyo.Lon4 -122.17209 degree", AT TIME: 2025-12-08T14:30:00Z 2025-12-08T03:15:28.474Z,1765163728.474 [CommandExec](IMPORTANT): Scheduled #4 (#3 of 4 with id='1jslu'): "set sci2_vtyoyo.Lat5 36.99378 degree;set sci2_vtyoyo.Lon5 -122.44675 degree;set sci2_vtyoyo.Speed .7 m/s;set sci2_vtyoyo.YoYoMinDepth 4 m;set sci2_vtyoyo.YoYoMaxDepth 100 m;set sci2_vtyoyo.MaxDepth 135 m", AT TIME: 2025-12-08T14:30:00Z 2025-12-08T03:15:28.475Z,1765163728.475 [CommandExec](IMPORTANT): Scheduled #5 (#4 of 4 with id='1jslu'): "set sci2_vtyoyo.SinkOffSurface 1 bool;set sci2_vtyoyo.SinkDepthTimeout 2 min;set sci2_vtyoyo:BackseatDriver.EnableBackseat 1 bool;run", AT TIME: 2025-12-08T14:30:00Z 2025-12-08T03:15:28.476Z,1765163728.476 [CommandExec](IMPORTANT): Scheduled #8 (#1 of 2 with id='1jspj'): "load Science/sci2_vtyoyo.tl;set sci2_vtyoyo.MissionTimeout 20 h;set sci2_vtyoyo.NeedCommsTime 90 min;set sci2_vtyoyo.Lat2 36.797 degree;set sci2_vtyoyo.Lon2 -121.847 degree;set sci2_vtyoyo.Speed .8 m/s;set sci2_vtyoyo.YoYoMinDepth 4 m", AFTER MISSION 2025-12-08T03:15:28.477Z,1765163728.477 [CommandExec](IMPORTANT): Scheduled #9 (#2 of 2 with id='1jspj'): "set sci2_vtyoyo.YoYoMaxDepth 90 m;set sci2_vtyoyo.MaxDepth 120 m;set sci2_vtyoyo.SinkOffSurface 1 bool;set sci2_vtyoyo.SinkDepthTimeout 3 min;set sci2_vtyoyo:BackseatDriver.EnableBackseat 1 bool;run", AFTER MISSION 2025-12-08T03:16:04.806Z,1765163764.806 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T03:17:24.176Z,1765163844.176 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T03:17:55.300Z,1765163875.300 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T03:18:05.700Z,1765163885.700 [Radio_Surface](INFO): Powering down 2025-12-08T03:18:26.428Z,1765163906.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T03:18:27.432Z,1765163907.432 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-08T03:19:31.693Z,1765163971.693 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755630 2025-12-08T03:19:38.482Z,1765163978.482 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-08T03:19:38.482Z,1765163978.482 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-08T03:19:38.482Z,1765163978.482 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T03:19:38.482Z,1765163978.482 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-08T03:19:38.898Z,1765163978.898 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-08T03:19:38.898Z,1765163978.898 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T03:19:38.898Z,1765163978.898 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-08T03:19:38.898Z,1765163978.898 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-08T03:19:38.898Z,1765163978.898 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T03:19:38.899Z,1765163978.899 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-08T03:19:39.284Z,1765163979.284 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-08T03:19:39.284Z,1765163979.284 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T03:19:39.284Z,1765163979.284 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-08T03:19:39.285Z,1765163979.285 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T03:19:39.285Z,1765163979.285 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T03:19:39.285Z,1765163979.285 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-08T03:19:39.285Z,1765163979.285 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T03:19:39.285Z,1765163979.285 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-08T03:19:39.285Z,1765163979.285 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-08T03:19:39.286Z,1765163979.286 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-08T03:19:39.286Z,1765163979.286 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-08T03:19:39.286Z,1765163979.286 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-08T03:19:39.691Z,1765163979.691 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-08T03:19:39.691Z,1765163979.691 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-08T03:19:39.692Z,1765163979.692 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-08T03:19:39.692Z,1765163979.692 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-08T03:19:39.692Z,1765163979.692 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-08T03:19:39.692Z,1765163979.692 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-08T03:19:39.692Z,1765163979.692 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-08T03:19:39.693Z,1765163979.693 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-08T03:19:39.693Z,1765163979.693 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-08T03:19:39.693Z,1765163979.693 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-08T03:19:39.694Z,1765163979.694 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-08T03:19:46.141Z,1765163986.141 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-08T03:20:16.854Z,1765164016.854 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-08T03:27:02.459Z,1765164422.459 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T03:27:02.463Z,1765164422.463 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-12-08T04:39:20.254Z,1765168760.254 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T04:44:44.682Z,1765169084.682 [keepstation:SurfaceComms] Running Loop=1 2025-12-08T04:44:44.683Z,1765169084.683 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-08T04:44:44.683Z,1765169084.683 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-08T04:44:44.686Z,1765169084.686 [keepstation:SurfaceComms:A] Stopped 2025-12-08T04:44:44.686Z,1765169084.686 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-08T04:44:44.686Z,1765169084.686 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-08T04:44:44.690Z,1765169084.690 [keepstation:NeedComms] Running Loop=1 2025-12-08T04:44:44.691Z,1765169084.691 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-08T04:44:44.691Z,1765169084.691 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-08T04:44:44.691Z,1765169084.691 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-08T04:44:44.692Z,1765169084.692 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-08T04:44:44.692Z,1765169084.692 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-08T04:44:44.693Z,1765169084.693 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-08T04:44:44.693Z,1765169084.693 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-08T04:44:44.693Z,1765169084.693 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-08T04:44:44.694Z,1765169084.694 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-08T04:44:44.694Z,1765169084.694 [keepstation:NeedComms:A] Running Loop=1 2025-12-08T04:44:44.696Z,1765169084.696 [keepstation:NeedComms:A](INFO): last time_fix was: 1765163682.000000 second since 1970/01/01T00:00:00Z 2025-12-08T04:44:44.696Z,1765169084.696 [keepstation:NeedComms:A] Stopped 2025-12-08T04:44:44.696Z,1765169084.696 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-08T04:44:44.696Z,1765169084.696 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-08T04:44:44.696Z,1765169084.696 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-08T04:44:45.131Z,1765169085.131 [keepstation:TransitToStation:Dive] Stopped 2025-12-08T04:44:45.131Z,1765169085.131 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-12-08T04:44:45.131Z,1765169085.131 [keepstation:TransitToStation:Dive:A] Stopped 2025-12-08T04:44:45.131Z,1765169085.131 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-12-08T04:44:45.148Z,1765169085.148 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-08T04:44:45.148Z,1765169085.148 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-08T04:44:45.148Z,1765169085.148 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-08T04:44:45.148Z,1765169085.148 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-08T04:44:45.148Z,1765169085.148 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-08T04:44:45.148Z,1765169085.148 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-08T04:44:45.151Z,1765169085.151 [keepstation:NeedComms:setActive:A] Stopped 2025-12-08T04:44:45.151Z,1765169085.151 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-08T04:44:45.493Z,1765169085.493 [keepstation:NeedComms:setActive:B] Stopped 2025-12-08T04:44:45.493Z,1765169085.493 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-08T04:44:45.493Z,1765169085.493 [keepstation:NeedComms:setActive] Stopped 2025-12-08T04:44:45.493Z,1765169085.493 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-08T04:45:27.865Z,1765169127.865 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.521594,-122.016227 2025-12-08T04:45:27.865Z,1765169127.865 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-12-08T04:45:27.865Z,1765169127.865 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-12-08T04:45:27.866Z,1765169127.866 [keepstation:TransitToStation:C] Running Loop=1 2025-12-08T04:45:28.239Z,1765169128.239 [keepstation:TransitToStation:C] Stopped 2025-12-08T04:45:28.240Z,1765169128.240 [keepstation:TransitToStation:D] Running Loop=1 2025-12-08T04:45:28.240Z,1765169128.240 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-08T04:45:28.240Z,1765169128.240 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-08T04:45:28.240Z,1765169128.240 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-08T04:45:28.241Z,1765169128.241 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-08T04:45:28.241Z,1765169128.241 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-08T04:45:28.241Z,1765169128.241 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-08T04:45:28.241Z,1765169128.241 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-08T04:45:28.643Z,1765169128.643 [keepstation:TransitToStation:D] Stopped 2025-12-08T04:45:28.643Z,1765169128.643 [keepstation:TransitToStation:Dive] Running Loop=1 2025-12-08T04:45:28.644Z,1765169128.644 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-12-08T04:45:28.644Z,1765169128.644 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-12-08T04:45:28.644Z,1765169128.644 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-12-08T04:45:28.644Z,1765169128.644 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-12-08T04:45:28.644Z,1765169128.644 [keepstation:TransitToStation] Stopped 2025-12-08T04:45:28.644Z,1765169128.644 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-12-08T04:45:28.644Z,1765169128.644 [keepstation:TransitToStation:Dive] Stopped 2025-12-08T04:45:28.645Z,1765169128.645 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-12-08T04:45:28.645Z,1765169128.645 [keepstation:TransitToStation:Dive:A] Stopped 2025-12-08T04:45:28.645Z,1765169128.645 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-12-08T04:45:28.645Z,1765169128.645 [keepstation:OnStation] Running Loop=1 2025-12-08T04:45:28.645Z,1765169128.645 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-12-08T04:46:08.782Z,1765169168.782 [keepstation:NeedComms:D] Running Loop=1 2025-12-08T04:46:09.280Z,1765169169.280 [Radio_Surface](INFO): Powering up 2025-12-08T04:46:15.161Z,1765169175.161 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-08T04:46:41.611Z,1765169201.611 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-08T04:46:41.686Z,1765169201.686 [NAL9602](ERROR): received: +CSQ:0 OK386, 2, 0, 0, 0 OK 2025-12-08T04:46:45.281Z,1765169205.281 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T04:47:16.424Z,1765169236.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T04:47:47.556Z,1765169267.556 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T04:47:57.160Z,1765169277.160 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T04:47:57.160Z,1765169277.160 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T04:48:18.676Z,1765169298.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T04:48:27.232Z,1765169307.232 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002964 2025-12-08T04:48:38.766Z,1765169318.766 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T04:48:38.766Z,1765169318.766 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T04:48:40.785Z,1765169320.785 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-08T04:48:54.120Z,1765169334.120 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-08T04:51:13.091Z,1765169473.091 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-08T04:51:16.735Z,1765169476.735 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,045115.00,A,3631.43642,N,12201.55788,W,0.408,253.55,081225,,,A*7B 2025-12-08T04:51:16.754Z,1765169476.754 [NAL9602](INFO): GPS fix at 20251208T045115: (36.523940, -122.025965) 2025-12-08T04:51:16.765Z,1765169476.765 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-08T04:51:16.768Z,1765169476.768 [keepstation:NeedComms:D] Stopped 2025-12-08T04:51:16.769Z,1765169476.769 [keepstation:NeedComms:E] Running Loop=1 2025-12-08T04:51:24.396Z,1765169484.396 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20251208T030336/Courier0018.lzma 2025-12-08T04:51:25.398Z,1765169485.398 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Courier0018.lzma.bak 2025-12-08T04:51:25.398Z,1765169485.398 [DataOverHttps](INFO): SBD MOMSN=26457880 2025-12-08T04:51:42.469Z,1765169502.469 [DataOverHttps](INFO): Sending 1111 bytes from file Logs/20251208T030336/Express0019.lzma 2025-12-08T04:51:43.470Z,1765169503.470 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0019.lzma.bak 2025-12-08T04:51:43.470Z,1765169503.470 [DataOverHttps](INFO): SBD MOMSN=26457883 2025-12-08T04:51:49.465Z,1765169509.465 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-08T04:51:59.635Z,1765169519.635 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20251208T030336/Express0022.lzma 2025-12-08T04:52:00.638Z,1765169520.638 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0022.lzma.bak 2025-12-08T04:52:00.638Z,1765169520.638 [DataOverHttps](INFO): SBD MOMSN=26457900 2025-12-08T04:52:02.087Z,1765169522.087 [keepstation:NeedComms:E] Stopped 2025-12-08T04:52:02.088Z,1765169522.088 [keepstation:NeedComms:F] Running Loop=1 2025-12-08T04:52:03.999Z,1765169523.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,045202.00,A,3631.43543,N,12201.56227,W,0.078,300.91,081225,,,A*73 2025-12-08T04:52:04.001Z,1765169524.001 [NAL9602](INFO): GPS fix at 20251208T045202: (36.523924, -122.026038) 2025-12-08T04:52:04.051Z,1765169524.051 [keepstation:NeedComms:F] Stopped 2025-12-08T04:52:04.051Z,1765169524.051 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-08T04:52:04.060Z,1765169524.060 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-08T04:52:04.060Z,1765169524.060 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-08T04:52:04.459Z,1765169524.459 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-08T04:52:04.464Z,1765169524.464 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-08T04:52:04.464Z,1765169524.464 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-08T04:52:04.464Z,1765169524.464 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-08T04:52:04.464Z,1765169524.464 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-08T04:52:04.464Z,1765169524.464 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-08T04:52:04.464Z,1765169524.464 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-08T04:52:04.824Z,1765169524.824 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-08T04:52:04.824Z,1765169524.824 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-08T04:52:04.824Z,1765169524.824 [keepstation:NeedComms:setInactive] Stopped 2025-12-08T04:52:04.825Z,1765169524.825 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-08T04:52:04.825Z,1765169524.825 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-08T04:52:04.825Z,1765169524.825 [keepstation:NeedComms] Stopped 2025-12-08T04:52:04.825Z,1765169524.825 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-08T04:52:04.826Z,1765169524.826 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-08T04:52:04.826Z,1765169524.826 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-08T04:52:05.239Z,1765169525.239 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-12-08T04:52:05.239Z,1765169525.239 [keepstation:OnStation] Stopped 2025-12-08T04:52:05.239Z,1765169525.239 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-12-08T04:52:05.239Z,1765169525.239 [keepstation:KeepStation] Running Loop=1 2025-12-08T04:52:05.239Z,1765169525.239 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-12-08T04:52:05.239Z,1765169525.239 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-08T04:52:05.244Z,1765169525.244 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-08T04:52:05.244Z,1765169525.244 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-08T04:52:05.244Z,1765169525.244 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-08T04:52:05.244Z,1765169525.244 [keepstation:KeepStation:B.Wait] Running Loop=1 2025-12-08T04:52:05.244Z,1765169525.244 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component. 2025-12-08T04:52:05.246Z,1765169525.246 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-08T04:52:05.246Z,1765169525.246 [keepstation:SurfaceComms:B] Stopped 2025-12-08T04:52:05.246Z,1765169525.246 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-08T04:52:05.247Z,1765169525.247 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-08T04:52:05.247Z,1765169525.247 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-08T04:52:05.247Z,1765169525.247 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-08T04:52:05.247Z,1765169525.247 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T04:52:05.247Z,1765169525.247 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-08T04:52:05.664Z,1765169525.664 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-08T04:52:05.666Z,1765169525.666 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-08T04:52:05.666Z,1765169525.666 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-08T04:52:05.666Z,1765169525.666 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-08T04:52:05.667Z,1765169525.667 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T04:52:05.667Z,1765169525.667 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-08T04:52:05.667Z,1765169525.667 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-08T04:52:05.667Z,1765169525.667 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-08T04:52:05.667Z,1765169525.667 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-08T04:52:06.038Z,1765169526.038 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-08T04:52:06.038Z,1765169526.038 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-08T04:52:06.039Z,1765169526.039 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-08T04:52:06.039Z,1765169526.039 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-08T04:52:06.039Z,1765169526.039 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-08T04:52:06.039Z,1765169526.039 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-08T04:52:06.462Z,1765169526.462 [keepstation:SurfaceComms:setStation] Stopped 2025-12-08T04:52:06.462Z,1765169526.462 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-08T04:52:06.462Z,1765169526.462 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-08T04:52:06.462Z,1765169526.462 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-08T04:52:06.462Z,1765169526.462 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-08T04:52:06.462Z,1765169526.462 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-08T04:52:06.862Z,1765169526.862 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-08T04:52:06.862Z,1765169526.862 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-08T04:52:06.862Z,1765169526.862 [keepstation:SurfaceComms:setSink] Stopped 2025-12-08T04:52:06.862Z,1765169526.862 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-08T04:52:06.863Z,1765169526.863 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-08T04:52:06.863Z,1765169526.863 [keepstation:SurfaceComms] Stopped 2025-12-08T04:52:06.863Z,1765169526.863 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-08T04:52:07.269Z,1765169527.269 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-08T04:52:07.269Z,1765169527.269 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-08T04:52:07.269Z,1765169527.269 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-08T04:52:07.269Z,1765169527.269 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-08T04:52:07.269Z,1765169527.269 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-08T04:52:07.269Z,1765169527.269 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-08T04:52:07.269Z,1765169527.269 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-08T04:52:07.269Z,1765169527.269 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-08T04:52:07.269Z,1765169527.269 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-08T04:52:07.270Z,1765169527.270 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T04:52:07.270Z,1765169527.270 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-08T04:52:07.270Z,1765169527.270 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-08T04:52:07.270Z,1765169527.270 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-08T04:52:07.270Z,1765169527.270 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-08T04:52:07.270Z,1765169527.270 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-08T04:52:07.270Z,1765169527.270 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-08T04:52:07.271Z,1765169527.271 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-08T04:52:07.271Z,1765169527.271 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-08T04:52:07.271Z,1765169527.271 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-08T04:52:34.778Z,1765169554.778 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-08T04:52:34.852Z,1765169554.852 [NAL9602](ERROR): received: +CSQ:0 OK386, 2, 0, 0, 0 OK 2025-12-08T04:54:03.456Z,1765169643.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T04:54:28.808Z,1765169668.808 [Radio_Surface](INFO): Powering down 2025-12-08T04:54:34.580Z,1765169674.580 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T04:54:35.584Z,1765169675.584 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-08T04:56:06.492Z,1765169766.492 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-08T04:56:06.492Z,1765169766.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-08T04:56:06.492Z,1765169766.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T04:56:06.492Z,1765169766.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-08T04:56:06.913Z,1765169766.913 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-08T04:56:06.913Z,1765169766.913 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T04:56:06.913Z,1765169766.913 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-08T04:56:06.913Z,1765169766.913 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-08T04:56:06.913Z,1765169766.913 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T04:56:06.913Z,1765169766.913 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-08T04:56:07.321Z,1765169767.321 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-08T04:56:07.321Z,1765169767.321 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T04:56:07.321Z,1765169767.321 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-08T04:56:07.321Z,1765169767.321 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T04:56:07.321Z,1765169767.321 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T04:56:07.321Z,1765169767.321 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-08T04:56:07.322Z,1765169767.322 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T04:56:07.322Z,1765169767.322 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-08T04:56:07.322Z,1765169767.322 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-08T04:56:07.322Z,1765169767.322 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-08T04:56:07.322Z,1765169767.322 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-08T04:56:07.322Z,1765169767.322 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-08T04:56:07.699Z,1765169767.699 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-08T04:56:07.699Z,1765169767.699 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-08T04:56:07.699Z,1765169767.699 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-08T04:56:07.699Z,1765169767.699 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-08T04:56:07.699Z,1765169767.699 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-08T04:56:07.699Z,1765169767.699 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-08T04:56:07.699Z,1765169767.699 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-08T04:56:07.700Z,1765169767.700 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-08T04:56:08.096Z,1765169768.096 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.17 mm (1 active estimators). 2025-12-08T04:56:10.985Z,1765169770.985 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.775299 2025-12-08T04:57:06.266Z,1765169826.266 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-08T04:57:36.982Z,1765169856.982 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-08T04:58:03.243Z,1765169883.243 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T04:58:03.246Z,1765169883.246 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-12-08T05:16:30.631Z,1765170990.631 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-08T06:10:22.257Z,1765174222.257 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T06:22:04.019Z,1765174924.019 [keepstation:SurfaceComms] Running Loop=1 2025-12-08T06:22:04.019Z,1765174924.019 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-08T06:22:04.020Z,1765174924.020 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-08T06:22:04.020Z,1765174924.020 [keepstation:SurfaceComms:A] Stopped 2025-12-08T06:22:04.021Z,1765174924.021 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-08T06:22:04.022Z,1765174924.022 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-08T06:22:04.414Z,1765174924.414 [keepstation:KeepStation:Dive] Stopped 2025-12-08T06:22:04.414Z,1765174924.414 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-08T06:22:04.414Z,1765174924.414 [keepstation:KeepStation:Dive:A] Stopped 2025-12-08T06:22:04.414Z,1765174924.414 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-08T06:22:04.415Z,1765174924.415 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-08T06:22:04.415Z,1765174924.415 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-08T06:22:04.415Z,1765174924.415 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-08T06:22:04.415Z,1765174924.415 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-08T06:22:04.415Z,1765174924.415 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-08T06:22:04.415Z,1765174924.415 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-08T06:22:04.433Z,1765174924.433 [keepstation:NeedComms] Running Loop=1 2025-12-08T06:22:04.433Z,1765174924.433 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-08T06:22:04.433Z,1765174924.433 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-08T06:22:04.433Z,1765174924.433 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-08T06:22:04.434Z,1765174924.434 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-08T06:22:04.434Z,1765174924.434 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-08T06:22:04.435Z,1765174924.435 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-08T06:22:04.435Z,1765174924.435 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-08T06:22:04.435Z,1765174924.435 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-08T06:22:04.436Z,1765174924.436 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-08T06:22:04.440Z,1765174924.440 [keepstation:NeedComms:A] Running Loop=1 2025-12-08T06:22:04.442Z,1765174924.442 [keepstation:NeedComms:A](INFO): last time_fix was: 1765169522.000000 second since 1970/01/01T00:00:00Z 2025-12-08T06:22:04.442Z,1765174924.442 [keepstation:NeedComms:A] Stopped 2025-12-08T06:22:04.442Z,1765174924.442 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-08T06:22:04.442Z,1765174924.442 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-08T06:22:04.442Z,1765174924.442 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-08T06:22:04.821Z,1765174924.821 [keepstation:NeedComms:setActive:A] Stopped 2025-12-08T06:22:04.821Z,1765174924.821 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-08T06:22:05.224Z,1765174925.224 [keepstation:NeedComms:setActive:B] Stopped 2025-12-08T06:22:05.224Z,1765174925.224 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-08T06:22:05.224Z,1765174925.224 [keepstation:NeedComms:setActive] Stopped 2025-12-08T06:22:05.224Z,1765174925.224 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-08T06:23:53.235Z,1765175033.235 [keepstation:NeedComms:D] Running Loop=1 2025-12-08T06:23:53.648Z,1765175033.648 [Radio_Surface](INFO): Powering up 2025-12-08T06:23:59.872Z,1765175039.872 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-08T06:24:26.060Z,1765175066.060 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-08T06:24:26.140Z,1765175066.140 [NAL9602](ERROR): received: +CSQ:0 OK386, 2, 0, 0, 0 OK 2025-12-08T06:24:29.992Z,1765175069.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T06:25:01.116Z,1765175101.116 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T06:25:32.248Z,1765175132.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T06:25:47.670Z,1765175147.670 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T06:25:47.670Z,1765175147.670 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T06:26:03.372Z,1765175163.372 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T06:26:11.828Z,1765175171.828 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002941 2025-12-08T06:29:01.994Z,1765175341.994 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T06:29:01.994Z,1765175341.994 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T06:29:02.405Z,1765175342.405 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-08T06:29:03.615Z,1765175343.615 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,062902.00,A,3631.85234,N,12201.28260,W,0.253,90.06,081225,,,A*45 2025-12-08T06:29:03.617Z,1765175343.617 [NAL9602](INFO): GPS fix at 20251208T062902: (36.530872, -122.021377) 2025-12-08T06:29:03.630Z,1765175343.630 [UniversalFixResidualReporter](INFO): Fix residual: 112.7 %DT, over the last 1039.9 m. Residual distance 1172.5 m at bearing 153.8 degrees. Fix at (36.5309, -122.0214) with 877.7 m made good. 2025-12-08T06:29:03.653Z,1765175343.653 [keepstation:NeedComms:D] Stopped 2025-12-08T06:29:03.653Z,1765175343.653 [keepstation:NeedComms:E] Running Loop=1 2025-12-08T06:29:04.018Z,1765175344.018 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T06:29:04.021Z,1765175344.021 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-12-08T06:29:11.444Z,1765175351.444 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20251208T030336/Courier0024.lzma 2025-12-08T06:29:12.446Z,1765175352.446 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Courier0024.lzma.bak 2025-12-08T06:29:12.447Z,1765175352.447 [DataOverHttps](INFO): SBD MOMSN=26458482 2025-12-08T06:29:28.757Z,1765175368.757 [DataOverHttps](INFO): Sending 597 bytes from file Logs/20251208T030336/Express0025.lzma 2025-12-08T06:29:29.758Z,1765175369.758 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0025.lzma.bak 2025-12-08T06:29:29.758Z,1765175369.758 [DataOverHttps](INFO): SBD MOMSN=26458493 2025-12-08T06:29:31.103Z,1765175371.103 [keepstation:NeedComms:E] Stopped 2025-12-08T06:29:31.103Z,1765175371.103 [keepstation:NeedComms:F] Running Loop=1 2025-12-08T06:29:33.103Z,1765175373.103 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,062931.00,A,3631.85448,N,12201.28282,W,1.400,93.96,081225,,,D*4A 2025-12-08T06:29:33.105Z,1765175373.105 [NAL9602](INFO): GPS fix at 20251208T062931: (36.530908, -122.021380) 2025-12-08T06:29:33.147Z,1765175373.147 [keepstation:NeedComms:F] Stopped 2025-12-08T06:29:33.147Z,1765175373.147 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-08T06:29:33.147Z,1765175373.147 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-08T06:29:33.147Z,1765175373.147 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-08T06:29:33.556Z,1765175373.556 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-08T06:29:33.556Z,1765175373.556 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-08T06:29:33.556Z,1765175373.556 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-08T06:29:33.556Z,1765175373.556 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-08T06:29:33.556Z,1765175373.556 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-08T06:29:33.556Z,1765175373.556 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-08T06:29:33.556Z,1765175373.556 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-08T06:29:33.962Z,1765175373.962 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-08T06:29:33.962Z,1765175373.962 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-08T06:29:33.962Z,1765175373.962 [keepstation:NeedComms:setInactive] Stopped 2025-12-08T06:29:33.962Z,1765175373.962 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-08T06:29:33.963Z,1765175373.963 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-08T06:29:33.963Z,1765175373.963 [keepstation:NeedComms] Stopped 2025-12-08T06:29:33.963Z,1765175373.963 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-08T06:29:33.963Z,1765175373.963 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-08T06:29:33.963Z,1765175373.963 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-08T06:29:34.330Z,1765175374.330 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-08T06:29:34.330Z,1765175374.330 [keepstation:SurfaceComms:B] Stopped 2025-12-08T06:29:34.330Z,1765175374.330 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-08T06:29:34.330Z,1765175374.330 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-08T06:29:34.330Z,1765175374.330 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-08T06:29:34.330Z,1765175374.330 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-08T06:29:34.330Z,1765175374.330 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T06:29:34.330Z,1765175374.330 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-08T06:29:34.761Z,1765175374.761 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-08T06:29:34.761Z,1765175374.761 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-08T06:29:34.761Z,1765175374.761 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-08T06:29:34.761Z,1765175374.761 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T06:29:34.761Z,1765175374.761 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-08T06:29:34.762Z,1765175374.762 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-08T06:29:34.762Z,1765175374.762 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-08T06:29:34.762Z,1765175374.762 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-08T06:29:35.159Z,1765175375.159 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-08T06:29:35.159Z,1765175375.159 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-08T06:29:35.159Z,1765175375.159 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-08T06:29:35.159Z,1765175375.159 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-08T06:29:35.159Z,1765175375.159 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-08T06:29:35.159Z,1765175375.159 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-08T06:29:35.545Z,1765175375.545 [keepstation:SurfaceComms:setStation] Stopped 2025-12-08T06:29:35.545Z,1765175375.545 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-08T06:29:35.545Z,1765175375.545 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-08T06:29:35.545Z,1765175375.545 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-08T06:29:35.545Z,1765175375.545 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-08T06:29:35.545Z,1765175375.545 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-08T06:29:35.965Z,1765175375.965 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-08T06:29:35.965Z,1765175375.965 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-08T06:29:35.965Z,1765175375.965 [keepstation:SurfaceComms:setSink] Stopped 2025-12-08T06:29:35.965Z,1765175375.965 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-08T06:29:35.965Z,1765175375.965 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-08T06:29:35.965Z,1765175375.965 [keepstation:SurfaceComms] Stopped 2025-12-08T06:29:35.965Z,1765175375.965 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-08T06:29:36.359Z,1765175376.359 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-08T06:29:36.359Z,1765175376.359 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-08T06:29:36.364Z,1765175376.364 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-08T06:29:36.364Z,1765175376.364 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-08T06:29:36.365Z,1765175376.365 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-08T06:29:36.365Z,1765175376.365 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-08T06:29:36.365Z,1765175376.365 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-08T06:29:36.365Z,1765175376.365 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T06:29:36.365Z,1765175376.365 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-08T06:29:36.365Z,1765175376.365 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-08T06:29:36.365Z,1765175376.365 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-08T06:29:36.365Z,1765175376.365 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-08T06:29:36.365Z,1765175376.365 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-08T06:29:36.366Z,1765175376.366 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-08T06:29:36.366Z,1765175376.366 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-08T06:29:36.366Z,1765175376.366 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-08T06:29:36.366Z,1765175376.366 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-08T06:30:05.425Z,1765175405.425 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-08T06:31:02.881Z,1765175462.881 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-12-08T06:31:34.024Z,1765175494.024 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T06:31:52.792Z,1765175512.792 [Radio_Surface](INFO): Powering down 2025-12-08T06:32:05.148Z,1765175525.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T06:32:06.152Z,1765175526.152 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-08T06:33:09.412Z,1765175589.412 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.159241 2025-12-08T06:33:17.742Z,1765175597.742 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-08T06:33:17.742Z,1765175597.742 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-08T06:33:17.742Z,1765175597.742 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T06:33:17.742Z,1765175597.742 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-08T06:33:18.158Z,1765175598.158 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-08T06:33:18.158Z,1765175598.158 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T06:33:18.158Z,1765175598.158 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-08T06:33:18.158Z,1765175598.158 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-08T06:33:18.158Z,1765175598.158 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T06:33:18.158Z,1765175598.158 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-08T06:33:18.546Z,1765175598.546 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-08T06:33:18.546Z,1765175598.546 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T06:33:18.546Z,1765175598.546 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-08T06:33:18.547Z,1765175598.547 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T06:33:18.547Z,1765175598.547 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T06:33:18.547Z,1765175598.547 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-08T06:33:18.547Z,1765175598.547 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T06:33:18.547Z,1765175598.547 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-08T06:33:18.547Z,1765175598.547 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-08T06:33:18.548Z,1765175598.548 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-08T06:33:18.548Z,1765175598.548 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-08T06:33:18.548Z,1765175598.548 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-08T06:33:18.952Z,1765175598.952 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-08T06:33:18.952Z,1765175598.952 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-08T06:33:18.952Z,1765175598.952 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-08T06:33:18.952Z,1765175598.952 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-08T06:33:18.952Z,1765175598.952 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-08T06:33:18.952Z,1765175598.952 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-08T06:33:18.953Z,1765175598.953 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-08T06:33:18.953Z,1765175598.953 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-08T06:58:22.675Z,1765177102.675 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-08T07:41:23.024Z,1765179683.024 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T07:59:33.429Z,1765180773.429 [keepstation:SurfaceComms] Running Loop=1 2025-12-08T07:59:33.430Z,1765180773.430 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-08T07:59:33.430Z,1765180773.430 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-08T07:59:33.430Z,1765180773.430 [keepstation:SurfaceComms:A] Stopped 2025-12-08T07:59:33.430Z,1765180773.430 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-08T07:59:33.430Z,1765180773.430 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-08T07:59:33.836Z,1765180773.836 [keepstation:KeepStation:Dive] Stopped 2025-12-08T07:59:33.837Z,1765180773.837 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-08T07:59:33.837Z,1765180773.837 [keepstation:KeepStation:Dive:A] Stopped 2025-12-08T07:59:33.837Z,1765180773.837 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-08T07:59:33.837Z,1765180773.837 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-08T07:59:33.837Z,1765180773.837 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-08T07:59:33.837Z,1765180773.837 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-08T07:59:33.837Z,1765180773.837 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-08T07:59:33.837Z,1765180773.837 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-08T07:59:33.838Z,1765180773.838 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-08T07:59:33.839Z,1765180773.839 [keepstation:NeedComms] Running Loop=1 2025-12-08T07:59:33.839Z,1765180773.839 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-08T07:59:33.839Z,1765180773.839 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-08T07:59:33.839Z,1765180773.839 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-08T07:59:33.840Z,1765180773.840 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-08T07:59:33.841Z,1765180773.841 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-08T07:59:33.841Z,1765180773.841 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-08T07:59:33.841Z,1765180773.841 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-08T07:59:33.842Z,1765180773.842 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-08T07:59:33.842Z,1765180773.842 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-08T07:59:33.842Z,1765180773.842 [keepstation:NeedComms:A] Running Loop=1 2025-12-08T07:59:33.844Z,1765180773.844 [keepstation:NeedComms:A](INFO): last time_fix was: 1765175371.000000 second since 1970/01/01T00:00:00Z 2025-12-08T07:59:33.844Z,1765180773.844 [keepstation:NeedComms:A] Stopped 2025-12-08T07:59:33.844Z,1765180773.844 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-08T07:59:33.844Z,1765180773.844 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-08T07:59:33.844Z,1765180773.844 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-08T07:59:34.255Z,1765180774.255 [keepstation:NeedComms:setActive:A] Stopped 2025-12-08T07:59:34.255Z,1765180774.255 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-08T07:59:34.645Z,1765180774.645 [keepstation:NeedComms:setActive:B] Stopped 2025-12-08T07:59:34.645Z,1765180774.645 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-08T07:59:34.645Z,1765180774.645 [keepstation:NeedComms:setActive] Stopped 2025-12-08T07:59:34.645Z,1765180774.645 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-08T08:00:04.870Z,1765180804.870 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T08:00:04.873Z,1765180804.873 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-12-08T08:01:09.874Z,1765180869.874 [keepstation:NeedComms:D] Running Loop=1 2025-12-08T08:01:10.260Z,1765180870.260 [Radio_Surface](INFO): Powering up 2025-12-08T08:01:16.425Z,1765180876.425 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-08T08:01:46.544Z,1765180906.544 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T08:02:17.668Z,1765180937.668 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T08:02:48.792Z,1765180968.792 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T08:03:19.916Z,1765180999.916 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T08:03:28.321Z,1765181008.321 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002911 2025-12-08T08:06:15.369Z,1765181175.369 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T08:06:15.369Z,1765181175.369 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T08:06:15.767Z,1765181175.767 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-08T08:06:16.982Z,1765181176.982 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,080615.00,A,3632.19991,N,12200.98293,W,2.371,313.39,081225,,,A*73 2025-12-08T08:06:16.984Z,1765181176.984 [NAL9602](INFO): GPS fix at 20251208T080615: (36.536665, -122.016382) 2025-12-08T08:06:16.995Z,1765181176.995 [UniversalFixResidualReporter](INFO): Fix residual: 134.1 %DT, over the last 1252.3 m. Residual distance 1678.7 m at bearing -178.5 degrees. Fix at (36.5367, -122.0164) with 780.5 m made good. 2025-12-08T08:06:17.019Z,1765181177.019 [keepstation:NeedComms:D] Stopped 2025-12-08T08:06:17.019Z,1765181177.019 [keepstation:NeedComms:E] Running Loop=1 2025-12-08T08:06:24.631Z,1765181184.631 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20251208T030336/Courier0027.lzma 2025-12-08T08:06:26.638Z,1765181186.638 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Courier0027.lzma.bak 2025-12-08T08:06:26.638Z,1765181186.638 [DataOverHttps](INFO): SBD MOMSN=26459265 2025-12-08T08:06:42.809Z,1765181202.809 [DataOverHttps](INFO): Sending 586 bytes from file Logs/20251208T030336/Express0028.lzma 2025-12-08T08:06:43.810Z,1765181203.810 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0028.lzma.bak 2025-12-08T08:06:43.810Z,1765181203.810 [DataOverHttps](INFO): SBD MOMSN=26459268 2025-12-08T08:06:45.278Z,1765181205.278 [keepstation:NeedComms:E] Stopped 2025-12-08T08:06:45.278Z,1765181205.278 [keepstation:NeedComms:F] Running Loop=1 2025-12-08T08:06:47.283Z,1765181207.283 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,080645.00,A,3632.20204,N,12200.98423,W,1.283,307.32,081225,,,A*77 2025-12-08T08:06:47.286Z,1765181207.286 [NAL9602](INFO): GPS fix at 20251208T080645: (36.536701, -122.016404) 2025-12-08T08:06:47.315Z,1765181207.315 [keepstation:NeedComms:F] Stopped 2025-12-08T08:06:47.315Z,1765181207.315 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-08T08:06:47.315Z,1765181207.315 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-08T08:06:47.320Z,1765181207.320 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-08T08:06:47.722Z,1765181207.722 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-08T08:06:47.722Z,1765181207.722 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-08T08:06:47.722Z,1765181207.722 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-08T08:06:47.723Z,1765181207.723 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-08T08:06:47.723Z,1765181207.723 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-08T08:06:47.723Z,1765181207.723 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-08T08:06:47.723Z,1765181207.723 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-08T08:06:48.103Z,1765181208.103 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-08T08:06:48.103Z,1765181208.103 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-08T08:06:48.103Z,1765181208.103 [keepstation:NeedComms:setInactive] Stopped 2025-12-08T08:06:48.103Z,1765181208.103 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-08T08:06:48.185Z,1765181208.185 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-08T08:06:48.185Z,1765181208.185 [keepstation:NeedComms] Stopped 2025-12-08T08:06:48.185Z,1765181208.185 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-08T08:06:48.185Z,1765181208.185 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-08T08:06:48.185Z,1765181208.185 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-08T08:06:48.548Z,1765181208.548 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-08T08:06:48.549Z,1765181208.549 [keepstation:SurfaceComms:B] Stopped 2025-12-08T08:06:48.549Z,1765181208.549 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-08T08:06:48.549Z,1765181208.549 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-08T08:06:48.549Z,1765181208.549 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-08T08:06:48.549Z,1765181208.549 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-08T08:06:48.549Z,1765181208.549 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T08:06:48.549Z,1765181208.549 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-08T08:06:48.944Z,1765181208.944 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-08T08:06:48.944Z,1765181208.944 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-08T08:06:48.944Z,1765181208.944 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-08T08:06:48.945Z,1765181208.945 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T08:06:48.945Z,1765181208.945 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-08T08:06:48.945Z,1765181208.945 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-08T08:06:48.945Z,1765181208.945 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-08T08:06:48.945Z,1765181208.945 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-08T08:06:49.333Z,1765181209.333 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-08T08:06:49.334Z,1765181209.334 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-08T08:06:49.334Z,1765181209.334 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-08T08:06:49.334Z,1765181209.334 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-08T08:06:49.334Z,1765181209.334 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-08T08:06:49.334Z,1765181209.334 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-08T08:06:49.767Z,1765181209.767 [keepstation:SurfaceComms:setStation] Stopped 2025-12-08T08:06:49.767Z,1765181209.767 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-08T08:06:49.767Z,1765181209.767 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-08T08:06:49.767Z,1765181209.767 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-08T08:06:49.772Z,1765181209.772 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-08T08:06:49.772Z,1765181209.772 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-08T08:06:50.145Z,1765181210.145 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-08T08:06:50.145Z,1765181210.145 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-08T08:06:50.145Z,1765181210.145 [keepstation:SurfaceComms:setSink] Stopped 2025-12-08T08:06:50.145Z,1765181210.145 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-08T08:06:50.145Z,1765181210.145 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-08T08:06:50.145Z,1765181210.145 [keepstation:SurfaceComms] Stopped 2025-12-08T08:06:50.145Z,1765181210.145 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-08T08:06:50.553Z,1765181210.553 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-08T08:06:50.553Z,1765181210.553 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-08T08:06:50.553Z,1765181210.553 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-08T08:06:50.553Z,1765181210.553 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-08T08:06:50.554Z,1765181210.554 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-08T08:06:50.554Z,1765181210.554 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-08T08:06:50.554Z,1765181210.554 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-08T08:06:50.554Z,1765181210.554 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T08:06:50.554Z,1765181210.554 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-08T08:06:50.554Z,1765181210.554 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-08T08:06:50.554Z,1765181210.554 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-08T08:06:50.554Z,1765181210.554 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-08T08:06:50.554Z,1765181210.554 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-08T08:06:50.555Z,1765181210.555 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-08T08:06:50.555Z,1765181210.555 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-08T08:06:50.555Z,1765181210.555 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-08T08:06:50.555Z,1765181210.555 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-08T08:07:19.197Z,1765181239.197 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-08T08:08:46.984Z,1765181326.984 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T08:09:18.108Z,1765181358.108 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T08:09:26.776Z,1765181366.776 [Radio_Surface](INFO): Powering down 2025-12-08T08:09:49.233Z,1765181389.233 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T08:09:50.236Z,1765181390.236 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-08T08:10:40.001Z,1765181440.001 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-08T08:10:40.001Z,1765181440.001 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-08T08:10:40.001Z,1765181440.001 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T08:10:40.001Z,1765181440.001 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-08T08:10:40.416Z,1765181440.416 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-08T08:10:40.416Z,1765181440.416 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T08:10:40.416Z,1765181440.416 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-08T08:10:40.416Z,1765181440.416 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-08T08:10:40.416Z,1765181440.416 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T08:10:40.416Z,1765181440.416 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-08T08:10:40.807Z,1765181440.807 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-08T08:10:40.807Z,1765181440.807 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T08:10:40.807Z,1765181440.807 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-08T08:10:40.808Z,1765181440.808 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T08:10:40.808Z,1765181440.808 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T08:10:40.808Z,1765181440.808 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-08T08:10:40.809Z,1765181440.809 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T08:10:40.809Z,1765181440.809 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-08T08:10:40.809Z,1765181440.809 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-08T08:10:40.809Z,1765181440.809 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-08T08:10:40.809Z,1765181440.809 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-08T08:10:40.809Z,1765181440.809 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-08T08:10:41.219Z,1765181441.219 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-08T08:10:41.219Z,1765181441.219 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-08T08:10:41.219Z,1765181441.219 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-08T08:10:41.219Z,1765181441.219 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-08T08:10:41.219Z,1765181441.219 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-08T08:10:41.219Z,1765181441.219 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-08T08:10:41.219Z,1765181441.219 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-08T08:10:41.220Z,1765181441.220 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-08T08:10:54.517Z,1765181454.517 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.756378 2025-12-08T08:16:16.154Z,1765181776.154 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T08:26:16.266Z,1765182376.266 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T08:36:16.634Z,1765182976.634 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T08:46:16.979Z,1765183576.979 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T08:46:18.199Z,1765183578.199 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-08T08:56:01.340Z,1765184161.340 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-12-08T08:56:05.781Z,1765184165.781 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.001914 CHAN A1 (24V): 0.004909 CHAN A2 (12V): -0.006846 CHAN A3 (5V): -0.043009 CHAN B0 (3.3V): -0.003801 CHAN B1 (3.15aV): -0.002135 CHAN B2 (3.15bV): -0.001674 CHAN B3 (GND): -0.004686 OPEN: 0.012004 Full Scale: +/- 1 mA 2025-12-08T08:56:17.343Z,1765184177.343 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T09:06:17.682Z,1765184777.682 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T09:07:00.917Z,1765184820.917 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-08T09:12:24.150Z,1765185144.150 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T09:14:45.551Z,1765185285.551 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-12-08T09:16:18.063Z,1765185378.063 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T09:20:28.947Z,1765185628.947 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-08T09:22:03.890Z,1765185723.890 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T09:26:18.413Z,1765185978.413 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T09:27:43.657Z,1765186063.657 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-08T09:31:06.051Z,1765186266.051 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T09:31:06.059Z,1765186266.059 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-08T09:36:18.756Z,1765186578.756 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T09:36:47.466Z,1765186607.466 [keepstation:SurfaceComms] Running Loop=1 2025-12-08T09:36:47.466Z,1765186607.466 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-08T09:36:47.467Z,1765186607.467 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-08T09:36:47.467Z,1765186607.467 [keepstation:SurfaceComms:A] Stopped 2025-12-08T09:36:47.467Z,1765186607.467 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-08T09:36:47.467Z,1765186607.467 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-08T09:36:47.855Z,1765186607.855 [keepstation:KeepStation:Dive] Stopped 2025-12-08T09:36:47.855Z,1765186607.855 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-08T09:36:47.855Z,1765186607.855 [keepstation:KeepStation:Dive:A] Stopped 2025-12-08T09:36:47.855Z,1765186607.855 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-08T09:36:47.855Z,1765186607.855 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-08T09:36:47.855Z,1765186607.855 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-08T09:36:47.856Z,1765186607.856 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-08T09:36:47.856Z,1765186607.856 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-08T09:36:47.856Z,1765186607.856 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-08T09:36:47.856Z,1765186607.856 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-08T09:36:47.858Z,1765186607.858 [keepstation:NeedComms] Running Loop=1 2025-12-08T09:36:47.858Z,1765186607.858 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-08T09:36:47.858Z,1765186607.858 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-08T09:36:47.858Z,1765186607.858 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-08T09:36:47.858Z,1765186607.858 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-08T09:36:47.859Z,1765186607.859 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-08T09:36:47.859Z,1765186607.859 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-08T09:36:47.859Z,1765186607.859 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-08T09:36:47.860Z,1765186607.860 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-08T09:36:47.860Z,1765186607.860 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-08T09:36:47.860Z,1765186607.860 [keepstation:NeedComms:A] Running Loop=1 2025-12-08T09:36:47.862Z,1765186607.862 [keepstation:NeedComms:A](INFO): last time_fix was: 1765181205.000000 second since 1970/01/01T00:00:00Z 2025-12-08T09:36:47.862Z,1765186607.862 [keepstation:NeedComms:A] Stopped 2025-12-08T09:36:47.862Z,1765186607.862 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-08T09:36:47.862Z,1765186607.862 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-08T09:36:47.862Z,1765186607.862 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-08T09:36:48.260Z,1765186608.260 [keepstation:NeedComms:setActive:A] Stopped 2025-12-08T09:36:48.261Z,1765186608.261 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-08T09:36:48.666Z,1765186608.666 [keepstation:NeedComms:setActive:B] Stopped 2025-12-08T09:36:48.666Z,1765186608.666 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-08T09:36:48.667Z,1765186608.667 [keepstation:NeedComms:setActive] Stopped 2025-12-08T09:36:48.667Z,1765186608.667 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-08T09:38:23.367Z,1765186703.367 [keepstation:NeedComms:D] Running Loop=1 2025-12-08T09:38:23.792Z,1765186703.792 [Radio_Surface](INFO): Powering up 2025-12-08T09:38:29.372Z,1765186709.372 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-08T09:38:59.522Z,1765186739.522 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T09:39:30.665Z,1765186770.665 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T09:40:01.788Z,1765186801.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T09:40:32.932Z,1765186832.932 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T09:40:42.444Z,1765186842.444 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.010945 2025-12-08T09:42:53.310Z,1765186973.310 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T09:42:53.310Z,1765186973.310 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T09:43:30.479Z,1765187010.479 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T09:43:30.480Z,1765187010.480 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T09:43:30.878Z,1765187010.878 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-08T09:43:32.095Z,1765187012.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,094330.00,A,3631.97268,N,12201.15778,W,0.661,253.48,081225,,,A*7D 2025-12-08T09:43:32.097Z,1765187012.097 [NAL9602](INFO): GPS fix at 20251208T094330: (36.532878, -122.019296) 2025-12-08T09:43:32.109Z,1765187012.109 [UniversalFixResidualReporter](INFO): Fix residual: 71.8 %DT, over the last 1752.5 m. Residual distance 1257.9 m at bearing 169.8 degrees. Fix at (36.5329, -122.0193) with 497.5 m made good. 2025-12-08T09:43:32.133Z,1765187012.133 [keepstation:NeedComms:D] Stopped 2025-12-08T09:43:32.134Z,1765187012.134 [keepstation:NeedComms:E] Running Loop=1 2025-12-08T09:43:39.987Z,1765187019.987 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20251208T030336/Courier0030.lzma 2025-12-08T09:43:40.990Z,1765187020.990 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Courier0030.lzma.bak 2025-12-08T09:43:40.990Z,1765187020.990 [DataOverHttps](INFO): SBD MOMSN=26459798 2025-12-08T09:43:57.042Z,1765187037.042 [DataOverHttps](INFO): Sending 928 bytes from file Logs/20251208T030336/Express0031.lzma 2025-12-08T09:43:58.042Z,1765187038.042 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0031.lzma.bak 2025-12-08T09:43:58.042Z,1765187038.042 [DataOverHttps](INFO): SBD MOMSN=26459801 2025-12-08T09:43:59.579Z,1765187039.579 [keepstation:NeedComms:E] Stopped 2025-12-08T09:43:59.579Z,1765187039.579 [keepstation:NeedComms:F] Running Loop=1 2025-12-08T09:44:01.730Z,1765187041.730 [NAL9602](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2025-12-08T09:44:01.731Z,1765187041.731 [NAL9602] Hardware Fault, FailCount= 1 2025-12-08T09:44:01.731Z,1765187041.731 [NAL9602](ERROR): Hardware Fault 2025-12-08T09:44:01.787Z,1765187041.787 [CBIT](ERROR): Hardware Fault in component: NAL9602 2025-12-08T09:44:02.001Z,1765187042.001 [NAL9602](INFO): Powering down 2025-12-08T09:44:02.877Z,1765187042.877 [CBIT](INFO): Clearing failed state for component NAL9602 2025-12-08T09:44:02.877Z,1765187042.877 [NAL9602] No Fault, FailCount= 1 2025-12-08T09:44:32.306Z,1765187072.306 [NAL9602](INFO): Powering up NAL9602 2025-12-08T09:44:43.234Z,1765187083.234 [NAL9602](INFO): NAL9602 initialized 2025-12-08T09:45:10.710Z,1765187110.710 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-12-08T09:45:37.795Z,1765187137.795 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,094533.00,A,3631.97477,N,12201.17731,W,0.233,295.03,081225,,,A*79 2025-12-08T09:45:37.797Z,1765187137.797 [NAL9602](INFO): GPS fix at 20251208T094533: (36.532913, -122.019622) 2025-12-08T09:45:37.811Z,1765187137.811 [keepstation:NeedComms:F] Stopped 2025-12-08T09:45:37.811Z,1765187137.811 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-08T09:45:37.811Z,1765187137.811 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-08T09:45:37.811Z,1765187137.811 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-08T09:45:38.218Z,1765187138.218 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-08T09:45:38.218Z,1765187138.218 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-08T09:45:38.218Z,1765187138.218 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-08T09:45:38.218Z,1765187138.218 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-08T09:45:38.219Z,1765187138.219 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-08T09:45:38.219Z,1765187138.219 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-08T09:45:38.219Z,1765187138.219 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-08T09:45:38.590Z,1765187138.590 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-08T09:45:38.590Z,1765187138.590 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-08T09:45:38.590Z,1765187138.590 [keepstation:NeedComms:setInactive] Stopped 2025-12-08T09:45:38.590Z,1765187138.590 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-08T09:45:38.591Z,1765187138.591 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-08T09:45:38.591Z,1765187138.591 [keepstation:NeedComms] Stopped 2025-12-08T09:45:38.591Z,1765187138.591 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-08T09:45:38.591Z,1765187138.591 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-08T09:45:38.591Z,1765187138.591 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-08T09:45:39.001Z,1765187139.001 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-08T09:45:39.001Z,1765187139.001 [keepstation:SurfaceComms:B] Stopped 2025-12-08T09:45:39.001Z,1765187139.001 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-08T09:45:39.001Z,1765187139.001 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-08T09:45:39.001Z,1765187139.001 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-08T09:45:39.001Z,1765187139.001 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-08T09:45:39.002Z,1765187139.002 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T09:45:39.002Z,1765187139.002 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-08T09:45:39.411Z,1765187139.411 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-08T09:45:39.412Z,1765187139.412 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-08T09:45:39.412Z,1765187139.412 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-08T09:45:39.412Z,1765187139.412 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T09:45:39.412Z,1765187139.412 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-08T09:45:39.412Z,1765187139.412 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-08T09:45:39.412Z,1765187139.412 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-08T09:45:39.412Z,1765187139.412 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-08T09:45:39.804Z,1765187139.804 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-08T09:45:39.804Z,1765187139.804 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-08T09:45:39.804Z,1765187139.804 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-08T09:45:39.804Z,1765187139.804 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-08T09:45:39.804Z,1765187139.804 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-08T09:45:39.804Z,1765187139.804 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-08T09:45:40.203Z,1765187140.203 [keepstation:SurfaceComms:setStation] Stopped 2025-12-08T09:45:40.203Z,1765187140.203 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-08T09:45:40.203Z,1765187140.203 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-08T09:45:40.203Z,1765187140.203 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-08T09:45:40.203Z,1765187140.203 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-08T09:45:40.203Z,1765187140.203 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-08T09:45:40.610Z,1765187140.610 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-08T09:45:40.611Z,1765187140.611 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-08T09:45:40.611Z,1765187140.611 [keepstation:SurfaceComms:setSink] Stopped 2025-12-08T09:45:40.611Z,1765187140.611 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-08T09:45:40.611Z,1765187140.611 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-08T09:45:40.611Z,1765187140.611 [keepstation:SurfaceComms] Stopped 2025-12-08T09:45:40.611Z,1765187140.611 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-08T09:45:41.018Z,1765187141.018 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-08T09:45:41.018Z,1765187141.018 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-08T09:45:41.018Z,1765187141.018 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-08T09:45:41.018Z,1765187141.018 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-08T09:45:41.019Z,1765187141.019 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-08T09:45:41.019Z,1765187141.019 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-08T09:45:41.019Z,1765187141.019 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-08T09:45:41.019Z,1765187141.019 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T09:45:41.019Z,1765187141.019 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-08T09:45:41.019Z,1765187141.019 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-08T09:45:41.020Z,1765187141.020 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-08T09:45:41.020Z,1765187141.020 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-08T09:45:41.020Z,1765187141.020 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-08T09:45:41.020Z,1765187141.020 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-08T09:45:41.021Z,1765187141.021 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-08T09:45:41.021Z,1765187141.021 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-08T09:45:41.021Z,1765187141.021 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-08T09:46:09.823Z,1765187169.823 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-08T09:46:19.080Z,1765187179.080 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T09:47:33.853Z,1765187253.853 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T09:48:04.980Z,1765187284.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T09:48:05.968Z,1765187285.968 [Radio_Surface](INFO): Powering down 2025-12-08T09:48:05.984Z,1765187285.984 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-08T09:49:38.673Z,1765187378.673 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-08T09:49:38.674Z,1765187378.674 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-08T09:49:38.674Z,1765187378.674 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T09:49:38.674Z,1765187378.674 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-08T09:49:39.118Z,1765187379.118 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-08T09:49:39.118Z,1765187379.118 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T09:49:39.118Z,1765187379.118 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-08T09:49:39.119Z,1765187379.119 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-08T09:49:39.119Z,1765187379.119 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T09:49:39.119Z,1765187379.119 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-08T09:49:39.477Z,1765187379.477 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-08T09:49:39.477Z,1765187379.477 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T09:49:39.478Z,1765187379.478 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-08T09:49:39.478Z,1765187379.478 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T09:49:39.478Z,1765187379.478 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T09:49:39.478Z,1765187379.478 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-08T09:49:39.478Z,1765187379.478 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T09:49:39.478Z,1765187379.478 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-08T09:49:39.479Z,1765187379.479 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-08T09:49:39.479Z,1765187379.479 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-08T09:49:39.479Z,1765187379.479 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-08T09:49:39.479Z,1765187379.479 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-08T09:49:39.896Z,1765187379.896 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-08T09:49:39.896Z,1765187379.896 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-08T09:49:39.896Z,1765187379.896 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-08T09:49:39.896Z,1765187379.896 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-08T09:49:39.896Z,1765187379.896 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-08T09:49:39.896Z,1765187379.896 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-08T09:49:39.896Z,1765187379.896 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-08T09:49:39.897Z,1765187379.897 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-08T09:49:41.376Z,1765187381.376 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747192 2025-12-08T09:56:19.118Z,1765187779.118 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T10:02:56.249Z,1765188176.249 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T10:03:02.729Z,1765188182.729 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T10:03:08.780Z,1765188188.780 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T10:06:19.467Z,1765188379.467 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T10:12:07.742Z,1765188727.742 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T10:12:07.755Z,1765188727.755 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-08T10:16:19.849Z,1765188979.849 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T10:17:09.953Z,1765189029.953 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-08T10:17:10.347Z,1765189030.347 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.17 mm. 2025-12-08T10:26:20.187Z,1765189580.187 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T10:34:24.182Z,1765190064.182 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T10:36:20.531Z,1765190180.531 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T10:46:20.646Z,1765190780.646 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T10:53:09.891Z,1765191189.891 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T10:53:09.895Z,1765191189.895 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-08T10:56:20.997Z,1765191380.997 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T11:06:21.355Z,1765191981.355 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T11:07:25.573Z,1765192045.573 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-08T11:15:38.072Z,1765192538.072 [keepstation:SurfaceComms] Running Loop=1 2025-12-08T11:15:38.072Z,1765192538.072 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-08T11:15:38.072Z,1765192538.072 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-08T11:15:38.073Z,1765192538.073 [keepstation:SurfaceComms:A] Stopped 2025-12-08T11:15:38.073Z,1765192538.073 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-08T11:15:38.073Z,1765192538.073 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-08T11:15:38.474Z,1765192538.474 [keepstation:KeepStation:Dive] Stopped 2025-12-08T11:15:38.474Z,1765192538.474 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-08T11:15:38.474Z,1765192538.474 [keepstation:KeepStation:Dive:A] Stopped 2025-12-08T11:15:38.474Z,1765192538.474 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-08T11:15:38.474Z,1765192538.474 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-08T11:15:38.474Z,1765192538.474 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-08T11:15:38.475Z,1765192538.475 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-08T11:15:38.475Z,1765192538.475 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-08T11:15:38.475Z,1765192538.475 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-08T11:15:38.475Z,1765192538.475 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-08T11:15:38.477Z,1765192538.477 [keepstation:NeedComms] Running Loop=1 2025-12-08T11:15:38.477Z,1765192538.477 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-08T11:15:38.477Z,1765192538.477 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-08T11:15:38.477Z,1765192538.477 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-08T11:15:38.477Z,1765192538.477 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-08T11:15:38.478Z,1765192538.478 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-08T11:15:38.478Z,1765192538.478 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-08T11:15:38.478Z,1765192538.478 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-08T11:15:38.479Z,1765192538.479 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-08T11:15:38.479Z,1765192538.479 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-08T11:15:38.479Z,1765192538.479 [keepstation:NeedComms:A] Running Loop=1 2025-12-08T11:15:38.481Z,1765192538.481 [keepstation:NeedComms:A](INFO): last time_fix was: 1765187133.000000 second since 1970/01/01T00:00:00Z 2025-12-08T11:15:38.481Z,1765192538.481 [keepstation:NeedComms:A] Stopped 2025-12-08T11:15:38.481Z,1765192538.481 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-08T11:15:38.481Z,1765192538.481 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-08T11:15:38.481Z,1765192538.481 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-08T11:15:38.887Z,1765192538.887 [keepstation:NeedComms:setActive:A] Stopped 2025-12-08T11:15:38.887Z,1765192538.887 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-08T11:15:39.295Z,1765192539.295 [keepstation:NeedComms:setActive:B] Stopped 2025-12-08T11:15:39.295Z,1765192539.295 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-08T11:15:39.295Z,1765192539.295 [keepstation:NeedComms:setActive] Stopped 2025-12-08T11:15:39.295Z,1765192539.295 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-08T11:16:21.724Z,1765192581.724 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T11:17:32.858Z,1765192652.858 [keepstation:NeedComms:D] Running Loop=1 2025-12-08T11:17:33.376Z,1765192653.376 [Radio_Surface](INFO): Powering up 2025-12-08T11:17:39.328Z,1765192659.328 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-08T11:18:09.452Z,1765192689.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T11:18:40.576Z,1765192720.576 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T11:19:11.708Z,1765192751.708 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T11:19:42.832Z,1765192782.832 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T11:19:51.512Z,1765192791.512 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002854 2025-12-08T11:21:03.530Z,1765192863.530 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T11:21:03.531Z,1765192863.531 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T11:21:20.478Z,1765192880.478 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T11:21:20.479Z,1765192880.479 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T11:21:29.367Z,1765192889.367 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-08T11:21:37.846Z,1765192897.846 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T11:21:37.846Z,1765192897.846 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T11:21:58.454Z,1765192918.454 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T11:21:58.454Z,1765192918.454 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T11:22:37.638Z,1765192957.638 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T11:22:37.639Z,1765192957.639 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T11:22:38.047Z,1765192958.047 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-08T11:22:39.257Z,1765192959.257 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112237.00,A,3631.81219,N,12201.59662,W,0.622,216.20,081225,,,A*7F 2025-12-08T11:22:39.260Z,1765192959.260 [NAL9602](INFO): GPS fix at 20251208T112237: (36.530203, -122.026610) 2025-12-08T11:22:39.272Z,1765192959.272 [UniversalFixResidualReporter](INFO): Fix residual: 95.2 %DT, over the last 1381.0 m. Residual distance 1315.1 m at bearing 138.8 degrees. Fix at (36.5302, -122.0266) with 693.3 m made good. 2025-12-08T11:22:39.275Z,1765192959.275 [keepstation:NeedComms:D] Stopped 2025-12-08T11:22:39.276Z,1765192959.276 [keepstation:NeedComms:E] Running Loop=1 2025-12-08T11:22:46.987Z,1765192966.987 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20251208T030336/Courier0033.lzma 2025-12-08T11:22:47.990Z,1765192967.990 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Courier0033.lzma.bak 2025-12-08T11:22:47.990Z,1765192967.990 [DataOverHttps](INFO): SBD MOMSN=26460300 2025-12-08T11:23:04.181Z,1765192984.181 [DataOverHttps](INFO): Sending 801 bytes from file Logs/20251208T030336/Express0034.lzma 2025-12-08T11:23:05.182Z,1765192985.182 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0034.lzma.bak 2025-12-08T11:23:05.183Z,1765192985.183 [DataOverHttps](INFO): SBD MOMSN=26460333 2025-12-08T11:23:06.343Z,1765192986.343 [keepstation:NeedComms:E] Stopped 2025-12-08T11:23:06.343Z,1765192986.343 [keepstation:NeedComms:F] Running Loop=1 2025-12-08T11:23:08.343Z,1765192988.343 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112306.00,A,3631.81451,N,12201.59917,W,1.302,318.33,081225,,,D*75 2025-12-08T11:23:08.345Z,1765192988.345 [NAL9602](INFO): GPS fix at 20251208T112306: (36.530242, -122.026653) 2025-12-08T11:23:08.391Z,1765192988.391 [keepstation:NeedComms:F] Stopped 2025-12-08T11:23:08.391Z,1765192988.391 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-08T11:23:08.391Z,1765192988.391 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-08T11:23:08.391Z,1765192988.391 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-08T11:23:08.802Z,1765192988.802 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-08T11:23:08.802Z,1765192988.802 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-08T11:23:08.802Z,1765192988.802 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-08T11:23:08.802Z,1765192988.802 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-08T11:23:08.802Z,1765192988.802 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-08T11:23:08.802Z,1765192988.802 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-08T11:23:08.802Z,1765192988.802 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-08T11:23:09.174Z,1765192989.174 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-08T11:23:09.174Z,1765192989.174 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-08T11:23:09.174Z,1765192989.174 [keepstation:NeedComms:setInactive] Stopped 2025-12-08T11:23:09.174Z,1765192989.174 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-08T11:23:09.175Z,1765192989.175 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-08T11:23:09.175Z,1765192989.175 [keepstation:NeedComms] Stopped 2025-12-08T11:23:09.175Z,1765192989.175 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-08T11:23:09.175Z,1765192989.175 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-08T11:23:09.180Z,1765192989.180 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-08T11:23:09.593Z,1765192989.593 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-08T11:23:09.593Z,1765192989.593 [keepstation:SurfaceComms:B] Stopped 2025-12-08T11:23:09.594Z,1765192989.594 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-08T11:23:09.594Z,1765192989.594 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-08T11:23:09.594Z,1765192989.594 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-08T11:23:09.594Z,1765192989.594 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-08T11:23:09.594Z,1765192989.594 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T11:23:09.594Z,1765192989.594 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-08T11:23:10.000Z,1765192990.000 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-08T11:23:10.000Z,1765192990.000 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-08T11:23:10.000Z,1765192990.000 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-08T11:23:10.001Z,1765192990.001 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T11:23:10.001Z,1765192990.001 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-08T11:23:10.001Z,1765192990.001 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-08T11:23:10.001Z,1765192990.001 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-08T11:23:10.001Z,1765192990.001 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-08T11:23:10.413Z,1765192990.413 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-08T11:23:10.413Z,1765192990.413 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-08T11:23:10.413Z,1765192990.413 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-08T11:23:10.413Z,1765192990.413 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-08T11:23:10.413Z,1765192990.413 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-08T11:23:10.413Z,1765192990.413 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-08T11:23:10.797Z,1765192990.797 [keepstation:SurfaceComms:setStation] Stopped 2025-12-08T11:23:10.797Z,1765192990.797 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-08T11:23:10.797Z,1765192990.797 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-08T11:23:10.797Z,1765192990.797 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-08T11:23:10.797Z,1765192990.797 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-08T11:23:10.797Z,1765192990.797 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-08T11:23:11.199Z,1765192991.199 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-08T11:23:11.199Z,1765192991.199 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-08T11:23:11.204Z,1765192991.204 [keepstation:SurfaceComms:setSink] Stopped 2025-12-08T11:23:11.204Z,1765192991.204 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-08T11:23:11.204Z,1765192991.204 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-08T11:23:11.204Z,1765192991.204 [keepstation:SurfaceComms] Stopped 2025-12-08T11:23:11.204Z,1765192991.204 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-08T11:23:11.615Z,1765192991.615 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-08T11:23:11.615Z,1765192991.615 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-08T11:23:11.620Z,1765192991.620 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-08T11:23:11.620Z,1765192991.620 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-08T11:23:11.622Z,1765192991.622 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-08T11:23:11.623Z,1765192991.623 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-08T11:23:11.623Z,1765192991.623 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-08T11:23:11.628Z,1765192991.628 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T11:23:11.628Z,1765192991.628 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-08T11:23:11.628Z,1765192991.628 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-08T11:23:11.628Z,1765192991.628 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-08T11:23:11.628Z,1765192991.628 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-08T11:23:11.628Z,1765192991.628 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-08T11:23:11.629Z,1765192991.629 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-08T11:23:11.629Z,1765192991.629 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-08T11:23:11.629Z,1765192991.629 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-08T11:23:11.629Z,1765192991.629 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-08T11:23:40.261Z,1765193020.261 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-08T11:25:08.724Z,1765193108.724 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T11:25:39.860Z,1765193139.860 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T11:25:49.492Z,1765193149.492 [Radio_Surface](INFO): Powering down 2025-12-08T11:26:10.984Z,1765193170.984 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T11:26:11.989Z,1765193171.989 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-08T11:26:21.926Z,1765193181.926 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T11:27:16.244Z,1765193236.244 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.731216 2025-12-08T11:27:24.091Z,1765193244.091 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-08T11:27:24.091Z,1765193244.091 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-08T11:27:24.091Z,1765193244.091 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T11:27:24.091Z,1765193244.091 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-08T11:27:24.496Z,1765193244.496 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-08T11:27:24.496Z,1765193244.496 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T11:27:24.496Z,1765193244.496 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-08T11:27:24.496Z,1765193244.496 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-08T11:27:24.496Z,1765193244.496 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T11:27:24.497Z,1765193244.497 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-08T11:27:24.905Z,1765193244.905 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-08T11:27:24.905Z,1765193244.905 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T11:27:24.905Z,1765193244.905 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-08T11:27:24.906Z,1765193244.906 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T11:27:24.906Z,1765193244.906 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T11:27:24.906Z,1765193244.906 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-08T11:27:24.906Z,1765193244.906 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T11:27:24.906Z,1765193244.906 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-08T11:27:24.906Z,1765193244.906 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-08T11:27:24.907Z,1765193244.907 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-08T11:27:24.907Z,1765193244.907 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-08T11:27:24.907Z,1765193244.907 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-08T11:27:25.322Z,1765193245.322 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-08T11:27:25.322Z,1765193245.322 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-08T11:27:25.322Z,1765193245.322 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-08T11:27:25.322Z,1765193245.322 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-08T11:27:25.322Z,1765193245.322 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-08T11:27:25.323Z,1765193245.323 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-08T11:27:25.323Z,1765193245.323 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-08T11:27:25.323Z,1765193245.323 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-08T11:27:25.697Z,1765193245.697 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.17 mm (1 active estimators). 2025-12-08T11:34:11.757Z,1765193651.757 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T11:34:11.761Z,1765193651.761 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-08T11:36:22.250Z,1765193782.250 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T11:46:22.663Z,1765194382.663 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T11:51:12.050Z,1765194672.050 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-12-08T11:56:11.799Z,1765194971.799 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-08T11:56:22.692Z,1765194982.692 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T12:06:23.047Z,1765195583.047 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T12:15:13.925Z,1765196113.925 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T12:15:13.929Z,1765196113.929 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-08T12:16:23.418Z,1765196183.418 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T12:26:23.780Z,1765196783.780 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T12:36:24.107Z,1765197384.107 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T12:37:29.559Z,1765197449.559 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T12:46:20.412Z,1765197980.412 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-08T12:46:24.452Z,1765197984.452 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T12:47:06.509Z,1765198026.509 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T12:53:08.509Z,1765198388.509 [keepstation:SurfaceComms] Running Loop=1 2025-12-08T12:53:08.510Z,1765198388.510 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-12-08T12:53:08.510Z,1765198388.510 [keepstation:SurfaceComms:A] Running Loop=1 2025-12-08T12:53:08.510Z,1765198388.510 [keepstation:SurfaceComms:A] Stopped 2025-12-08T12:53:08.510Z,1765198388.510 [keepstation:SurfaceComms:B] Running Loop=1 2025-12-08T12:53:08.510Z,1765198388.510 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-12-08T12:53:08.884Z,1765198388.884 [keepstation:KeepStation:Dive] Stopped 2025-12-08T12:53:08.884Z,1765198388.884 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-12-08T12:53:08.884Z,1765198388.884 [keepstation:KeepStation:Dive:A] Stopped 2025-12-08T12:53:08.884Z,1765198388.884 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-12-08T12:53:08.885Z,1765198388.885 [keepstation:DiveCmd:StationKeep] Stopped 2025-12-08T12:53:08.885Z,1765198388.885 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-12-08T12:53:08.885Z,1765198388.885 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-12-08T12:53:08.885Z,1765198388.885 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-12-08T12:53:08.885Z,1765198388.885 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-12-08T12:53:08.885Z,1765198388.885 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-12-08T12:53:08.887Z,1765198388.887 [keepstation:NeedComms] Running Loop=1 2025-12-08T12:53:08.887Z,1765198388.887 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-12-08T12:53:08.887Z,1765198388.887 [keepstation:NeedComms:C.GoToSurface] Running Loop=1 2025-12-08T12:53:08.887Z,1765198388.887 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-08T12:53:08.887Z,1765198388.887 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-12-08T12:53:08.888Z,1765198388.888 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-12-08T12:53:08.888Z,1765198388.888 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-12-08T12:53:08.889Z,1765198388.889 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2025-12-08T12:53:08.889Z,1765198388.889 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-08T12:53:08.889Z,1765198388.889 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-08T12:53:08.889Z,1765198388.889 [keepstation:NeedComms:A] Running Loop=1 2025-12-08T12:53:08.891Z,1765198388.891 [keepstation:NeedComms:A](INFO): last time_fix was: 1765192986.000000 second since 1970/01/01T00:00:00Z 2025-12-08T12:53:08.891Z,1765198388.891 [keepstation:NeedComms:A] Stopped 2025-12-08T12:53:08.891Z,1765198388.891 [keepstation:NeedComms:setActive] Running Loop=1 2025-12-08T12:53:08.891Z,1765198388.891 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive 2025-12-08T12:53:08.891Z,1765198388.891 [keepstation:NeedComms:setActive:A] Running Loop=1 2025-12-08T12:53:09.287Z,1765198389.287 [keepstation:NeedComms:setActive:A] Stopped 2025-12-08T12:53:09.287Z,1765198389.287 [keepstation:NeedComms:setActive:B] Running Loop=1 2025-12-08T12:53:09.690Z,1765198389.690 [keepstation:NeedComms:setActive:B] Stopped 2025-12-08T12:53:09.690Z,1765198389.690 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive 2025-12-08T12:53:09.690Z,1765198389.690 [keepstation:NeedComms:setActive] Stopped 2025-12-08T12:53:09.690Z,1765198389.690 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive 2025-12-08T12:54:42.897Z,1765198482.897 [keepstation:NeedComms:D] Running Loop=1 2025-12-08T12:54:43.560Z,1765198483.560 [Radio_Surface](INFO): Powering up 2025-12-08T12:54:48.980Z,1765198488.980 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-08T12:55:19.100Z,1765198519.100 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T12:55:50.224Z,1765198550.224 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T12:56:15.796Z,1765198575.796 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T12:56:15.799Z,1765198575.799 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-08T12:56:21.348Z,1765198581.348 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T12:56:24.692Z,1765198584.692 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-12-08T12:56:52.472Z,1765198612.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T12:57:00.900Z,1765198620.900 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002852 2025-12-08T13:00:03.653Z,1765198803.653 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T13:00:03.653Z,1765198803.653 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T13:00:04.053Z,1765198804.053 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-08T13:00:05.267Z,1765198805.267 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,130003.00,A,3631.84878,N,12201.53788,W,1.127,298.86,081225,,,D*71 2025-12-08T13:00:05.269Z,1765198805.269 [NAL9602](INFO): GPS fix at 20251208T130003: (36.530813, -122.025631) 2025-12-08T13:00:05.281Z,1765198805.281 [UniversalFixResidualReporter](INFO): Fix residual: 96.8 %DT, over the last 1427.1 m. Residual distance 1381.4 m at bearing 141.8 degrees. Fix at (36.5308, -122.0256) with 111.2 m made good. 2025-12-08T13:00:05.284Z,1765198805.284 [keepstation:NeedComms:D] Stopped 2025-12-08T13:00:05.284Z,1765198805.284 [keepstation:NeedComms:E] Running Loop=1 2025-12-08T13:00:12.723Z,1765198812.723 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20251208T030336/Courier0036.lzma 2025-12-08T13:00:13.726Z,1765198813.726 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Courier0036.lzma.bak 2025-12-08T13:00:13.726Z,1765198813.726 [DataOverHttps](INFO): SBD MOMSN=26460823 2025-12-08T13:00:32.597Z,1765198832.597 [DataOverHttps](INFO): Sending 725 bytes from file Logs/20251208T030336/Express0037.lzma 2025-12-08T13:00:33.598Z,1765198833.598 [DataOverHttps](INFO): Moved sent file to Logs/20251208T030336/Express0037.lzma.bak 2025-12-08T13:00:33.598Z,1765198833.598 [DataOverHttps](INFO): SBD MOMSN=26460830 2025-12-08T13:00:34.931Z,1765198834.931 [keepstation:NeedComms:E] Stopped 2025-12-08T13:00:34.931Z,1765198834.931 [keepstation:NeedComms:F] Running Loop=1 2025-12-08T13:00:36.933Z,1765198836.933 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,130035.00,A,3631.85066,N,12201.54145,W,0.369,316.58,081225,,,D*7F 2025-12-08T13:00:36.935Z,1765198836.935 [NAL9602](INFO): GPS fix at 20251208T130035: (36.530844, -122.025691) 2025-12-08T13:00:36.960Z,1765198836.960 [keepstation:NeedComms:F] Stopped 2025-12-08T13:00:36.961Z,1765198836.961 [keepstation:NeedComms:setRestoreMode] Running Loop=1 2025-12-08T13:00:36.961Z,1765198836.961 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode 2025-12-08T13:00:36.961Z,1765198836.961 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1 2025-12-08T13:00:37.386Z,1765198837.386 [keepstation:NeedComms:setRestoreMode:A] Stopped 2025-12-08T13:00:37.386Z,1765198837.386 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode 2025-12-08T13:00:37.386Z,1765198837.386 [keepstation:NeedComms:setRestoreMode] Stopped 2025-12-08T13:00:37.386Z,1765198837.386 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode 2025-12-08T13:00:37.386Z,1765198837.386 [keepstation:NeedComms:setInactive] Running Loop=1 2025-12-08T13:00:37.387Z,1765198837.387 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive 2025-12-08T13:00:37.387Z,1765198837.387 [keepstation:NeedComms:setInactive:A] Running Loop=1 2025-12-08T13:00:37.795Z,1765198837.795 [keepstation:NeedComms:setInactive:A] Stopped 2025-12-08T13:00:37.795Z,1765198837.795 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive 2025-12-08T13:00:37.795Z,1765198837.795 [keepstation:NeedComms:setInactive] Stopped 2025-12-08T13:00:37.800Z,1765198837.800 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive 2025-12-08T13:00:37.801Z,1765198837.801 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-12-08T13:00:37.801Z,1765198837.801 [keepstation:NeedComms] Stopped 2025-12-08T13:00:37.801Z,1765198837.801 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-12-08T13:00:37.801Z,1765198837.801 [keepstation:NeedComms:C.GoToSurface] Stopped 2025-12-08T13:00:37.801Z,1765198837.801 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-08T13:00:38.176Z,1765198838.176 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-12-08T13:00:38.176Z,1765198838.176 [keepstation:SurfaceComms:B] Stopped 2025-12-08T13:00:38.176Z,1765198838.176 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-12-08T13:00:38.176Z,1765198838.176 [keepstation:SurfaceComms:HandleNan] Running Loop=1 2025-12-08T13:00:38.177Z,1765198838.177 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan 2025-12-08T13:00:38.177Z,1765198838.177 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1 2025-12-08T13:00:38.177Z,1765198838.177 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T13:00:38.177Z,1765198838.177 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1 2025-12-08T13:00:38.588Z,1765198838.588 [keepstation:SurfaceComms:HandleNan] Stopped 2025-12-08T13:00:38.588Z,1765198838.588 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan 2025-12-08T13:00:38.588Z,1765198838.588 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped 2025-12-08T13:00:38.589Z,1765198838.589 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation 2025-12-08T13:00:38.589Z,1765198838.589 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped 2025-12-08T13:00:38.589Z,1765198838.589 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-12-08T13:00:38.589Z,1765198838.589 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-12-08T13:00:38.589Z,1765198838.589 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-12-08T13:00:38.968Z,1765198838.968 [keepstation:SurfaceComms:setTransit] Stopped 2025-12-08T13:00:38.969Z,1765198838.969 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-12-08T13:00:38.969Z,1765198838.969 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-12-08T13:00:38.969Z,1765198838.969 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-12-08T13:00:38.969Z,1765198838.969 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-12-08T13:00:38.969Z,1765198838.969 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-12-08T13:00:39.367Z,1765198839.367 [keepstation:SurfaceComms:setStation] Stopped 2025-12-08T13:00:39.367Z,1765198839.367 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-12-08T13:00:39.367Z,1765198839.367 [keepstation:SurfaceComms:setStation:A] Stopped 2025-12-08T13:00:39.367Z,1765198839.367 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-12-08T13:00:39.367Z,1765198839.367 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-12-08T13:00:39.367Z,1765198839.367 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-12-08T13:00:39.798Z,1765198839.798 [keepstation:SurfaceComms:setSink:A] Stopped 2025-12-08T13:00:39.799Z,1765198839.799 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-12-08T13:00:39.799Z,1765198839.799 [keepstation:SurfaceComms:setSink] Stopped 2025-12-08T13:00:39.799Z,1765198839.799 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-12-08T13:00:39.799Z,1765198839.799 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-12-08T13:00:39.799Z,1765198839.799 [keepstation:SurfaceComms] Stopped 2025-12-08T13:00:39.799Z,1765198839.799 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-12-08T13:00:40.215Z,1765198840.215 [keepstation:KeepStation:Dive] Running Loop=1 2025-12-08T13:00:40.224Z,1765198840.224 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-12-08T13:00:40.238Z,1765198840.238 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-12-08T13:00:40.238Z,1765198840.238 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-12-08T13:00:40.238Z,1765198840.238 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-12-08T13:00:40.239Z,1765198840.239 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-12-08T13:00:40.239Z,1765198840.239 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-12-08T13:00:40.239Z,1765198840.239 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T13:00:40.239Z,1765198840.239 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-12-08T13:00:40.239Z,1765198840.239 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-12-08T13:00:40.239Z,1765198840.239 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-12-08T13:00:40.239Z,1765198840.239 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-12-08T13:00:40.239Z,1765198840.239 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-12-08T13:00:40.240Z,1765198840.240 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-12-08T13:00:40.240Z,1765198840.240 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-12-08T13:00:40.240Z,1765198840.240 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-12-08T13:00:40.241Z,1765198840.241 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-12-08T13:01:09.243Z,1765198869.243 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-08T13:03:08.296Z,1765198988.296 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-12-08T13:03:39.420Z,1765199019.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T13:04:00.284Z,1765199040.284 [Radio_Surface](INFO): Powering down 2025-12-08T13:04:10.552Z,1765199050.552 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-08T13:04:11.557Z,1765199051.557 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-08T13:05:18.824Z,1765199118.824 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.247299 2025-12-08T13:05:29.826Z,1765199129.826 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-12-08T13:05:29.826Z,1765199129.826 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-12-08T13:05:29.826Z,1765199129.826 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T13:05:29.826Z,1765199129.826 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-12-08T13:05:30.233Z,1765199130.233 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-12-08T13:05:30.233Z,1765199130.233 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-12-08T13:05:30.233Z,1765199130.233 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-12-08T13:05:30.233Z,1765199130.233 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-12-08T13:05:30.233Z,1765199130.233 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T13:05:30.233Z,1765199130.233 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-12-08T13:05:30.631Z,1765199130.631 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-12-08T13:05:30.631Z,1765199130.631 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T13:05:30.648Z,1765199130.648 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-12-08T13:05:30.648Z,1765199130.648 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-12-08T13:05:30.648Z,1765199130.648 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T13:05:30.649Z,1765199130.649 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-12-08T13:05:30.649Z,1765199130.649 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-12-08T13:05:30.649Z,1765199130.649 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-12-08T13:05:30.649Z,1765199130.649 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-12-08T13:05:30.649Z,1765199130.649 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-12-08T13:05:30.649Z,1765199130.649 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-12-08T13:05:30.649Z,1765199130.649 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-12-08T13:05:31.032Z,1765199131.032 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-12-08T13:05:31.032Z,1765199131.032 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-12-08T13:05:31.032Z,1765199131.032 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-12-08T13:05:31.032Z,1765199131.032 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-12-08T13:05:31.032Z,1765199131.032 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-12-08T13:05:31.033Z,1765199131.033 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-12-08T13:05:31.033Z,1765199131.033 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-12-08T13:05:31.034Z,1765199131.034 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-12-08T13:11:24.963Z,1765199484.963 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-08T13:13:00.698Z,1765199580.698 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-12-08T13:18:30.818Z,1765199910.818 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T13:23:06.031Z,1765200186.031 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-12-08T13:25:13.304Z,1765200313.304 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-08T13:30:55.913Z,1765200655.913 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-12-08T13:33:41.973Z,1765200821.973 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-12-08T13:37:17.282Z,1765201037.282 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T13:37:17.286Z,1765201037.286 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-12-08T13:59:32.510Z,1765202372.510 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-08T14:14:16.925Z,1765203256.925 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-08T14:18:19.270Z,1765203499.270 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-12-08T14:18:19.274Z,1765203499.274 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.