2025-04-23T05:30:51.160Z,1745386251.160 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist 2025-04-23T05:30:51.162Z,1745386251.162 [CommandExec](IMPORTANT): got command restart logs 2025-04-23T05:30:51.451Z,1745386251.451 [DataOverHttps](IMPORTANT): SBD MTMSN=20250423T053049 2025-04-23T05:30:52.931Z,1745386252.931 [NAL9602](INFO): SBD MO Status=2, MOMSN=20381, MT Status=2, MTMSN=0 2025-04-23T05:30:52.931Z,1745386252.931 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-23T05:31:02.489Z,1745386262.489 [DataOverHttps](INFO): Received command: sched 20250423T13 "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs" 2025-04-23T05:31:03.491Z,1745386263.491 [DataOverHttps](IMPORTANT): SBD MTMSN=20250423T053101 2025-04-23T05:31:05.793Z,1745386265.793 [CommandExec](IMPORTANT): got command schedule 20250423T130000 "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs" 2025-04-23T05:31:05.794Z,1745386265.794 [CommandExec](IMPORTANT): Scheduled #13: "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs", AT TIME: 2025-04-23T13:00:00Z 2025-04-23T05:31:12.249Z,1745386272.249 [DataOverHttps](INFO): Received command: sched 20250423T13 "load Science/profile_station.tl;set profile_station.MissionTimeout 15 h;set profile_station.NeedCommsTime 90 min;set profile_station.Lat 36.7948 degree;set profile_station.Lon -121.8726 degree" 264y 1 3 2025-04-23T05:31:12.342Z,1745386272.342 [CommandExec](IMPORTANT): got command schedule 20250423T130000 "load Science/profile_station.tl;set profile_station.MissionTimeout 15 h;set profile_station.NeedCommsTime 90 min;set profile_station.Lat 36.7948 degree;set profile_station.Lon -121.8726 degree" 264y 1 3.000000 2025-04-23T05:31:12.343Z,1745386272.343 [CommandExec](IMPORTANT): Scheduling command #1 of 3 with id=264y 2025-04-23T05:31:12.344Z,1745386272.344 [CommandExec](IMPORTANT): Scheduled #14 (#1 of 3 with id='264y'): "load Science/profile_station.tl;set profile_station.MissionTimeout 15 h;set profile_station.NeedCommsTime 90 min;set profile_station.Lat 36.7948 degree;set profile_station.Lon -121.8726 degree", AT TIME: 2025-04-23T13:00:00Z 2025-04-23T05:31:13.251Z,1745386273.251 [DataOverHttps](IMPORTANT): SBD MTMSN=20250423T053111 2025-04-23T05:31:21.025Z,1745386281.025 [DataOverHttps](INFO): Received command: sched 20250423T13 "set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 40 m;set profile_station.YoYoPitch 15 degree;set profile_station.Speed 1 m/s;set profile_station.SinkOffSurface 1 bool" 264y 2 3 2025-04-23T05:31:21.079Z,1745386281.079 [CommandExec](IMPORTANT): got command schedule 20250423T130000 "set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 40 m;set profile_station.YoYoPitch 15 degree;set profile_station.Speed 1 m/s;set profile_station.SinkOffSurface 1 bool" 264y 2 3.000000 2025-04-23T05:31:21.079Z,1745386281.079 [CommandExec](IMPORTANT): Scheduling command #2 of 3 with id=264y 2025-04-23T05:31:21.080Z,1745386281.080 [CommandExec](IMPORTANT): Scheduled #15 (#2 of 3 with id='264y'): "set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 40 m;set profile_station.YoYoPitch 15 degree;set profile_station.Speed 1 m/s;set profile_station.SinkOffSurface 1 bool", AT TIME: 2025-04-23T13:00:00Z 2025-04-23T05:31:22.027Z,1745386282.027 [DataOverHttps](IMPORTANT): SBD MTMSN=20250423T053119 2025-04-23T05:31:27.669Z,1745386287.669 [NAL9602](INFO): SBD MO Status=2, MOMSN=20381, MT Status=2, MTMSN=0 2025-04-23T05:31:27.670Z,1745386287.670 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-23T05:31:29.758Z,1745386289.758 [DataOverHttps](INFO): Received command: sched 20250423T13 "set profile_station.SinkDepth 2 m;set profile_station.MaxDepth 55 m;set profile_station:NeedComms.CommsTimeout 10 min;run" 264y 3 3 2025-04-23T05:31:29.853Z,1745386289.853 [CommandExec](IMPORTANT): got command schedule 20250423T130000 "set profile_station.SinkDepth 2 m;set profile_station.MaxDepth 55 m;set profile_station:NeedComms.CommsTimeout 10 min;run" 264y 3 3.000000 2025-04-23T05:31:29.854Z,1745386289.854 [CommandExec](IMPORTANT): Scheduling command #3 of 3 with id=264y 2025-04-23T05:31:29.856Z,1745386289.856 [CommandExec](IMPORTANT): Scheduled #16 (#3 of 3 with id='264y'): "set profile_station.SinkDepth 2 m;set profile_station.MaxDepth 55 m;set profile_station:NeedComms.CommsTimeout 10 min;run", AT TIME: 2025-04-23T13:00:00Z 2025-04-23T05:31:30.758Z,1745386290.758 [DataOverHttps](IMPORTANT): SBD MTMSN=20250423T053128 2025-04-23T05:31:41.009Z,1745386301.009 [DataOverHttps](INFO): Received command: schedule resume 2025-04-23T05:31:41.071Z,1745386301.071 [CommandExec](IMPORTANT): got command schedule resume 2025-04-23T05:31:47.877Z,1745386307.877 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=20381, MT Status=1, MTMSN=4308 2025-04-23T05:31:47.877Z,1745386307.877 [NAL9602](INFO): Data available in MT queue 2025-04-23T05:31:48.573Z,1745386308.573 [NAL9602](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.Depth 20 m;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m" 25ui 1 2 2025-04-23T05:31:48.585Z,1745386308.585 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.Depth 20 m;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m" 25ui 1 2.000000 2025-04-23T05:31:48.586Z,1745386308.586 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=25ui 2025-04-23T05:31:48.587Z,1745386308.587 [CommandExec](IMPORTANT): Scheduled #17 (#1 of 2 with id='25ui'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.Depth 20 m;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m", ASAP 2025-04-23T05:31:49.492Z,1745386309.492 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053147.00,A,3647.49337,N,12153.70712,W,0.680,140.27,230425,,,A*7F 2025-04-23T05:31:49.495Z,1745386309.495 [NAL9602](INFO): GPS fix at 20250423T053147: (36.791556, -121.895119) 2025-04-23T05:31:49.507Z,1745386309.507 [UniversalFixResidualReporter](INFO): Fix residual: 22.4 %DT, over the last 5097.1 m. Residual distance 1142.2 m at bearing -61.2 degrees. Fix at (36.7916, -121.8951) with 3008.5 m made good. 2025-04-23T05:31:49.511Z,1745386309.511 [profile_station:NeedComms:C] Stopped 2025-04-23T05:31:49.512Z,1745386309.512 [profile_station:NeedComms:D] Running Loop=1 2025-04-23T05:31:56.844Z,1745386316.844 [DataOverHttps](INFO): Sending 91 bytes from file Logs/20250422T180512/Courier0196.lzma 2025-04-23T05:31:57.848Z,1745386317.848 [DataOverHttps](INFO): Moved sent file to Logs/20250422T180512/Courier0196.lzma.bak 2025-04-23T05:31:57.848Z,1745386317.848 [DataOverHttps](INFO): SBD MOMSN=24716612 2025-04-23T05:32:15.318Z,1745386335.318 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20250423T053051/Courier0000.lzma 2025-04-23T05:32:16.319Z,1745386336.319 [DataOverHttps](INFO): Moved sent file to Logs/20250423T053051/Courier0000.lzma.bak 2025-04-23T05:32:16.319Z,1745386336.319 [DataOverHttps](INFO): SBD MOMSN=24716615 2025-04-23T05:32:21.826Z,1745386341.826 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-23T05:32:32.847Z,1745386352.847 [DataOverHttps](INFO): Sending 1690 bytes from file Logs/20250422T180512/Express0197.lzma 2025-04-23T05:32:33.843Z,1745386353.843 [DataOverHttps](INFO): Moved sent file to Logs/20250422T180512/Express0197.lzma.bak 2025-04-23T05:32:33.843Z,1745386353.843 [DataOverHttps](INFO): SBD MOMSN=24716620 2025-04-23T05:32:53.652Z,1745386373.652 [DataOverHttps](INFO): Sending 1341 bytes from file Logs/20250423T053051/Express0001.lzma 2025-04-23T05:32:54.650Z,1745386374.650 [DataOverHttps](INFO): Moved sent file to Logs/20250423T053051/Express0001.lzma.bak 2025-04-23T05:32:54.651Z,1745386374.651 [DataOverHttps](INFO): SBD MOMSN=24716625 2025-04-23T05:32:56.597Z,1745386376.597 [profile_station:NeedComms:D] Stopped 2025-04-23T05:32:56.597Z,1745386376.597 [profile_station:NeedComms:E] Running Loop=1 2025-04-23T05:32:58.609Z,1745386378.609 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053256.00,A,3647.48764,N,12153.69811,W,0.661,138.69,230425,,,D*74 2025-04-23T05:32:58.611Z,1745386378.611 [NAL9602](INFO): GPS fix at 20250423T053256: (36.791461, -121.894969) 2025-04-23T05:32:58.634Z,1745386378.634 [profile_station:NeedComms:E] Stopped 2025-04-23T05:32:58.635Z,1745386378.635 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms 2025-04-23T05:32:58.635Z,1745386378.635 [profile_station:NeedComms] Stopped 2025-04-23T05:32:58.635Z,1745386378.635 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms 2025-04-23T05:32:58.635Z,1745386378.635 [profile_station:NeedComms:B.GoToSurface] Stopped 2025-04-23T05:32:58.635Z,1745386378.635 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-23T05:32:59.047Z,1745386379.047 [profile_station:SurfaceComms:B](INFO): Completed profile_station:SurfaceComms:B 2025-04-23T05:32:59.047Z,1745386379.047 [profile_station:SurfaceComms:B] Stopped 2025-04-23T05:32:59.047Z,1745386379.047 [profile_station:SurfaceComms:B](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:B 2025-04-23T05:32:59.047Z,1745386379.047 [profile_station:SurfaceComms:setInitial] Running Loop=1 2025-04-23T05:32:59.048Z,1745386379.048 [profile_station:SurfaceComms:setInitial](DEBUG): Aggregate::initialize profile_station:SurfaceComms:setInitial 2025-04-23T05:32:59.048Z,1745386379.048 [profile_station:SurfaceComms:setInitial:A] Running Loop=1 2025-04-23T05:32:59.453Z,1745386379.453 [profile_station:SurfaceComms:setInitial] Stopped 2025-04-23T05:32:59.453Z,1745386379.453 [profile_station:SurfaceComms:setInitial](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:setInitial 2025-04-23T05:32:59.453Z,1745386379.453 [profile_station:SurfaceComms:setInitial:A] Stopped 2025-04-23T05:32:59.453Z,1745386379.453 [profile_station:SurfaceComms:setSink] Running Loop=1 2025-04-23T05:32:59.453Z,1745386379.453 [profile_station:SurfaceComms:setSink](DEBUG): Aggregate::initialize profile_station:SurfaceComms:setSink 2025-04-23T05:32:59.453Z,1745386379.453 [profile_station:SurfaceComms:setSink:A] Running Loop=1 2025-04-23T05:32:59.931Z,1745386379.931 [profile_station:SurfaceComms:setSink:A] Stopped 2025-04-23T05:32:59.931Z,1745386379.931 [profile_station:SurfaceComms:setSink](INFO): Completed profile_station:SurfaceComms:setSink 2025-04-23T05:32:59.931Z,1745386379.931 [profile_station:SurfaceComms:setSink] Stopped 2025-04-23T05:32:59.931Z,1745386379.931 [profile_station:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:setSink 2025-04-23T05:32:59.932Z,1745386379.932 [profile_station:SurfaceComms](INFO): Completed profile_station:SurfaceComms 2025-04-23T05:32:59.932Z,1745386379.932 [profile_station:SurfaceComms] Stopped 2025-04-23T05:32:59.932Z,1745386379.932 [profile_station:SurfaceComms](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms 2025-04-23T05:33:00.270Z,1745386380.270 [profile_station:CircleWrapper:Dive] Running Loop=1 2025-04-23T05:33:00.270Z,1745386380.270 [profile_station:CircleWrapper:Dive](DEBUG): Aggregate::initialize profile_station:CircleWrapper:Dive 2025-04-23T05:33:00.270Z,1745386380.270 [profile_station:CircleWrapper:Dive:A] Running Loop=1 2025-04-23T05:33:00.271Z,1745386380.271 [profile_station:CircleWrapper:Dive:A](DEBUG): Aggregate::initialize profile_station:CircleWrapper:Dive:A 2025-04-23T05:33:00.271Z,1745386380.271 [profile_station:DiveCmd:SurfaceSink] Running Loop=1 2025-04-23T05:33:00.271Z,1745386380.271 [profile_station:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize profile_station:DiveCmd:SurfaceSink 2025-04-23T05:33:00.272Z,1745386380.272 [profile_station:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-04-23T05:33:00.272Z,1745386380.272 [profile_station:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize profile_station:DiveCmd:SurfaceSink:sink 2025-04-23T05:33:00.272Z,1745386380.272 [profile_station:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-23T05:33:00.272Z,1745386380.272 [profile_station:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-23T05:33:00.272Z,1745386380.272 [profile_station:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-23T05:33:00.272Z,1745386380.272 [profile_station:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-04-23T05:33:00.272Z,1745386380.272 [profile_station:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-04-23T05:33:00.280Z,1745386380.280 [profile_station:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 5.000000 m 2025-04-23T05:33:00.280Z,1745386380.280 [profile_station:DiveCmd:SurfaceSink:sink:C] Stopped 2025-04-23T05:33:00.280Z,1745386380.280 [profile_station:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-04-23T05:33:00.280Z,1745386380.280 [profile_station:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-04-23T05:33:09.743Z,1745386389.743 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=20382, MT Status=1, MTMSN=4309 2025-04-23T05:33:09.743Z,1745386389.743 [NAL9602](INFO): Data available in MT queue 2025-04-23T05:33:10.357Z,1745386390.357 [NAL9602](INFO): Received command: sched asap "set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run" 25ui 2 2 2025-04-23T05:33:10.425Z,1745386390.425 [CommandExec](IMPORTANT): got command schedule asap "set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run" 25ui 2 2.000000 2025-04-23T05:33:10.425Z,1745386390.425 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=25ui 2025-04-23T05:33:10.426Z,1745386390.426 [CommandExec](IMPORTANT): Scheduled #18 (#2 of 2 with id='25ui'): "set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run", ASAP 2025-04-23T05:33:10.549Z,1745386390.549 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-04-23T05:33:10.549Z,1745386390.549 [MissionManager](INFO): Uninitializing Mission profile_station 2025-04-23T05:33:10.549Z,1745386390.549 [profile_station] Stopped 2025-04-23T05:33:10.549Z,1745386390.549 [profile_station](DEBUG): Aggregate::uninitialize profile_station 2025-04-23T05:33:10.549Z,1745386390.549 [profile_station:Science] Stopped 2025-04-23T05:33:10.549Z,1745386390.549 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science 2025-04-23T05:33:10.549Z,1745386390.549 [profile_station:Science:B] Stopped 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:Science:C] Stopped 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:Science:E] Stopped 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:Science:OceanCurrent] Stopped 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:Science:OceanCurrent:A.] Stopped 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:Science:PeakDetectChl] Stopped 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth] Stopped 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Uninitialize. 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:StandardEnvelopes] Stopped 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-04-23T05:33:10.550Z,1745386390.550 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:BackseatDriver] Stopped 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:BackseatDriver](DEBUG): Aggregate::uninitialize profile_station:BackseatDriver 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:BackseatDriver:A.BackseatDriver] Stopped 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:PowerOnly] Stopped 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:PowerOnly](DEBUG): Aggregate::uninitialize profile_station:PowerOnly 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:PowerOnly:E.Wait] Stopped 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-04-23T05:33:10.551Z,1745386390.551 [profile_station:DiveCmd] Stopped 2025-04-23T05:33:10.552Z,1745386390.552 [profile_station:DiveCmd](DEBUG): Aggregate::uninitialize profile_station:DiveCmd 2025-04-23T05:33:10.552Z,1745386390.552 [profile_station:DiveCmd:SurfaceSink] Stopped 2025-04-23T05:33:10.552Z,1745386390.552 [profile_station:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:SurfaceSink 2025-04-23T05:33:10.552Z,1745386390.552 [profile_station:DiveCmd:SurfaceSink:sink] Stopped 2025-04-23T05:33:10.552Z,1745386390.552 [profile_station:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:SurfaceSink:sink 2025-04-23T05:33:10.552Z,1745386390.552 [profile_station:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-04-23T05:33:10.552Z,1745386390.552 [profile_station:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-23T05:33:10.552Z,1745386390.552 [profile_station:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-04-23T05:33:10.552Z,1745386390.552 [profile_station:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-04-23T05:33:10.552Z,1745386390.552 [profile_station:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-04-23T05:33:10.552Z,1745386390.552 [profile_station:CircleWrapper] Stopped 2025-04-23T05:33:10.553Z,1745386390.553 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper 2025-04-23T05:33:10.553Z,1745386390.553 [profile_station:CircleWrapper:A] Stopped 2025-04-23T05:33:10.553Z,1745386390.553 [profile_station:CircleWrapper:Dive] Stopped 2025-04-23T05:33:10.553Z,1745386390.553 [profile_station:CircleWrapper:Dive](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive 2025-04-23T05:33:10.553Z,1745386390.553 [profile_station:CircleWrapper:Dive:A] Stopped 2025-04-23T05:33:10.553Z,1745386390.553 [profile_station:CircleWrapper:Dive:A](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive:A 2025-04-23T05:33:10.553Z,1745386390.553 [profile_station:CircleWrapper:C.Circle] Stopped 2025-04-23T05:33:10.553Z,1745386390.553 [profile_station:CircleWrapper:C.Circle](DEBUG): Uninitialize. 2025-04-23T05:33:10.839Z,1745386390.839 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-04-23T05:33:10.839Z,1745386390.839 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-04-23T05:33:10.853Z,1745386390.853 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-04-23T05:33:10.958Z,1745386390.958 [WetLabsBB2FL](INFO): Powering down 2025-04-23T05:33:12.217Z,1745386392.217 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into: 4 45 NaN NaN 30 15 4 0.75 200 10 10 45 2 0 0 1 2 3 NaN 0 Sinking to Timed out while trying to reach target sink depth at current depth of 2025-04-23T05:33:12.227Z,1745386392.227 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-04-23T05:33:12.231Z,1745386392.231 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-04-23T05:33:12.234Z,1745386392.234 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-04-23T05:33:12.238Z,1745386392.238 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-04-23T05:33:12.241Z,1745386392.241 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-04-23T05:33:12.273Z,1745386392.273 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-04-23T05:33:12.277Z,1745386392.277 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-04-23T05:33:12.280Z,1745386392.280 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-04-23T05:33:12.293Z,1745386392.293 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-04-23T05:33:12.302Z,1745386392.302 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool 2025-04-23T05:33:12.311Z,1745386392.311 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m 2025-04-23T05:33:12.315Z,1745386392.315 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min 2025-04-23T05:33:12.335Z,1745386392.335 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-04-23T05:33:12.339Z,1745386392.339 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-04-23T05:33:12.350Z,1745386392.350 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count 2025-04-23T05:33:12.358Z,1745386392.358 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count 2025-04-23T05:33:12.362Z,1745386392.362 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count 2025-04-23T05:33:12.375Z,1745386392.375 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count 2025-04-23T05:33:12.379Z,1745386392.379 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count 2025-04-23T05:33:12.390Z,1745386392.390 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool 2025-04-23T05:33:12.391Z,1745386392.391 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-04-23T05:33:12.391Z,1745386392.391 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-04-23T05:33:12.397Z,1745386392.397 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-04-23T05:33:12.769Z,1745386392.769 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2025-04-23T05:33:12.775Z,1745386392.775 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-04-23T05:33:12.779Z,1745386392.779 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-04-23T05:33:12.783Z,1745386392.783 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-04-23T05:33:12.786Z,1745386392.786 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-04-23T05:33:12.791Z,1745386392.791 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-04-23T05:33:12.794Z,1745386392.794 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s 2025-04-23T05:33:12.827Z,1745386392.827 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-04-23T05:33:12.831Z,1745386392.831 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-04-23T05:33:12.890Z,1745386392.890 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-23T05:33:12.963Z,1745386392.963 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-04-23T05:33:12.963Z,1745386392.963 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-04-23T05:33:12.969Z,1745386392.969 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-04-23T05:33:13.105Z,1745386393.105 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2025-04-23T05:33:13.119Z,1745386393.119 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-04-23T05:33:13.124Z,1745386393.124 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2025-04-23T05:33:13.135Z,1745386393.135 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-04-23T05:33:13.140Z,1745386393.140 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-04-23T05:33:13.153Z,1745386393.153 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-04-23T05:33:13.170Z,1745386393.170 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-04-23T05:33:13.192Z,1745386393.192 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-04-23T05:33:13.221Z,1745386393.221 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-04-23T05:33:13.221Z,1745386393.221 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-04-23T05:33:13.222Z,1745386393.222 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-04-23T05:33:13.295Z,1745386393.295 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-04-23T05:33:13.308Z,1745386393.308 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-04-23T05:33:13.325Z,1745386393.325 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-04-23T05:33:13.333Z,1745386393.333 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-04-23T05:33:13.333Z,1745386393.333 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-04-23T05:33:13.334Z,1745386393.334 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-04-23T05:33:13.585Z,1745386393.585 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-04-23T05:33:13.590Z,1745386393.590 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-04-23T05:33:13.594Z,1745386393.594 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-04-23T05:33:13.597Z,1745386393.597 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-04-23T05:33:13.622Z,1745386393.622 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-04-23T05:33:13.641Z,1745386393.641 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool 2025-04-23T05:33:13.690Z,1745386393.690 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-04-23T05:33:13.692Z,1745386393.692 [keepstation:H.Pitch](DEBUG): Construct. 2025-04-23T05:33:13.772Z,1745386393.772 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-04-23T05:33:13.778Z,1745386393.778 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct. 2025-04-23T05:33:13.782Z,1745386393.782 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct. 2025-04-23T05:33:13.837Z,1745386393.837 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-04-23T05:33:13.845Z,1745386393.845 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct. 2025-04-23T05:33:13.855Z,1745386393.855 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct. 2025-04-23T05:33:13.871Z,1745386393.871 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct. 2025-04-23T05:33:13.886Z,1745386393.886 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation. 2025-04-23T05:33:13.904Z,1745386393.904 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-04-23T05:33:13.955Z,1745386393.955 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait. 2025-04-23T05:33:13.993Z,1745386393.993 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission keepstation { """ Vehicle transits to desired waypoint (or stays put if none is commanded) and stays within the specified radius. """ arguments { MissionTimeout = 4 hour """ Maximum duration of mission """ NeedCommsTime = 45 minute """ How often to surface for communications """ Latitude = NaN degree """ Latitude of waypoint to hold. If NaN, hold the latitude at start of mission. """ Longitude = NaN degree """ Longitude of waypoint to hold. If NaN, hold the longitude at start of mission. """ Depth = 30 meter """ Depth held during drift mode """ ApproachDepth = 15 meter """ Depth for initial approach to station. """ DepthDeadband = 4 meter """ How much vertical drift from the specified depth is allowed in drift mode """ Speed = 0.75 meter_per_second """ Vehicle transit speed. """ Radius = 200 meter """ Radius of circle around waypoint to hold. """ SinkOffSurface = false """ If true, use buoyancy only to decend to SinkDepth before driving to first target depth """ SinkDepth = 10 meter """ Initial depth to decend using only buoyancy before driving """ SinkDepthTimeout = 10 minute """ Max wait time for the vehicle to reach the target depth """ MaxDepth = 45 meter """ Maximum allowable depth during the mission. """ MinOffshore = 2 kilometer """ Minimum distance from the shoreline to maintain """ } output { DiveMode = 0 count """ Mission variable (don't change). The mission sets this variable to switch between dive modes. Initialized to DoingComms. """ DoingComms = 0 count """ Mission variable (don't change). The mission will run in this mode when doing surface comms. """ SurfaceSink = 1 count """ Mission variable (don't change). The mission will run in this mode when sinking from the surface using only buoyancy. """ TransitToStation = 2 count """ Mission variable (don't change). The mission will run in this mode when performing the transit to station """ StationKeep = 3 count """ Mission variable (don't change). The mission will run in this mode when keeping station """ OnStation = false """ Mission variable (don't change). Determines last mode (Transit or StationKeep) to resume after needcomms """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Pitch { run in parallel set massPosition = Control:VerticalControl.massDefault } aggregate SurfaceComms { run when ( called or ( elapsed ( Universal:time_fix ) > NeedCommsTime ) ) assign in sequence DiveMode = DoingComms call refId="NeedComms" aggregate setTransit { run in sequence break if ( SinkOffSurface or OnStation ) assign in sequence DiveMode = TransitToStation } aggregate setStation { run in sequence break if ( SinkOffSurface or not OnStation ) assign in sequence DiveMode = StationKeep } aggregate setSink { run in sequence break if ( not SinkOffSurface ) assign in sequence DiveMode = SurfaceSink } } call id="StartingMission" refId="SurfaceComms" aggregate DiveCmd { run when ( called ) aggregate SurfaceSink { run while ( DiveMode == SurfaceSink ) aggregate sink { run in sequence behavior Guidance:Buoyancy id="BuoyancyHold" { run in parallel set position = NaN cc } behavior Guidance:SetSpeed { run in parallel set speed = 0 m/s } syslog info "Sinking to " + SinkDepth~meter behavior Guidance:Pitch { run in sequence timeout duration=SinkDepthTimeout { syslog important "Timed out while trying to reach target sink depth at current depth of " + Universal:depth~meter } set depth = SinkDepth } # Once we've sunk, either resume transit or station keeping depths and speeds aggregate sinkToTransit { run in sequence break if ( OnStation ) assign in sequence DiveMode = TransitToStation } aggregate sinkToStation { run in sequence break if ( not OnStation ) assign in sequence DiveMode = StationKeep } } } aggregate TransitToStation { run while ( DiveMode == TransitToStation ) aggregate dive { run in sequence behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch { run in parallel set depth = ApproachDepth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } } } aggregate StationKeep { run while ( DiveMode == StationKeep ) assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:KeepStation { run in parallel set latitude = Latitude set longitude = Longitude set radius = Radius set speed = Speed } } } aggregate TransitToStation { """ Need a separate aggregate for transit if we want to specify a different depth for the approach. """ run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call refId="DiveCmd" } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude } assign in sequence DiveMode = StationKeep assign in sequence OnStation = true } call id="OnStation" refId="NeedComms" aggregate KeepStation { run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call refId="DiveCmd" } behavior Guidance:Wait { """ Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. """ run in sequence set duration = MissionTimeout } } } 2025-04-23T05:33:13.994Z,1745386393.994 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-04-23T05:33:14.112Z,1745386394.112 [MissionManager](IMPORTANT): Started mission Default 2025-04-23T05:33:14.112Z,1745386394.112 [Default] Running Loop=1 2025-04-23T05:33:14.121Z,1745386394.121 [Default](DEBUG): Aggregate::initialize Default 2025-04-23T05:33:14.121Z,1745386394.121 [Default:B.GoToSurface] Running Loop=1 2025-04-23T05:33:14.121Z,1745386394.121 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-23T05:33:14.121Z,1745386394.121 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-23T05:33:14.121Z,1745386394.121 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-23T05:33:14.122Z,1745386394.122 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-23T05:33:14.122Z,1745386394.122 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-23T05:33:14.122Z,1745386394.122 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-23T05:33:14.123Z,1745386394.123 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-23T05:33:14.123Z,1745386394.123 [Default:A.Wait] Running Loop=1 2025-04-23T05:33:14.123Z,1745386394.123 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-04-23T05:33:27.444Z,1745386407.444 [Default:A.Wait](INFO): Done Waiting. 2025-04-23T05:33:27.444Z,1745386407.444 [Default:A.Wait] Stopped 2025-04-23T05:33:27.444Z,1745386407.444 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-23T05:33:27.846Z,1745386407.846 [Default:CheckIn] Running Loop=1 2025-04-23T05:33:27.846Z,1745386407.846 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-23T05:33:27.846Z,1745386407.846 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-23T05:33:29.840Z,1745386409.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053327.00,A,3647.48512,N,12153.69354,W,1.069,117.86,230425,,,D*79 2025-04-23T05:33:29.850Z,1745386409.850 [NAL9602](INFO): GPS fix at 20250423T053327: (36.791419, -121.894892) 2025-04-23T05:33:29.885Z,1745386409.885 [Default:CheckIn:Read_GPS] Stopped 2025-04-23T05:33:29.885Z,1745386409.885 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-23T05:33:39.357Z,1745386419.357 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250423T053051/Courier0003.lzma 2025-04-23T05:33:40.362Z,1745386420.362 [DataOverHttps](INFO): Moved sent file to Logs/20250423T053051/Courier0003.lzma.bak 2025-04-23T05:33:40.363Z,1745386420.363 [DataOverHttps](INFO): SBD MOMSN=24716650 2025-04-23T05:33:52.016Z,1745386432.016 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 11 hour 2025-04-23T05:33:52.017Z,1745386432.017 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 120 minute 2025-04-23T05:33:52.018Z,1745386432.018 [CommandExec](IMPORTANT): got command set keepstation.Depth 20 meter 2025-04-23T05:33:52.019Z,1745386432.019 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool 2025-04-23T05:33:52.020Z,1745386432.020 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 45 meter 2025-04-23T05:34:02.732Z,1745386442.732 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-23T05:34:05.062Z,1745386445.062 [DataOverHttps](INFO): Sending 471 bytes from file Logs/20250423T053051/Express0005.lzma 2025-04-23T05:34:06.063Z,1745386446.063 [DataOverHttps](INFO): Moved sent file to Logs/20250423T053051/Express0005.lzma.bak 2025-04-23T05:34:06.063Z,1745386446.063 [DataOverHttps](INFO): SBD MOMSN=24716654 2025-04-23T05:34:08.819Z,1745386448.819 [Default:CheckIn:Read_Iridium] Stopped 2025-04-23T05:34:08.819Z,1745386448.819 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-23T05:34:08.819Z,1745386448.819 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-23T05:34:14.359Z,1745386454.359 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second 2025-04-23T05:34:14.360Z,1745386454.360 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 10 minute 2025-04-23T05:34:14.361Z,1745386454.361 [CommandExec](IMPORTANT): got command run 2025-04-23T05:34:14.363Z,1745386454.363 [CommandExec](IMPORTANT): Running 2025-04-23T05:34:14.457Z,1745386454.457 [Default] Stopped 2025-04-23T05:34:14.457Z,1745386454.457 [Default](DEBUG): Aggregate::uninitialize Default 2025-04-23T05:34:14.457Z,1745386454.457 [Default:B.GoToSurface] Stopped 2025-04-23T05:34:14.457Z,1745386454.457 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-23T05:34:14.457Z,1745386454.457 [Default:CheckIn] Stopped 2025-04-23T05:34:14.457Z,1745386454.457 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-23T05:34:14.457Z,1745386454.457 [Default:CheckIn:C.Wait] Stopped 2025-04-23T05:34:14.457Z,1745386454.457 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-23T05:34:14.457Z,1745386454.457 [MissionManager](IMPORTANT): Started mission keepstation 2025-04-23T05:34:14.458Z,1745386454.458 [keepstation] Running Loop=1 2025-04-23T05:34:14.458Z,1745386454.458 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-04-23T05:34:14.458Z,1745386454.458 [keepstation:StandardEnvelopes] Running Loop=1 2025-04-23T05:34:14.458Z,1745386454.458 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-04-23T05:34:14.458Z,1745386454.458 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-04-23T05:34:14.458Z,1745386454.458 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-04-23T05:34:14.458Z,1745386454.458 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-04-23T05:34:14.458Z,1745386454.458 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-04-23T05:34:14.458Z,1745386454.458 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-04-23T05:34:14.459Z,1745386454.459 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-04-23T05:34:14.459Z,1745386454.459 [keepstation:BackseatDriver] Running Loop=1 2025-04-23T05:34:14.459Z,1745386454.459 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-04-23T05:34:14.459Z,1745386454.459 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-04-23T05:34:14.459Z,1745386454.459 [keepstation:PowerOnly] Running Loop=1 2025-04-23T05:34:14.459Z,1745386454.459 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-04-23T05:34:14.459Z,1745386454.459 [keepstation:PowerOnly:A] Running Loop=1 2025-04-23T05:34:14.459Z,1745386454.459 [keepstation:PowerOnly:B] Running Loop=1 2025-04-23T05:34:14.459Z,1745386454.459 [keepstation:PowerOnly:C] Running Loop=1 2025-04-23T05:34:14.459Z,1745386454.459 [keepstation:PowerOnly:D] Running Loop=1 2025-04-23T05:34:14.460Z,1745386454.460 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-04-23T05:34:14.460Z,1745386454.460 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-04-23T05:34:14.460Z,1745386454.460 [keepstation:H.Pitch] Running Loop=1 2025-04-23T05:34:14.460Z,1745386454.460 [keepstation:H.Pitch](DEBUG): Initialize. 2025-04-23T05:34:14.460Z,1745386454.460 [keepstation:B] Running Loop=1 2025-04-23T05:34:14.470Z,1745386454.470 [keepstation:H.Pitch] Running Loop=1 2025-04-23T05:34:14.470Z,1745386454.470 [keepstation:PowerOnly] Running Loop=1 2025-04-23T05:34:14.470Z,1745386454.470 [keepstation:PowerOnly:D] Stopped 2025-04-23T05:34:14.470Z,1745386454.470 [keepstation:PowerOnly:C] Stopped 2025-04-23T05:34:14.470Z,1745386454.470 [keepstation:PowerOnly:B] Stopped 2025-04-23T05:34:14.471Z,1745386454.471 [keepstation:PowerOnly:A] Stopped 2025-04-23T05:34:14.471Z,1745386454.471 [keepstation:BackseatDriver] Running Loop=1 2025-04-23T05:34:14.471Z,1745386454.471 [keepstation:StandardEnvelopes] Running Loop=1 2025-04-23T05:34:14.471Z,1745386454.471 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-04-23T05:34:14.471Z,1745386454.471 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-04-23T05:34:14.471Z,1745386454.471 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-04-23T05:34:14.472Z,1745386454.472 [keepstation:B] Stopped 2025-04-23T05:34:14.472Z,1745386454.472 [keepstation:D] Running Loop=1 2025-04-23T05:34:14.841Z,1745386454.841 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 3.45 mm. 2025-04-23T05:34:14.842Z,1745386454.842 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 3.45 mm. 2025-04-23T05:34:14.843Z,1745386454.843 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm. 2025-04-23T05:34:14.844Z,1745386454.844 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.10 m/s, pitch 30.00 deg, mass-position 3.45 mm. 2025-04-23T05:34:14.882Z,1745386454.882 [keepstation:D] Stopped 2025-04-23T05:34:14.882Z,1745386454.882 [keepstation:E] Running Loop=1 2025-04-23T05:34:15.293Z,1745386455.293 [keepstation:E] Stopped 2025-04-23T05:34:15.293Z,1745386455.293 [keepstation:StartingMission] Running Loop=1 2025-04-23T05:34:15.293Z,1745386455.293 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-04-23T05:34:15.666Z,1745386455.666 [keepstation:SurfaceComms] Running Loop=1 2025-04-23T05:34:15.666Z,1745386455.666 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-04-23T05:34:15.666Z,1745386455.666 [keepstation:SurfaceComms:A] Running Loop=1 2025-04-23T05:34:15.666Z,1745386455.666 [keepstation:SurfaceComms:A] Stopped 2025-04-23T05:34:15.666Z,1745386455.666 [keepstation:SurfaceComms:B] Running Loop=1 2025-04-23T05:34:15.666Z,1745386455.666 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-04-23T05:34:16.081Z,1745386456.081 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2025-04-23T05:34:16.081Z,1745386456.081 [keepstation:NeedComms] Running Loop=1 2025-04-23T05:34:16.081Z,1745386456.081 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-23T05:34:16.081Z,1745386456.081 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-23T05:34:16.081Z,1745386456.081 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-23T05:34:16.082Z,1745386456.082 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-23T05:34:16.082Z,1745386456.082 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-23T05:34:16.082Z,1745386456.082 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-23T05:34:16.083Z,1745386456.083 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-23T05:34:16.083Z,1745386456.083 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-23T05:34:16.083Z,1745386456.083 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-23T05:34:16.084Z,1745386456.084 [keepstation:NeedComms:A] Running Loop=1 2025-04-23T05:34:16.089Z,1745386456.089 [keepstation:NeedComms:A](INFO): last time_fix was: 1745386407.000000 second since 1970/01/01T00:00:00Z 2025-04-23T05:34:16.089Z,1745386456.089 [keepstation:NeedComms:A] Stopped 2025-04-23T05:34:16.467Z,1745386456.467 [keepstation:NeedComms:C] Running Loop=1 2025-04-23T05:34:16.883Z,1745386456.883 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-23T05:34:18.472Z,1745386458.472 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053416.00,A,3647.48117,N,12153.68669,W,0.797,131.41,230425,,,D*7F 2025-04-23T05:34:18.475Z,1745386458.475 [NAL9602](INFO): GPS fix at 20250423T053416: (36.791353, -121.894778) 2025-04-23T05:34:18.487Z,1745386458.487 [keepstation:NeedComms:C] Stopped 2025-04-23T05:34:18.487Z,1745386458.487 [keepstation:NeedComms:D] Running Loop=1 2025-04-23T05:34:18.911Z,1745386458.911 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-23T05:34:26.708Z,1745386466.708 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250423T053051/Courier0009.lzma 2025-04-23T05:34:27.710Z,1745386467.710 [DataOverHttps](INFO): Moved sent file to Logs/20250423T053051/Courier0009.lzma.bak 2025-04-23T05:34:27.711Z,1745386467.711 [DataOverHttps](INFO): SBD MOMSN=24716665 2025-04-23T05:34:31.063Z,1745386471.063 [NAL9602](INFO): SBD MO Status=0, MOMSN=20383, MT Status=0, MTMSN=0 2025-04-23T05:34:31.063Z,1745386471.063 [NAL9602](INFO): No messages in MT queue 2025-04-23T05:34:45.042Z,1745386485.042 [DataOverHttps](INFO): Sending 320 bytes from file Logs/20250423T053051/Express0010.lzma 2025-04-23T05:34:46.043Z,1745386486.043 [DataOverHttps](INFO): Moved sent file to Logs/20250423T053051/Express0010.lzma.bak 2025-04-23T05:34:46.043Z,1745386486.043 [DataOverHttps](INFO): SBD MOMSN=24716672 2025-04-23T05:34:48.091Z,1745386488.091 [keepstation:NeedComms:D] Stopped 2025-04-23T05:34:48.092Z,1745386488.092 [keepstation:NeedComms:E] Running Loop=1 2025-04-23T05:34:48.471Z,1745386488.471 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-23T05:34:50.082Z,1745386490.082 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053448.00,A,3647.47845,N,12153.68261,W,1.458,127.02,230425,,,D*78 2025-04-23T05:34:50.089Z,1745386490.089 [NAL9602](INFO): GPS fix at 20250423T053448: (36.791308, -121.894710) 2025-04-23T05:34:50.134Z,1745386490.134 [keepstation:NeedComms:E] Stopped 2025-04-23T05:34:50.135Z,1745386490.135 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-23T05:34:50.135Z,1745386490.135 [keepstation:NeedComms] Stopped 2025-04-23T05:34:50.135Z,1745386490.135 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-23T05:34:50.135Z,1745386490.135 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-23T05:34:50.135Z,1745386490.135 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-23T05:34:50.509Z,1745386490.509 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-04-23T05:34:50.509Z,1745386490.509 [keepstation:SurfaceComms:B] Stopped 2025-04-23T05:34:50.509Z,1745386490.509 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-04-23T05:34:50.510Z,1745386490.510 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-04-23T05:34:50.510Z,1745386490.510 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-04-23T05:34:50.510Z,1745386490.510 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-04-23T05:34:50.907Z,1745386490.907 [keepstation:SurfaceComms:setTransit] Stopped 2025-04-23T05:34:50.907Z,1745386490.907 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-04-23T05:34:50.907Z,1745386490.907 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-04-23T05:34:50.907Z,1745386490.907 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-04-23T05:34:50.908Z,1745386490.908 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-04-23T05:34:50.908Z,1745386490.908 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-04-23T05:34:51.294Z,1745386491.294 [keepstation:SurfaceComms:setStation] Stopped 2025-04-23T05:34:51.294Z,1745386491.294 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-04-23T05:34:51.294Z,1745386491.294 [keepstation:SurfaceComms:setStation:A] Stopped 2025-04-23T05:34:51.294Z,1745386491.294 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-04-23T05:34:51.294Z,1745386491.294 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-04-23T05:34:51.294Z,1745386491.294 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-04-23T05:34:51.718Z,1745386491.718 [keepstation:SurfaceComms:setSink:A] Stopped 2025-04-23T05:34:51.718Z,1745386491.718 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-04-23T05:34:51.718Z,1745386491.718 [keepstation:SurfaceComms:setSink] Stopped 2025-04-23T05:34:51.718Z,1745386491.718 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-04-23T05:34:51.718Z,1745386491.718 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-04-23T05:34:51.718Z,1745386491.718 [keepstation:SurfaceComms] Stopped 2025-04-23T05:34:51.718Z,1745386491.718 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-04-23T05:34:52.133Z,1745386492.133 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-04-23T05:34:52.133Z,1745386492.133 [keepstation:StartingMission] Stopped 2025-04-23T05:34:52.133Z,1745386492.133 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-04-23T05:34:52.134Z,1745386492.134 [keepstation:TransitToStation] Running Loop=1 2025-04-23T05:34:52.134Z,1745386492.134 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-04-23T05:34:52.134Z,1745386492.134 [keepstation:TransitToStation:Dive] Running Loop=1 2025-04-23T05:34:52.134Z,1745386492.134 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-04-23T05:34:52.134Z,1745386492.134 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-04-23T05:34:52.134Z,1745386492.134 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-04-23T05:34:52.134Z,1745386492.134 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-04-23T05:34:52.134Z,1745386492.134 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-23T05:34:52.526Z,1745386492.526 [keepstation:TransitToStation:Dive] Running Loop=1 2025-04-23T05:34:52.526Z,1745386492.526 [keepstation:DiveCmd] Running Loop=1 2025-04-23T05:34:52.526Z,1745386492.526 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2025-04-23T05:34:52.526Z,1745386492.526 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-04-23T05:34:52.527Z,1745386492.527 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-04-23T05:34:52.527Z,1745386492.527 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-04-23T05:34:52.527Z,1745386492.527 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-23T05:34:52.527Z,1745386492.527 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-23T05:34:52.527Z,1745386492.527 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-23T05:34:52.527Z,1745386492.527 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-23T05:34:52.527Z,1745386492.527 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-04-23T05:34:52.527Z,1745386492.527 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-04-23T05:34:52.527Z,1745386492.527 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-04-23T05:34:52.527Z,1745386492.527 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-04-23T05:34:52.528Z,1745386492.528 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-04-23T05:34:52.528Z,1745386492.528 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-04-23T05:34:52.528Z,1745386492.528 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-04-23T05:34:52.528Z,1745386492.528 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-23T05:34:52.528Z,1745386492.528 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-04-23T05:34:52.528Z,1745386492.528 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-04-23T05:34:52.528Z,1745386492.528 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-04-23T05:34:52.537Z,1745386492.537 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-04-23T05:34:52.537Z,1745386492.537 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-23T05:34:52.537Z,1745386492.537 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-04-23T05:34:52.537Z,1745386492.537 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-23T05:34:52.537Z,1745386492.537 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-23T05:34:52.537Z,1745386492.537 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-04-23T05:34:52.537Z,1745386492.537 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-23T05:34:52.537Z,1745386492.537 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-23T05:34:52.538Z,1745386492.538 [keepstation:DiveCmd:StationKeep] Stopped 2025-04-23T05:34:52.538Z,1745386492.538 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-04-23T05:34:52.538Z,1745386492.538 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-04-23T05:34:52.538Z,1745386492.538 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-04-23T05:34:52.538Z,1745386492.538 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-04-23T05:34:52.539Z,1745386492.539 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-04-23T05:34:52.539Z,1745386492.539 [keepstation:DiveCmd:TransitToStation] Stopped 2025-04-23T05:34:52.539Z,1745386492.539 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-04-23T05:34:52.539Z,1745386492.539 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-04-23T05:34:52.539Z,1745386492.539 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-04-23T05:34:52.539Z,1745386492.539 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-04-23T05:34:52.539Z,1745386492.539 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-23T05:34:52.539Z,1745386492.539 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-04-23T05:34:52.539Z,1745386492.539 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-04-23T05:34:52.539Z,1745386492.539 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-04-23T05:34:52.539Z,1745386492.539 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-04-23T05:34:52.540Z,1745386492.540 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-04-23T05:34:52.540Z,1745386492.540 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-04-23T05:34:52.540Z,1745386492.540 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-04-23T05:34:52.540Z,1745386492.540 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-04-23T05:34:52.549Z,1745386492.549 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-23T05:34:52.549Z,1745386492.549 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-23T05:35:22.508Z,1745386522.508 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-23T05:36:58.581Z,1745386618.581 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T05:37:17.209Z,1745386637.209 [Radio_Surface](INFO): Powering down 2025-04-23T05:37:29.713Z,1745386649.713 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T05:37:30.719Z,1745386650.719 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-23T05:38:22.328Z,1745386702.328 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-04-23T05:38:22.329Z,1745386702.329 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-04-23T05:38:22.329Z,1745386702.329 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-23T05:38:22.329Z,1745386702.329 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-04-23T05:38:22.716Z,1745386702.716 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-04-23T05:38:22.717Z,1745386702.717 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-23T05:38:22.717Z,1745386702.717 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-04-23T05:38:22.717Z,1745386702.717 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-23T05:38:22.717Z,1745386702.717 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-04-23T05:38:22.717Z,1745386702.717 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-23T05:38:22.717Z,1745386702.717 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-04-23T05:38:23.118Z,1745386703.118 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-04-23T05:38:23.118Z,1745386703.118 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-04-23T05:38:23.118Z,1745386703.118 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-04-23T05:38:23.118Z,1745386703.118 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-04-23T05:38:23.118Z,1745386703.118 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-04-23T05:38:23.118Z,1745386703.118 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-23T05:38:23.118Z,1745386703.118 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-04-23T05:38:23.118Z,1745386703.118 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-04-23T05:38:23.119Z,1745386703.119 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-04-23T05:38:23.119Z,1745386703.119 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-04-23T05:38:23.119Z,1745386703.119 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-04-23T05:38:23.119Z,1745386703.119 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-04-23T05:38:23.119Z,1745386703.119 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-04-23T05:38:23.120Z,1745386703.120 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-04-23T05:38:23.120Z,1745386703.120 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-04-23T05:38:23.120Z,1745386703.120 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-04-23T05:38:23.120Z,1745386703.120 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-04-23T05:38:23.120Z,1745386703.120 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-23T05:38:23.120Z,1745386703.120 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-04-23T05:38:23.120Z,1745386703.120 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-23T05:38:23.121Z,1745386703.121 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-04-23T05:38:23.121Z,1745386703.121 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-04-23T05:38:23.121Z,1745386703.121 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-04-23T05:38:23.121Z,1745386703.121 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-23T05:38:23.121Z,1745386703.121 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-04-23T05:38:23.534Z,1745386703.534 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position -0.45 mm (3 active estimators). 2025-04-23T05:38:27.567Z,1745386707.567 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-04-23T05:38:27.567Z,1745386707.567 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-04-23T05:38:27.567Z,1745386707.567 [keepstation:TransitToStation:C] Running Loop=1 2025-04-23T05:38:27.970Z,1745386707.970 [keepstation:TransitToStation:C] Stopped 2025-04-23T05:38:27.970Z,1745386707.970 [keepstation:TransitToStation:D] Running Loop=1 2025-04-23T05:38:27.971Z,1745386707.971 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-23T05:38:27.971Z,1745386707.971 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-04-23T05:38:27.971Z,1745386707.971 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-23T05:38:27.971Z,1745386707.971 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-23T05:38:27.971Z,1745386707.971 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-04-23T05:38:27.971Z,1745386707.971 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-23T05:38:27.972Z,1745386707.972 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-23T05:38:27.972Z,1745386707.972 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-23T05:38:27.972Z,1745386707.972 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-23T05:38:27.973Z,1745386707.973 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-23T05:38:27.973Z,1745386707.973 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-23T05:38:27.973Z,1745386707.973 [keepstation:DiveCmd:TransitToStation] Stopped 2025-04-23T05:38:27.974Z,1745386707.974 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-04-23T05:38:27.974Z,1745386707.974 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-04-23T05:38:27.974Z,1745386707.974 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-04-23T05:38:27.974Z,1745386707.974 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-04-23T05:38:27.974Z,1745386707.974 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-23T05:38:27.974Z,1745386707.974 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-04-23T05:38:27.974Z,1745386707.974 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-04-23T05:38:27.974Z,1745386707.974 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-04-23T05:38:28.385Z,1745386708.385 [keepstation:TransitToStation:D] Stopped 2025-04-23T05:38:28.386Z,1745386708.386 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-04-23T05:38:28.386Z,1745386708.386 [keepstation:TransitToStation] Stopped 2025-04-23T05:38:28.386Z,1745386708.386 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-04-23T05:38:28.386Z,1745386708.386 [keepstation:TransitToStation:Dive] Stopped 2025-04-23T05:38:28.386Z,1745386708.386 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-04-23T05:38:28.386Z,1745386708.386 [keepstation:TransitToStation:Dive:A] Stopped 2025-04-23T05:38:28.386Z,1745386708.386 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-04-23T05:38:28.386Z,1745386708.386 [keepstation:OnStation] Running Loop=1 2025-04-23T05:38:28.386Z,1745386708.386 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-04-23T05:38:28.786Z,1745386708.786 [keepstation:NeedComms] Running Loop=1 2025-04-23T05:38:28.786Z,1745386708.786 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-23T05:38:28.786Z,1745386708.786 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-23T05:38:28.786Z,1745386708.786 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-23T05:38:28.787Z,1745386708.787 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-23T05:38:28.787Z,1745386708.787 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-23T05:38:28.788Z,1745386708.788 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-23T05:38:28.788Z,1745386708.788 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-23T05:38:28.788Z,1745386708.788 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-23T05:38:28.805Z,1745386708.805 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-23T05:38:28.805Z,1745386708.805 [keepstation:NeedComms:A] Running Loop=1 2025-04-23T05:38:28.806Z,1745386708.806 [keepstation:NeedComms:A](INFO): last time_fix was: 1745386488.000000 second since 1970/01/01T00:00:00Z 2025-04-23T05:38:28.807Z,1745386708.807 [keepstation:NeedComms:A] Stopped 2025-04-23T05:39:06.113Z,1745386746.113 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755402 2025-04-23T05:39:20.095Z,1745386760.095 [keepstation:NeedComms:C] Running Loop=1 2025-04-23T05:39:20.833Z,1745386760.833 [Radio_Surface](INFO): Powering up 2025-04-23T05:39:26.193Z,1745386766.193 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-23T05:39:48.754Z,1745386788.754 [NAL9602](INFO): SBD MO Status=0, MOMSN=20384, MT Status=0, MTMSN=0 2025-04-23T05:39:48.754Z,1745386788.754 [NAL9602](INFO): No messages in MT queue 2025-04-23T05:39:49.966Z,1745386789.966 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053948.00,A,3647.44243,N,12153.64070,W,0.778,141.78,230425,,,D*79 2025-04-23T05:39:49.969Z,1745386789.969 [NAL9602](INFO): GPS fix at 20250423T053948: (36.790707, -121.894012) 2025-04-23T05:39:50.007Z,1745386790.007 [keepstation:NeedComms:C] Stopped 2025-04-23T05:39:50.007Z,1745386790.007 [keepstation:NeedComms:D] Running Loop=1 2025-04-23T05:39:56.313Z,1745386796.313 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T05:40:18.871Z,1745386818.871 [NAL9602](INFO): SBD MO Status=2, MOMSN=20385, MT Status=2, MTMSN=0 2025-04-23T05:40:18.871Z,1745386818.871 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-23T05:40:27.450Z,1745386827.450 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T05:40:37.692Z,1745386837.692 [NAL9602](INFO): SBD MO Status=1, MOMSN=20385, MT Status=0, MTMSN=0 2025-04-23T05:40:37.745Z,1745386837.745 [NAL9602](INFO): Sent 75 bytes from file Logs/20250423T053051/Courier0012.lzma 2025-04-23T05:40:37.745Z,1745386837.745 [NAL9602](INFO): Packets left to send: 0 2025-04-23T05:40:49.474Z,1745386849.474 [NAL9602](INFO): SBD MO Status=1, MOMSN=20386, MT Status=0, MTMSN=0 2025-04-23T05:40:49.529Z,1745386849.529 [NAL9602](INFO): Sent 205 bytes from file Logs/20250423T053051/Express0013.lzma 2025-04-23T05:40:49.529Z,1745386849.529 [NAL9602](INFO): Packets left to send: 0 2025-04-23T05:40:58.585Z,1745386858.585 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T05:41:00.770Z,1745386860.770 [NAL9602](INFO): SBD MO Status=0, MOMSN=20387, MT Status=0, MTMSN=0 2025-04-23T05:41:00.858Z,1745386860.858 [keepstation:NeedComms:D] Stopped 2025-04-23T05:41:00.859Z,1745386860.859 [keepstation:NeedComms:E] Running Loop=1 2025-04-23T05:41:03.197Z,1745386863.197 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,054101.00,A,3647.43364,N,12153.63054,W,0.564,139.55,230425,,,D*76 2025-04-23T05:41:03.199Z,1745386863.199 [NAL9602](INFO): GPS fix at 20250423T054101: (36.790561, -121.893842) 2025-04-23T05:41:03.213Z,1745386863.213 [keepstation:NeedComms:E] Stopped 2025-04-23T05:41:03.214Z,1745386863.214 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-23T05:41:03.214Z,1745386863.214 [keepstation:NeedComms] Stopped 2025-04-23T05:41:03.214Z,1745386863.214 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-23T05:41:03.214Z,1745386863.214 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-23T05:41:03.214Z,1745386863.214 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-23T05:41:03.633Z,1745386863.633 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-04-23T05:41:03.633Z,1745386863.633 [keepstation:OnStation] Stopped 2025-04-23T05:41:03.633Z,1745386863.633 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-04-23T05:41:03.634Z,1745386863.634 [keepstation:KeepStation] Running Loop=1 2025-04-23T05:41:03.634Z,1745386863.634 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-04-23T05:41:03.634Z,1745386863.634 [keepstation:KeepStation:Dive] Running Loop=1 2025-04-23T05:41:03.634Z,1745386863.634 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-04-23T05:41:03.634Z,1745386863.634 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-04-23T05:41:03.634Z,1745386863.634 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-04-23T05:41:03.634Z,1745386863.634 [keepstation:KeepStation:B.Wait] Running Loop=1 2025-04-23T05:41:03.634Z,1745386863.634 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component. 2025-04-23T05:41:04.012Z,1745386864.012 [keepstation:KeepStation:Dive] Running Loop=1 2025-04-23T05:41:29.709Z,1745386889.709 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T05:41:35.922Z,1745386895.922 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-23T05:41:40.989Z,1745386900.989 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003399 2025-04-23T05:43:02.029Z,1745386982.029 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T05:43:33.153Z,1745387013.153 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T05:43:38.601Z,1745387018.601 [Radio_Surface](INFO): Powering down 2025-04-23T05:44:04.277Z,1745387044.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T05:44:05.281Z,1745387045.281 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-23T05:45:09.545Z,1745387109.545 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735336 2025-04-23T06:05:24.638Z,1745388324.638 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-23T06:05:29.117Z,1745388329.117 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.893250 CHAN A1 (24V): 0.844227 CHAN A2 (12V): 0.925058 CHAN A3 (5V): 0.549762 CHAN B0 (3.3V): 0.370885 CHAN B1 (3.15aV): 0.480016 CHAN B2 (3.15bV): 0.479790 CHAN B3 (GND): 0.005697 OPEN: 0.006853 Full Scale: +/- 1 mA 2025-04-23T06:28:00.709Z,1745389680.709 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-23T06:28:00.709Z,1745389680.709 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-23T06:28:00.710Z,1745389680.710 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-23T06:28:00.713Z,1745389680.713 [BPC1](INFO): Received data from all battery sticks. 2025-04-23T07:27:13.985Z,1745393233.985 [BuoyancyServo](ERROR): getPosition uart error serial timeout 2025-04-23T07:27:13.985Z,1745393233.985 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout 2025-04-23T07:27:13.985Z,1745393233.985 [BuoyancyServo] Communications Fault, FailCount= 1 2025-04-23T07:27:13.986Z,1745393233.986 [BuoyancyServo](ERROR): Communications Fault 2025-04-23T07:27:13.991Z,1745393233.991 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2025-04-23T07:27:15.927Z,1745393235.927 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-23T07:27:15.927Z,1745393235.927 [BuoyancyServo](INFO): Powering down 2025-04-23T07:27:16.745Z,1745393236.745 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2025-04-23T07:27:16.746Z,1745393236.746 [BuoyancyServo] No Fault, FailCount= 1 2025-04-23T07:27:17.162Z,1745393237.162 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-04-23T07:27:17.168Z,1745393237.168 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-04-23T07:32:43.575Z,1745393563.575 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-04-23T07:35:36.476Z,1745393736.476 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-23T07:35:36.477Z,1745393736.477 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-23T07:35:36.478Z,1745393736.478 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-23T07:35:36.480Z,1745393736.480 [BPC1](INFO): Received data from all battery sticks. 2025-04-23T07:41:03.317Z,1745394063.317 [keepstation:SurfaceComms] Running Loop=1 2025-04-23T07:41:03.317Z,1745394063.317 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-04-23T07:41:03.318Z,1745394063.318 [keepstation:SurfaceComms:A] Running Loop=1 2025-04-23T07:41:03.318Z,1745394063.318 [keepstation:SurfaceComms:A] Stopped 2025-04-23T07:41:03.318Z,1745394063.318 [keepstation:SurfaceComms:B] Running Loop=1 2025-04-23T07:41:03.318Z,1745394063.318 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-04-23T07:41:03.723Z,1745394063.723 [keepstation:KeepStation:Dive] Stopped 2025-04-23T07:41:03.723Z,1745394063.723 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-04-23T07:41:03.723Z,1745394063.723 [keepstation:KeepStation:Dive:A] Stopped 2025-04-23T07:41:03.723Z,1745394063.723 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-04-23T07:41:03.723Z,1745394063.723 [keepstation:DiveCmd:StationKeep] Stopped 2025-04-23T07:41:03.724Z,1745394063.724 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-04-23T07:41:03.724Z,1745394063.724 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-04-23T07:41:03.724Z,1745394063.724 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-04-23T07:41:03.724Z,1745394063.724 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-04-23T07:41:03.724Z,1745394063.724 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-04-23T07:41:03.726Z,1745394063.726 [keepstation:NeedComms] Running Loop=1 2025-04-23T07:41:03.726Z,1745394063.726 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-23T07:41:03.726Z,1745394063.726 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-23T07:41:03.726Z,1745394063.726 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-23T07:41:03.726Z,1745394063.726 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-23T07:41:03.727Z,1745394063.727 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-23T07:41:03.727Z,1745394063.727 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-23T07:41:03.727Z,1745394063.727 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-23T07:41:03.728Z,1745394063.728 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-23T07:41:03.728Z,1745394063.728 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-23T07:41:03.728Z,1745394063.728 [keepstation:NeedComms:A] Running Loop=1 2025-04-23T07:41:03.730Z,1745394063.730 [keepstation:NeedComms:A](INFO): last time_fix was: 1745386861.000000 second since 1970/01/01T00:00:00Z 2025-04-23T07:41:03.730Z,1745394063.730 [keepstation:NeedComms:A] Stopped 2025-04-23T07:42:13.312Z,1745394133.312 [keepstation:NeedComms:C] Running Loop=1 2025-04-23T07:42:13.873Z,1745394133.873 [Radio_Surface](INFO): Powering up 2025-04-23T07:42:19.545Z,1745394139.545 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-23T07:42:40.771Z,1745394160.771 [NAL9602](INFO): SBD MO Status=0, MOMSN=20388, MT Status=0, MTMSN=0 2025-04-23T07:42:40.771Z,1745394160.771 [NAL9602](INFO): No messages in MT queue 2025-04-23T07:42:41.978Z,1745394161.978 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,074240.00,A,3647.66309,N,12153.18696,W,0.447,291.20,230425,,,A*7C 2025-04-23T07:42:41.981Z,1745394161.981 [NAL9602](INFO): GPS fix at 20250423T074240: (36.794385, -121.886449) 2025-04-23T07:42:41.993Z,1745394161.993 [keepstation:NeedComms:C] Stopped 2025-04-23T07:42:41.994Z,1745394161.994 [keepstation:NeedComms:D] Running Loop=1 2025-04-23T07:42:58.752Z,1745394178.752 [NAL9602](INFO): SBD MO Status=1, MOMSN=20389, MT Status=0, MTMSN=0 2025-04-23T07:42:58.813Z,1745394178.813 [NAL9602](INFO): Sent 207 bytes from file Logs/20250423T053051/Courier0015.lzma 2025-04-23T07:42:58.813Z,1745394178.813 [NAL9602](INFO): Packets left to send: 0 2025-04-23T07:43:08.562Z,1745394188.562 [NAL9602](INFO): SBD MO Status=1, MOMSN=20390, MT Status=0, MTMSN=0 2025-04-23T07:43:08.609Z,1745394188.609 [NAL9602](INFO): Sent 332 bytes from file Logs/20250423T053051/Express0016.lzma 2025-04-23T07:43:08.609Z,1745394188.609 [NAL9602](INFO): Packets left to send: 2 2025-04-23T07:43:21.168Z,1745394201.168 [NAL9602](INFO): SBD MO Status=1, MOMSN=20391, MT Status=0, MTMSN=0 2025-04-23T07:43:21.221Z,1745394201.221 [NAL9602](INFO): Sent 332 bytes from file Logs/20250423T053051/Express0016.lzma 2025-04-23T07:43:21.221Z,1745394201.221 [NAL9602](INFO): Packets left to send: 1 2025-04-23T07:43:31.326Z,1745394211.326 [NAL9602](INFO): SBD MO Status=1, MOMSN=20392, MT Status=0, MTMSN=0 2025-04-23T07:43:31.381Z,1745394211.381 [NAL9602](INFO): Sent 36 bytes from file Logs/20250423T053051/Express0016.lzma 2025-04-23T07:43:31.381Z,1745394211.381 [NAL9602](INFO): Packets left to send: 0 2025-04-23T07:43:51.827Z,1745394231.827 [NAL9602](INFO): SBD MO Status=0, MOMSN=20393, MT Status=0, MTMSN=0 2025-04-23T07:43:51.915Z,1745394231.915 [keepstation:NeedComms:D] Stopped 2025-04-23T07:43:51.915Z,1745394231.915 [keepstation:NeedComms:E] Running Loop=1 2025-04-23T07:43:54.258Z,1745394234.258 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,074352.00,A,3647.65969,N,12153.18225,W,1.555,135.60,230425,,,A*77 2025-04-23T07:43:54.260Z,1745394234.260 [NAL9602](INFO): GPS fix at 20250423T074352: (36.794328, -121.886371) 2025-04-23T07:43:54.273Z,1745394234.273 [keepstation:NeedComms:E] Stopped 2025-04-23T07:43:54.274Z,1745394234.274 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-23T07:43:54.274Z,1745394234.274 [keepstation:NeedComms] Stopped 2025-04-23T07:43:54.274Z,1745394234.274 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-23T07:43:54.275Z,1745394234.275 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-23T07:43:54.275Z,1745394234.275 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-23T07:43:54.690Z,1745394234.690 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-04-23T07:43:54.690Z,1745394234.690 [keepstation:SurfaceComms:B] Stopped 2025-04-23T07:43:54.690Z,1745394234.690 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-04-23T07:43:54.690Z,1745394234.690 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-04-23T07:43:54.690Z,1745394234.690 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-04-23T07:43:54.690Z,1745394234.690 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-04-23T07:43:55.085Z,1745394235.085 [keepstation:SurfaceComms:setTransit] Stopped 2025-04-23T07:43:55.085Z,1745394235.085 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-04-23T07:43:55.086Z,1745394235.086 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-04-23T07:43:55.086Z,1745394235.086 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-04-23T07:43:55.086Z,1745394235.086 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-04-23T07:43:55.086Z,1745394235.086 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-04-23T07:43:55.478Z,1745394235.478 [keepstation:SurfaceComms:setStation] Stopped 2025-04-23T07:43:55.478Z,1745394235.478 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-04-23T07:43:55.478Z,1745394235.478 [keepstation:SurfaceComms:setStation:A] Stopped 2025-04-23T07:43:55.478Z,1745394235.478 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-04-23T07:43:55.478Z,1745394235.478 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-04-23T07:43:55.478Z,1745394235.478 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-04-23T07:43:55.921Z,1745394235.921 [keepstation:SurfaceComms:setSink:A] Stopped 2025-04-23T07:43:55.922Z,1745394235.922 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-04-23T07:43:55.922Z,1745394235.922 [keepstation:SurfaceComms:setSink] Stopped 2025-04-23T07:43:55.922Z,1745394235.922 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-04-23T07:43:55.922Z,1745394235.922 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-04-23T07:43:55.922Z,1745394235.922 [keepstation:SurfaceComms] Stopped 2025-04-23T07:43:55.922Z,1745394235.922 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-04-23T07:43:56.285Z,1745394236.285 [keepstation:KeepStation:Dive] Running Loop=1 2025-04-23T07:43:56.285Z,1745394236.285 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-04-23T07:43:56.285Z,1745394236.285 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-04-23T07:43:56.285Z,1745394236.285 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-04-23T07:43:56.286Z,1745394236.286 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-04-23T07:43:56.286Z,1745394236.286 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-04-23T07:43:56.286Z,1745394236.286 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-04-23T07:43:56.286Z,1745394236.286 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-23T07:43:56.286Z,1745394236.286 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-23T07:43:56.286Z,1745394236.286 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-23T07:43:56.286Z,1745394236.286 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-23T07:43:56.286Z,1745394236.286 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-04-23T07:43:56.286Z,1745394236.286 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-04-23T07:43:56.287Z,1745394236.287 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-04-23T07:43:56.287Z,1745394236.287 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-04-23T07:43:56.287Z,1745394236.287 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-04-23T07:43:56.287Z,1745394236.287 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-04-23T07:44:26.577Z,1745394266.577 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-23T07:44:27.681Z,1745394267.681 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003424 2025-04-23T07:45:51.873Z,1745394351.873 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T07:46:21.529Z,1745394381.529 [Radio_Surface](INFO): Powering down 2025-04-23T07:46:22.997Z,1745394382.997 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T07:46:24.001Z,1745394384.001 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-23T07:47:49.397Z,1745394469.397 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-04-23T07:47:49.398Z,1745394469.398 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-04-23T07:47:49.398Z,1745394469.398 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-23T07:47:49.398Z,1745394469.398 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-04-23T07:47:49.804Z,1745394469.804 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-04-23T07:47:49.809Z,1745394469.809 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-23T07:47:49.809Z,1745394469.809 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-04-23T07:47:49.809Z,1745394469.809 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-04-23T07:47:49.809Z,1745394469.809 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-23T07:47:49.809Z,1745394469.809 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-04-23T07:47:50.202Z,1745394470.202 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-04-23T07:47:50.202Z,1745394470.202 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-23T07:47:50.202Z,1745394470.202 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-04-23T07:47:50.202Z,1745394470.202 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-23T07:47:50.203Z,1745394470.203 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-04-23T07:47:50.203Z,1745394470.203 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-04-23T07:47:50.203Z,1745394470.203 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-23T07:47:50.203Z,1745394470.203 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-04-23T07:47:50.203Z,1745394470.203 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-23T07:47:50.203Z,1745394470.203 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-04-23T07:47:50.203Z,1745394470.203 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-04-23T07:47:50.203Z,1745394470.203 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-04-23T07:47:50.602Z,1745394470.602 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-23T07:47:50.602Z,1745394470.602 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-04-23T07:47:50.602Z,1745394470.602 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-23T07:47:50.602Z,1745394470.602 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-23T07:47:50.602Z,1745394470.602 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-04-23T07:47:50.603Z,1745394470.603 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-23T07:47:50.603Z,1745394470.603 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-23T07:47:59.393Z,1745394479.393 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735245 2025-04-23T08:43:12.286Z,1745397792.286 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-23T08:43:12.287Z,1745397792.287 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-23T08:43:12.287Z,1745397792.287 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-23T08:43:12.290Z,1745397792.290 [BPC1](INFO): Received data from all battery sticks. 2025-04-23T09:43:54.377Z,1745401434.377 [keepstation:SurfaceComms] Running Loop=1 2025-04-23T09:43:54.378Z,1745401434.378 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-04-23T09:43:54.378Z,1745401434.378 [keepstation:SurfaceComms:A] Running Loop=1 2025-04-23T09:43:54.382Z,1745401434.382 [keepstation:SurfaceComms:A] Stopped 2025-04-23T09:43:54.382Z,1745401434.382 [keepstation:SurfaceComms:B] Running Loop=1 2025-04-23T09:43:54.382Z,1745401434.382 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-04-23T09:43:54.384Z,1745401434.384 [keepstation:NeedComms] Running Loop=1 2025-04-23T09:43:54.384Z,1745401434.384 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-23T09:43:54.384Z,1745401434.384 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-23T09:43:54.384Z,1745401434.384 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-23T09:43:54.385Z,1745401434.385 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-23T09:43:54.385Z,1745401434.385 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-23T09:43:54.386Z,1745401434.386 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-23T09:43:54.386Z,1745401434.386 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-23T09:43:54.386Z,1745401434.386 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-23T09:43:54.386Z,1745401434.386 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-23T09:43:54.387Z,1745401434.387 [keepstation:NeedComms:A] Running Loop=1 2025-04-23T09:43:54.388Z,1745401434.388 [keepstation:NeedComms:A](INFO): last time_fix was: 1745394232.000000 second since 1970/01/01T00:00:00Z 2025-04-23T09:43:54.389Z,1745401434.389 [keepstation:NeedComms:A] Stopped 2025-04-23T09:43:54.786Z,1745401434.786 [keepstation:KeepStation:Dive] Stopped 2025-04-23T09:43:54.786Z,1745401434.786 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-04-23T09:43:54.786Z,1745401434.786 [keepstation:KeepStation:Dive:A] Stopped 2025-04-23T09:43:54.786Z,1745401434.786 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-04-23T09:43:54.787Z,1745401434.787 [keepstation:DiveCmd:StationKeep] Stopped 2025-04-23T09:43:54.787Z,1745401434.787 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-04-23T09:43:54.787Z,1745401434.787 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-04-23T09:43:54.787Z,1745401434.787 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-04-23T09:43:54.787Z,1745401434.787 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-04-23T09:43:54.787Z,1745401434.787 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-04-23T09:45:28.982Z,1745401528.982 [keepstation:NeedComms:C] Running Loop=1 2025-04-23T09:45:29.521Z,1745401529.521 [Radio_Surface](INFO): Powering up 2025-04-23T09:45:35.657Z,1745401535.657 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-23T09:46:01.366Z,1745401561.366 [NAL9602](INFO): SBD MO Status=0, MOMSN=20394, MT Status=0, MTMSN=0 2025-04-23T09:46:01.366Z,1745401561.366 [NAL9602](INFO): No messages in MT queue 2025-04-23T09:46:05.412Z,1745401565.412 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,094603.00,A,3647.96797,N,12152.71467,W,1.400,157.76,230425,,,A*77 2025-04-23T09:46:05.414Z,1745401565.414 [NAL9602](INFO): GPS fix at 20250423T094603: (36.799466, -121.878578) 2025-04-23T09:46:05.427Z,1745401565.427 [keepstation:NeedComms:C] Stopped 2025-04-23T09:46:05.427Z,1745401565.427 [keepstation:NeedComms:D] Running Loop=1 2025-04-23T09:46:05.777Z,1745401565.777 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T09:46:30.226Z,1745401590.226 [NAL9602](INFO): SBD MO Status=2, MOMSN=20395, MT Status=2, MTMSN=0 2025-04-23T09:46:30.227Z,1745401590.227 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-23T09:46:36.903Z,1745401596.903 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T09:46:48.645Z,1745401608.645 [NAL9602](INFO): SBD MO Status=1, MOMSN=20395, MT Status=0, MTMSN=0 2025-04-23T09:46:48.697Z,1745401608.697 [NAL9602](INFO): Sent 92 bytes from file Logs/20250423T053051/Courier0018.lzma 2025-04-23T09:46:48.697Z,1745401608.697 [NAL9602](INFO): Packets left to send: 0 2025-04-23T09:47:00.108Z,1745401620.108 [NAL9602](INFO): SBD MO Status=1, MOMSN=20396, MT Status=0, MTMSN=0 2025-04-23T09:47:00.169Z,1745401620.169 [NAL9602](INFO): Sent 332 bytes from file Logs/20250423T053051/Express0019.lzma 2025-04-23T09:47:00.169Z,1745401620.169 [NAL9602](INFO): Packets left to send: 1 2025-04-23T09:47:08.025Z,1745401628.025 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T09:47:11.072Z,1745401631.072 [NAL9602](INFO): SBD MO Status=1, MOMSN=20397, MT Status=0, MTMSN=0 2025-04-23T09:47:11.125Z,1745401631.125 [NAL9602](INFO): Sent 97 bytes from file Logs/20250423T053051/Express0019.lzma 2025-04-23T09:47:11.126Z,1745401631.126 [NAL9602](INFO): Packets left to send: 0 2025-04-23T09:47:23.520Z,1745401643.520 [NAL9602](INFO): SBD MO Status=0, MOMSN=20398, MT Status=0, MTMSN=0 2025-04-23T09:47:23.603Z,1745401643.603 [keepstation:NeedComms:D] Stopped 2025-04-23T09:47:23.603Z,1745401643.603 [keepstation:NeedComms:E] Running Loop=1 2025-04-23T09:47:25.947Z,1745401645.947 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,094724.00,A,3647.97005,N,12152.70996,W,0.369,348.43,230425,,,A*7F 2025-04-23T09:47:25.949Z,1745401645.949 [NAL9602](INFO): GPS fix at 20250423T094724: (36.799501, -121.878499) 2025-04-23T09:47:25.962Z,1745401645.962 [keepstation:NeedComms:E] Stopped 2025-04-23T09:47:25.963Z,1745401645.963 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-23T09:47:25.963Z,1745401645.963 [keepstation:NeedComms] Stopped 2025-04-23T09:47:25.964Z,1745401645.964 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-23T09:47:25.964Z,1745401645.964 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-23T09:47:25.964Z,1745401645.964 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-23T09:47:26.382Z,1745401646.382 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-04-23T09:47:26.382Z,1745401646.382 [keepstation:SurfaceComms:B] Stopped 2025-04-23T09:47:26.382Z,1745401646.382 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-04-23T09:47:26.382Z,1745401646.382 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-04-23T09:47:26.382Z,1745401646.382 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-04-23T09:47:26.382Z,1745401646.382 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-04-23T09:47:26.772Z,1745401646.772 [keepstation:SurfaceComms:setTransit] Stopped 2025-04-23T09:47:26.772Z,1745401646.772 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-04-23T09:47:26.772Z,1745401646.772 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-04-23T09:47:26.772Z,1745401646.772 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-04-23T09:47:26.772Z,1745401646.772 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-04-23T09:47:26.772Z,1745401646.772 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-04-23T09:47:27.189Z,1745401647.189 [keepstation:SurfaceComms:setStation] Stopped 2025-04-23T09:47:27.189Z,1745401647.189 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-04-23T09:47:27.189Z,1745401647.189 [keepstation:SurfaceComms:setStation:A] Stopped 2025-04-23T09:47:27.189Z,1745401647.189 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-04-23T09:47:27.189Z,1745401647.189 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-04-23T09:47:27.189Z,1745401647.189 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-04-23T09:47:27.569Z,1745401647.569 [keepstation:SurfaceComms:setSink:A] Stopped 2025-04-23T09:47:27.569Z,1745401647.569 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-04-23T09:47:27.569Z,1745401647.569 [keepstation:SurfaceComms:setSink] Stopped 2025-04-23T09:47:27.569Z,1745401647.569 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-04-23T09:47:27.569Z,1745401647.569 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-04-23T09:47:27.569Z,1745401647.569 [keepstation:SurfaceComms] Stopped 2025-04-23T09:47:27.569Z,1745401647.569 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-04-23T09:47:27.983Z,1745401647.983 [keepstation:KeepStation:Dive] Running Loop=1 2025-04-23T09:47:27.983Z,1745401647.983 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-04-23T09:47:27.983Z,1745401647.983 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-04-23T09:47:27.984Z,1745401647.984 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-04-23T09:47:27.984Z,1745401647.984 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-04-23T09:47:27.984Z,1745401647.984 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-04-23T09:47:27.985Z,1745401647.985 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-04-23T09:47:27.985Z,1745401647.985 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-23T09:47:27.985Z,1745401647.985 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-23T09:47:27.985Z,1745401647.985 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-23T09:47:27.985Z,1745401647.985 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-23T09:47:27.985Z,1745401647.985 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-04-23T09:47:27.985Z,1745401647.985 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-04-23T09:47:27.986Z,1745401647.986 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-04-23T09:47:27.986Z,1745401647.986 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-04-23T09:47:27.986Z,1745401647.986 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-04-23T09:47:27.986Z,1745401647.986 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-04-23T09:47:39.157Z,1745401659.157 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T09:47:48.041Z,1745401668.041 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003417 2025-04-23T09:47:58.678Z,1745401678.678 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-23T09:49:43.325Z,1745401783.325 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T09:49:59.017Z,1745401799.017 [Radio_Surface](INFO): Powering down 2025-04-23T09:50:14.449Z,1745401814.449 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T09:50:15.453Z,1745401815.453 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-23T09:50:52.829Z,1745401852.829 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-23T09:50:52.830Z,1745401852.830 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-23T09:50:52.831Z,1745401852.831 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-23T09:50:52.838Z,1745401852.838 [BPC1](INFO): Received data from all battery sticks. 2025-04-23T09:51:19.725Z,1745401879.725 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.951218 2025-04-23T09:51:25.137Z,1745401885.137 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-04-23T09:51:25.138Z,1745401885.138 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-04-23T09:51:25.138Z,1745401885.138 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-23T09:51:25.138Z,1745401885.138 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-04-23T09:51:25.566Z,1745401885.566 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-04-23T09:51:25.567Z,1745401885.567 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-23T09:51:25.567Z,1745401885.567 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-04-23T09:51:25.567Z,1745401885.567 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-04-23T09:51:25.567Z,1745401885.567 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-23T09:51:25.567Z,1745401885.567 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-04-23T09:51:25.944Z,1745401885.944 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-04-23T09:51:25.944Z,1745401885.944 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-23T09:51:25.944Z,1745401885.944 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-04-23T09:51:25.944Z,1745401885.944 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-23T09:51:25.945Z,1745401885.945 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-04-23T09:51:25.945Z,1745401885.945 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-04-23T09:51:25.945Z,1745401885.945 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-23T09:51:25.945Z,1745401885.945 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-04-23T09:51:25.945Z,1745401885.945 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-23T09:51:25.945Z,1745401885.945 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-04-23T09:51:25.945Z,1745401885.945 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-04-23T09:51:25.946Z,1745401885.945 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-04-23T09:51:26.346Z,1745401886.346 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-23T09:51:26.346Z,1745401886.346 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-04-23T09:51:26.347Z,1745401886.347 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-23T09:51:26.347Z,1745401886.347 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-23T09:51:26.347Z,1745401886.347 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-04-23T09:51:26.347Z,1745401886.347 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-23T09:51:26.347Z,1745401886.347 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-23T10:58:28.180Z,1745405908.180 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-23T10:58:28.208Z,1745405908.208 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-23T10:58:28.213Z,1745405908.213 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-23T10:58:28.216Z,1745405908.216 [BPC1](INFO): Received data from all battery sticks. 2025-04-23T11:47:26.055Z,1745408846.055 [keepstation:SurfaceComms] Running Loop=1 2025-04-23T11:47:26.055Z,1745408846.055 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-04-23T11:47:26.056Z,1745408846.056 [keepstation:SurfaceComms:A] Running Loop=1 2025-04-23T11:47:26.073Z,1745408846.073 [keepstation:SurfaceComms:A] Stopped 2025-04-23T11:47:26.073Z,1745408846.073 [keepstation:SurfaceComms:B] Running Loop=1 2025-04-23T11:47:26.073Z,1745408846.073 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-04-23T11:47:26.451Z,1745408846.451 [keepstation:KeepStation:Dive] Stopped 2025-04-23T11:47:26.451Z,1745408846.451 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-04-23T11:47:26.451Z,1745408846.451 [keepstation:KeepStation:Dive:A] Stopped 2025-04-23T11:47:26.451Z,1745408846.451 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-04-23T11:47:26.451Z,1745408846.451 [keepstation:DiveCmd:StationKeep] Stopped 2025-04-23T11:47:26.451Z,1745408846.451 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-04-23T11:47:26.452Z,1745408846.452 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-04-23T11:47:26.452Z,1745408846.452 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-04-23T11:47:26.452Z,1745408846.452 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-04-23T11:47:26.452Z,1745408846.452 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-04-23T11:47:26.454Z,1745408846.454 [keepstation:NeedComms] Running Loop=1 2025-04-23T11:47:26.454Z,1745408846.454 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-23T11:47:26.454Z,1745408846.454 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-23T11:47:26.454Z,1745408846.454 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-23T11:47:26.454Z,1745408846.454 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-23T11:47:26.455Z,1745408846.455 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-23T11:47:26.455Z,1745408846.455 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-23T11:47:26.455Z,1745408846.455 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-23T11:47:26.456Z,1745408846.456 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-23T11:47:26.456Z,1745408846.456 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-23T11:47:26.456Z,1745408846.456 [keepstation:NeedComms:A] Running Loop=1 2025-04-23T11:47:26.458Z,1745408846.458 [keepstation:NeedComms:A](INFO): last time_fix was: 1745401644.000000 second since 1970/01/01T00:00:00Z 2025-04-23T11:47:26.458Z,1745408846.458 [keepstation:NeedComms:A] Stopped 2025-04-23T11:48:50.206Z,1745408930.206 [keepstation:NeedComms:C] Running Loop=1 2025-04-23T11:48:50.841Z,1745408930.841 [Radio_Surface](INFO): Powering up 2025-04-23T11:48:56.909Z,1745408936.909 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-23T11:49:27.030Z,1745408967.030 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T11:49:29.774Z,1745408969.774 [NAL9602](INFO): SBD MO Status=0, MOMSN=20399, MT Status=0, MTMSN=0 2025-04-23T11:49:29.774Z,1745408969.774 [NAL9602](INFO): No messages in MT queue 2025-04-23T11:49:30.986Z,1745408970.986 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,114929.00,A,3648.18997,N,12152.30097,W,0.778,37.63,230425,,,A*4E 2025-04-23T11:49:30.989Z,1745408970.989 [NAL9602](INFO): GPS fix at 20250423T114929: (36.803166, -121.871683) 2025-04-23T11:49:31.002Z,1745408971.002 [keepstation:NeedComms:C] Stopped 2025-04-23T11:49:31.002Z,1745408971.002 [keepstation:NeedComms:D] Running Loop=1 2025-04-23T11:49:54.943Z,1745408994.943 [NAL9602](INFO): SBD MO Status=2, MOMSN=20400, MT Status=2, MTMSN=0 2025-04-23T11:49:54.943Z,1745408994.943 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-23T11:49:58.165Z,1745408998.165 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T11:50:20.534Z,1745409020.534 [NAL9602](INFO): SBD MO Status=2, MOMSN=20400, MT Status=2, MTMSN=0 2025-04-23T11:50:20.535Z,1745409020.535 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-23T11:50:29.297Z,1745409029.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T11:50:57.961Z,1745409057.961 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003404 2025-04-23T11:51:01.894Z,1745409061.894 [NAL9602](INFO): SBD MO Status=1, MOMSN=20400, MT Status=0, MTMSN=0 2025-04-23T11:51:01.945Z,1745409061.945 [NAL9602](INFO): Sent 90 bytes from file Logs/20250423T053051/Courier0021.lzma 2025-04-23T11:51:01.945Z,1745409061.945 [NAL9602](INFO): Packets left to send: 0 2025-04-23T11:51:05.288Z,1745409065.288 [DataOverHttps](INFO): Sending 402 bytes from file Logs/20250423T053051/Express0022.lzma 2025-04-23T11:51:05.522Z,1745409065.522 [DataOverHttps](INFO): Moved sent file to Logs/20250423T053051/Express0022.lzma.bak 2025-04-23T11:51:05.523Z,1745409065.523 [DataOverHttps](INFO): SBD MOMSN=24717937 2025-04-23T11:51:07.594Z,1745409067.594 [keepstation:NeedComms:D] Stopped 2025-04-23T11:51:07.594Z,1745409067.594 [keepstation:NeedComms:E] Running Loop=1 2025-04-23T11:51:09.564Z,1745409069.564 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,115107.00,A,3648.19559,N,12152.29768,W,0.505,253.79,230425,,,A*78 2025-04-23T11:51:09.566Z,1745409069.566 [NAL9602](INFO): GPS fix at 20250423T115107: (36.803260, -121.871628) 2025-04-23T11:51:09.583Z,1745409069.583 [keepstation:NeedComms:E] Stopped 2025-04-23T11:51:09.584Z,1745409069.584 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-23T11:51:09.609Z,1745409069.609 [keepstation:NeedComms] Stopped 2025-04-23T11:51:09.609Z,1745409069.609 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-23T11:51:09.610Z,1745409069.610 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-23T11:51:09.610Z,1745409069.610 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-23T11:51:10.026Z,1745409070.026 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-04-23T11:51:10.026Z,1745409070.026 [keepstation:SurfaceComms:B] Stopped 2025-04-23T11:51:10.026Z,1745409070.026 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-04-23T11:51:10.027Z,1745409070.027 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-04-23T11:51:10.027Z,1745409070.027 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-04-23T11:51:10.027Z,1745409070.027 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-04-23T11:51:10.402Z,1745409070.402 [keepstation:SurfaceComms:setTransit] Stopped 2025-04-23T11:51:10.402Z,1745409070.402 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-04-23T11:51:10.402Z,1745409070.402 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-04-23T11:51:10.402Z,1745409070.402 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-04-23T11:51:10.402Z,1745409070.402 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-04-23T11:51:10.402Z,1745409070.402 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-04-23T11:51:10.811Z,1745409070.811 [keepstation:SurfaceComms:setStation] Stopped 2025-04-23T11:51:10.811Z,1745409070.811 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-04-23T11:51:10.812Z,1745409070.812 [keepstation:SurfaceComms:setStation:A] Stopped 2025-04-23T11:51:10.812Z,1745409070.812 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-04-23T11:51:10.817Z,1745409070.817 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-04-23T11:51:10.817Z,1745409070.817 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-04-23T11:51:11.235Z,1745409071.235 [keepstation:SurfaceComms:setSink:A] Stopped 2025-04-23T11:51:11.235Z,1745409071.235 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-04-23T11:51:11.235Z,1745409071.235 [keepstation:SurfaceComms:setSink] Stopped 2025-04-23T11:51:11.235Z,1745409071.235 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-04-23T11:51:11.236Z,1745409071.236 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-04-23T11:51:11.236Z,1745409071.236 [keepstation:SurfaceComms] Stopped 2025-04-23T11:51:11.236Z,1745409071.236 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-04-23T11:51:11.610Z,1745409071.610 [keepstation:KeepStation:Dive] Running Loop=1 2025-04-23T11:51:11.611Z,1745409071.611 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-04-23T11:51:11.611Z,1745409071.611 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-04-23T11:51:11.612Z,1745409071.612 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-04-23T11:51:11.617Z,1745409071.617 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-04-23T11:51:11.617Z,1745409071.617 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-04-23T11:51:11.617Z,1745409071.617 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-04-23T11:51:11.617Z,1745409071.617 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-23T11:51:11.618Z,1745409071.618 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-04-23T11:51:11.618Z,1745409071.618 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-23T11:51:11.618Z,1745409071.618 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-04-23T11:51:11.618Z,1745409071.618 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-04-23T11:51:11.618Z,1745409071.618 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-04-23T11:51:11.618Z,1745409071.618 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-04-23T11:51:11.619Z,1745409071.619 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-04-23T11:51:11.619Z,1745409071.619 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-04-23T11:51:11.619Z,1745409071.619 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-04-23T11:51:41.590Z,1745409101.590 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-23T11:53:17.629Z,1745409197.629 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T11:53:42.529Z,1745409222.529 [Radio_Surface](INFO): Powering down 2025-04-23T11:53:48.753Z,1745409228.753 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-23T11:53:49.757Z,1745409229.757 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-23T11:55:10.767Z,1745409310.767 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-04-23T11:55:10.767Z,1745409310.767 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-04-23T11:55:10.767Z,1745409310.767 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-23T11:55:10.767Z,1745409310.767 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-04-23T11:55:11.181Z,1745409311.181 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-04-23T11:55:11.181Z,1745409311.181 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-04-23T11:55:11.181Z,1745409311.181 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-04-23T11:55:11.182Z,1745409311.182 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-04-23T11:55:11.182Z,1745409311.182 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-23T11:55:11.182Z,1745409311.182 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-04-23T11:55:11.569Z,1745409311.569 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-04-23T11:55:11.569Z,1745409311.569 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-23T11:55:11.569Z,1745409311.569 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-04-23T11:55:11.569Z,1745409311.569 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-04-23T11:55:11.570Z,1745409311.570 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-04-23T11:55:11.570Z,1745409311.570 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-04-23T11:55:11.570Z,1745409311.570 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-04-23T11:55:11.570Z,1745409311.570 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-04-23T11:55:11.570Z,1745409311.570 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-23T11:55:11.570Z,1745409311.570 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-04-23T11:55:11.570Z,1745409311.570 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-04-23T11:55:11.571Z,1745409311.571 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-04-23T11:55:11.976Z,1745409311.976 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-04-23T11:55:11.977Z,1745409311.977 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-04-23T11:55:11.977Z,1745409311.977 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-04-23T11:55:11.977Z,1745409311.977 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-04-23T11:55:11.977Z,1745409311.977 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-04-23T11:55:11.977Z,1745409311.977 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-04-23T11:55:11.977Z,1745409311.977 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-23T11:55:25.158Z,1745409325.158 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747635 2025-04-23T12:05:24.998Z,1745409924.998 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-23T12:05:29.501Z,1745409929.501 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.891615 CHAN A1 (24V): 0.841728 CHAN A2 (12V): 0.921406 CHAN A3 (5V): 0.512053 CHAN B0 (3.3V): 0.343787 CHAN B1 (3.15aV): 0.435415 CHAN B2 (3.15bV): 0.435521 CHAN B3 (GND): 0.005252 OPEN: 0.026265 Full Scale: +/- 1 mA 2025-04-23T12:06:07.260Z,1745409967.260 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-23T12:06:07.261Z,1745409967.261 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-23T12:06:07.262Z,1745409967.262 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-04-23T12:06:07.264Z,1745409967.264 [BPC1](INFO): Received data from all battery sticks.