2025-08-21T03:31:00.657Z,1755747060.657 [CommandExec](IMPORTANT): got command restart logs
2025-08-21T03:31:00.659Z,1755747060.659 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 30.60 mm.
2025-08-21T03:31:00.661Z,1755747060.661 [ElevatorOffsetCalculator](IMPORTANT): Completed estimation for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.60 mm. Average elevator angle=-3.424768 +/- 0.091472 deg (conf. level 99.90%, sigma: 0.428610 deg).
2025-08-21T03:31:00.663Z,1755747060.663 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm.
2025-08-21T03:31:00.697Z,1755747060.697 [keepstation:D] Stopped
2025-08-21T03:31:00.697Z,1755747060.697 [keepstation:E] Running Loop=1
2025-08-21T03:31:01.145Z,1755747061.145 [keepstation:E] Stopped
2025-08-21T03:31:01.145Z,1755747061.145 [keepstation:StartingMission] Running Loop=1
2025-08-21T03:31:01.145Z,1755747061.145 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-08-21T03:31:01.491Z,1755747061.491 [keepstation:SurfaceComms] Running Loop=1
2025-08-21T03:31:01.492Z,1755747061.492 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-21T03:31:01.492Z,1755747061.492 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-21T03:31:01.492Z,1755747061.492 [keepstation:SurfaceComms:A] Stopped
2025-08-21T03:31:01.492Z,1755747061.492 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-21T03:31:01.492Z,1755747061.492 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-21T03:31:01.897Z,1755747061.897 [keepstation:NeedComms] Running Loop=1
2025-08-21T03:31:01.897Z,1755747061.897 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-21T03:31:01.897Z,1755747061.897 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-21T03:31:01.897Z,1755747061.897 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-21T03:31:01.897Z,1755747061.897 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-21T03:31:01.898Z,1755747061.898 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-21T03:31:01.898Z,1755747061.898 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-21T03:31:01.898Z,1755747061.898 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-21T03:31:01.899Z,1755747061.899 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-21T03:31:01.899Z,1755747061.899 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-21T03:31:01.907Z,1755747061.907 [keepstation:NeedComms:A] Running Loop=1
2025-08-21T03:31:01.909Z,1755747061.909 [keepstation:NeedComms:A](INFO): last time_fix was:  1755744190.000000 second since 1970/01/01T00:00:00Z 
2025-08-21T03:31:01.909Z,1755747061.909 [keepstation:NeedComms:A] Stopped
2025-08-21T03:31:02.386Z,1755747062.386 [keepstation:NeedComms:C] Running Loop=1
2025-08-21T03:31:02.694Z,1755747062.694 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-08-21T03:31:16.656Z,1755747076.656 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist
2025-08-21T03:31:20.072Z,1755747080.072 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003133
2025-08-21T03:32:36.069Z,1755747156.069 [NAL9602](INFO): SBD MO Status=1, MOMSN=22163, MT Status=0, MTMSN=0
2025-08-21T03:32:36.070Z,1755747156.070 [NAL9602](INFO): No messages in MT queue
2025-08-21T03:32:37.257Z,1755747157.257 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033237.00,A,3647.38589,N,12154.27774,W,0.505,39.05,210825,,,A*42
2025-08-21T03:32:37.264Z,1755747157.264 [NAL9602](INFO): GPS fix at 20250821T033237: (36.789765, -121.904629)
2025-08-21T03:32:37.309Z,1755747157.309 [UniversalFixResidualReporter](INFO): Fix residual: 12.7 %DT, over the last 2897.2 m. Residual distance 369.0 m at bearing -24.1 degrees. Fix at (36.7898, -121.9046) with 1699.5 m made good.
2025-08-21T03:32:37.311Z,1755747157.311 [keepstation:NeedComms:C] Stopped
2025-08-21T03:32:37.320Z,1755747157.320 [keepstation:NeedComms:D] Running Loop=1
2025-08-21T03:32:37.723Z,1755747157.723 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-08-21T03:32:38.173Z,1755747158.173 [DataOverHttps](INFO): Sending 91 bytes from file Logs/20250820T133000/Courier0126.lzma
2025-08-21T03:32:49.122Z,1755747169.122 [DataOverHttps](INFO): Moved sent file to Logs/20250820T133000/Courier0126.lzma.bak
2025-08-21T03:32:49.122Z,1755747169.122 [DataOverHttps](INFO): SBD MOMSN=25874835
2025-08-21T03:33:09.776Z,1755747189.776 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T03:33:16.128Z,1755747196.128 [DataOverHttps](INFO): Sending 332 bytes from file Logs/20250821T033100/Courier0000.lzma
2025-08-21T03:33:27.169Z,1755747207.169 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Courier0000.lzma.bak
2025-08-21T03:33:27.169Z,1755747207.169 [DataOverHttps](INFO): SBD MOMSN=25874839
2025-08-21T03:33:53.865Z,1755747233.865 [DataOverHttps](INFO): Sending 1970 bytes from file Logs/20250820T133000/Express0127.lzma
2025-08-21T03:34:04.906Z,1755747244.906 [DataOverHttps](INFO): Moved sent file to Logs/20250820T133000/Express0127.lzma.bak
2025-08-21T03:34:04.906Z,1755747244.906 [DataOverHttps](INFO): SBD MOMSN=25874843
2025-08-21T03:34:31.981Z,1755747271.981 [DataOverHttps](INFO): Sending 724 bytes from file Logs/20250821T033100/Express0001.lzma
2025-08-21T03:34:43.021Z,1755747283.021 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Express0001.lzma.bak
2025-08-21T03:34:43.021Z,1755747283.021 [DataOverHttps](INFO): SBD MOMSN=25874874
2025-08-21T03:34:44.351Z,1755747284.351 [keepstation:NeedComms:D] Stopped
2025-08-21T03:34:44.352Z,1755747284.352 [keepstation:NeedComms:E] Running Loop=1
2025-08-21T03:34:44.775Z,1755747284.775 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-08-21T03:34:46.355Z,1755747286.355 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033446.00,A,3647.38448,N,12154.27669,W,0.641,176.11,210825,,,A*7F
2025-08-21T03:34:46.357Z,1755747286.357 [NAL9602](INFO): GPS fix at 20250821T033446: (36.789741, -121.904612)
2025-08-21T03:34:46.405Z,1755747286.405 [keepstation:NeedComms:E] Stopped
2025-08-21T03:34:46.407Z,1755747286.407 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-21T03:34:46.407Z,1755747286.407 [keepstation:NeedComms] Stopped
2025-08-21T03:34:46.407Z,1755747286.407 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-21T03:34:46.407Z,1755747286.407 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-21T03:34:46.407Z,1755747286.407 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-21T03:34:46.814Z,1755747286.814 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-21T03:34:46.814Z,1755747286.814 [keepstation:SurfaceComms:B] Stopped
2025-08-21T03:34:46.814Z,1755747286.814 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-21T03:34:46.814Z,1755747286.814 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-21T03:34:46.814Z,1755747286.814 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-21T03:34:46.814Z,1755747286.814 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-21T03:34:47.184Z,1755747287.184 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-21T03:34:47.184Z,1755747287.184 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-21T03:34:47.184Z,1755747287.184 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-21T03:34:47.185Z,1755747287.185 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-21T03:34:47.185Z,1755747287.185 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-21T03:34:47.185Z,1755747287.185 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-21T03:34:47.600Z,1755747287.600 [keepstation:SurfaceComms:setStation] Stopped
2025-08-21T03:34:47.600Z,1755747287.600 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-21T03:34:47.600Z,1755747287.600 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-21T03:34:47.600Z,1755747287.600 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-21T03:34:47.600Z,1755747287.600 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-21T03:34:47.600Z,1755747287.600 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-21T03:34:48.017Z,1755747288.017 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-21T03:34:48.017Z,1755747288.017 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-08-21T03:34:48.017Z,1755747288.017 [keepstation:SurfaceComms:setSink] Stopped
2025-08-21T03:34:48.017Z,1755747288.017 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-21T03:34:48.017Z,1755747288.017 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-21T03:34:48.017Z,1755747288.017 [keepstation:SurfaceComms] Stopped
2025-08-21T03:34:48.017Z,1755747288.017 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-21T03:34:48.440Z,1755747288.440 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-08-21T03:34:48.440Z,1755747288.440 [keepstation:StartingMission] Stopped
2025-08-21T03:34:48.440Z,1755747288.440 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-08-21T03:34:48.440Z,1755747288.440 [keepstation:TransitToStation] Running Loop=1
2025-08-21T03:34:48.440Z,1755747288.440 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-08-21T03:34:48.441Z,1755747288.441 [keepstation:TransitToStation:Dive] Running Loop=1
2025-08-21T03:34:48.441Z,1755747288.441 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-08-21T03:34:48.441Z,1755747288.441 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-08-21T03:34:48.441Z,1755747288.441 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-08-21T03:34:48.441Z,1755747288.441 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-08-21T03:34:48.441Z,1755747288.441 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-08-21T03:34:48.825Z,1755747288.825 [keepstation:TransitToStation:Dive] Running Loop=1
2025-08-21T03:34:48.825Z,1755747288.825 [keepstation:DiveCmd] Running Loop=1
2025-08-21T03:34:48.825Z,1755747288.825 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-08-21T03:34:48.825Z,1755747288.825 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-08-21T03:34:48.825Z,1755747288.825 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-08-21T03:34:48.825Z,1755747288.825 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-08-21T03:34:48.826Z,1755747288.826 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T03:34:48.826Z,1755747288.826 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-21T03:34:48.826Z,1755747288.826 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-21T03:34:48.826Z,1755747288.826 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-08-21T03:34:48.826Z,1755747288.826 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-08-21T03:34:48.826Z,1755747288.826 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-08-21T03:34:48.826Z,1755747288.826 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-08-21T03:34:48.826Z,1755747288.826 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-08-21T03:34:48.826Z,1755747288.826 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-08-21T03:34:48.826Z,1755747288.826 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-08-21T03:34:48.827Z,1755747288.827 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-08-21T03:34:48.827Z,1755747288.827 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-21T03:34:48.827Z,1755747288.827 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-08-21T03:34:48.827Z,1755747288.827 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-08-21T03:34:48.827Z,1755747288.827 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-08-21T03:34:48.827Z,1755747288.827 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-08-21T03:34:48.851Z,1755747288.851 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-21T03:34:48.852Z,1755747288.852 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-21T03:34:48.852Z,1755747288.852 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-21T03:34:48.852Z,1755747288.852 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-21T03:34:48.852Z,1755747288.852 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-21T03:34:48.852Z,1755747288.852 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-21T03:34:48.852Z,1755747288.852 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-21T03:34:48.853Z,1755747288.853 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-21T03:34:48.853Z,1755747288.853 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-21T03:34:48.853Z,1755747288.853 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-21T03:34:48.853Z,1755747288.853 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-21T03:34:48.853Z,1755747288.853 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-21T03:34:48.853Z,1755747288.853 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-21T03:34:48.853Z,1755747288.853 [keepstation:DiveCmd:TransitToStation] Stopped
2025-08-21T03:34:48.853Z,1755747288.853 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-08-21T03:34:48.853Z,1755747288.853 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-08-21T03:34:48.854Z,1755747288.854 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-08-21T03:34:48.854Z,1755747288.854 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-08-21T03:34:48.854Z,1755747288.854 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-21T03:34:48.854Z,1755747288.854 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-08-21T03:34:48.854Z,1755747288.854 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-08-21T03:34:48.854Z,1755747288.854 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-08-21T03:34:48.854Z,1755747288.854 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-08-21T03:34:48.854Z,1755747288.854 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-08-21T03:34:48.855Z,1755747288.855 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-08-21T03:34:48.855Z,1755747288.855 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-08-21T03:34:48.855Z,1755747288.855 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-08-21T03:34:48.855Z,1755747288.855 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-08-21T03:34:48.867Z,1755747288.867 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-21T03:35:08.577Z,1755747308.577 [NAL9602](INFO): SBD MO Status=1, MOMSN=22164, MT Status=0, MTMSN=0
2025-08-21T03:35:08.577Z,1755747308.577 [NAL9602](INFO): No messages in MT queue
2025-08-21T03:35:39.001Z,1755747339.001 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T03:36:45.688Z,1755747405.688 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T03:36:50.202Z,1755747410.202 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-08-21T03:36:50.202Z,1755747410.202 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-08-21T03:36:50.202Z,1755747410.202 [keepstation:TransitToStation:C] Running Loop=1
2025-08-21T03:36:50.516Z,1755747410.516 [keepstation:TransitToStation:C] Stopped
2025-08-21T03:36:50.516Z,1755747410.516 [keepstation:TransitToStation:D] Running Loop=1
2025-08-21T03:36:50.516Z,1755747410.516 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-21T03:36:50.516Z,1755747410.516 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-21T03:36:50.516Z,1755747410.516 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-21T03:36:50.516Z,1755747410.516 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-21T03:36:50.517Z,1755747410.517 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-21T03:36:50.517Z,1755747410.517 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-21T03:36:50.517Z,1755747410.517 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-21T03:36:50.517Z,1755747410.517 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-21T03:36:50.517Z,1755747410.517 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-21T03:36:50.518Z,1755747410.518 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-21T03:36:50.518Z,1755747410.518 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-21T03:36:50.518Z,1755747410.518 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-08-21T03:36:50.519Z,1755747410.519 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-08-21T03:36:50.519Z,1755747410.519 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-08-21T03:36:50.519Z,1755747410.519 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T03:36:50.519Z,1755747410.519 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-08-21T03:36:50.519Z,1755747410.519 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-21T03:36:50.519Z,1755747410.519 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-08-21T03:36:50.519Z,1755747410.519 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-08-21T03:36:50.519Z,1755747410.519 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-08-21T03:36:50.927Z,1755747410.927 [keepstation:TransitToStation:D] Stopped
2025-08-21T03:36:50.928Z,1755747410.928 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-08-21T03:36:50.928Z,1755747410.928 [keepstation:TransitToStation] Stopped
2025-08-21T03:36:50.928Z,1755747410.928 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-08-21T03:36:50.928Z,1755747410.928 [keepstation:TransitToStation:Dive] Stopped
2025-08-21T03:36:50.928Z,1755747410.928 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-08-21T03:36:50.928Z,1755747410.928 [keepstation:TransitToStation:Dive:A] Stopped
2025-08-21T03:36:50.928Z,1755747410.928 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-08-21T03:36:50.929Z,1755747410.929 [keepstation:OnStation] Running Loop=1
2025-08-21T03:36:50.929Z,1755747410.929 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-08-21T03:36:51.351Z,1755747411.351 [keepstation:NeedComms] Running Loop=1
2025-08-21T03:36:51.351Z,1755747411.351 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-21T03:36:51.351Z,1755747411.351 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-21T03:36:51.351Z,1755747411.351 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-21T03:36:51.352Z,1755747411.352 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-21T03:36:51.352Z,1755747411.352 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-21T03:36:51.353Z,1755747411.353 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-21T03:36:51.353Z,1755747411.353 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-21T03:36:51.353Z,1755747411.353 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-21T03:36:51.353Z,1755747411.353 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-21T03:36:51.354Z,1755747411.354 [keepstation:NeedComms:A] Running Loop=1
2025-08-21T03:36:51.356Z,1755747411.356 [keepstation:NeedComms:A](INFO): last time_fix was:  1755747286.000000 second since 1970/01/01T00:00:00Z 
2025-08-21T03:36:51.356Z,1755747411.356 [keepstation:NeedComms:A] Stopped
2025-08-21T03:36:55.410Z,1755747415.410 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE. Altitude:2.400000m. At depth:3.491580m.
2025-08-21T03:37:16.812Z,1755747436.812 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T03:37:20.618Z,1755747440.618 [keepstation:NeedComms:C] Running Loop=1
2025-08-21T03:37:43.340Z,1755747463.340 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-08-21T03:37:47.936Z,1755747467.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T03:38:19.096Z,1755747499.096 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-21T03:39:55.446Z,1755747595.446 [NAL9602](INFO): SBD MO Status=1, MOMSN=22165, MT Status=0, MTMSN=0
2025-08-21T03:39:55.446Z,1755747595.446 [NAL9602](INFO): No messages in MT queue
2025-08-21T03:39:56.643Z,1755747596.643 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033956.00,A,3647.39328,N,12154.27750,W,0.603,29.10,210825,,,A*44
2025-08-21T03:39:56.649Z,1755747596.649 [NAL9602](INFO): GPS fix at 20250821T033956: (36.789888, -121.904625)
2025-08-21T03:39:56.686Z,1755747596.686 [UniversalFixResidualReporter](INFO): Fix residual: 79.3 %DT, over the last 1145.2 m. Residual distance 908.5 m at bearing -160.6 degrees. Fix at (36.7899, -121.9046) with 16.4 m made good.
2025-08-21T03:39:56.694Z,1755747596.694 [keepstation:NeedComms:C] Stopped
2025-08-21T03:39:56.695Z,1755747596.695 [keepstation:NeedComms:D] Running Loop=1
2025-08-21T03:39:57.809Z,1755747597.809 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20250821T033100/Courier0003.lzma
2025-08-21T03:40:08.761Z,1755747608.761 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Courier0003.lzma.bak
2025-08-21T03:40:08.761Z,1755747608.761 [DataOverHttps](INFO): SBD MOMSN=25874880
2025-08-21T03:40:28.464Z,1755747628.464 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T03:40:38.300Z,1755747638.300 [DataOverHttps](INFO): Sending 506 bytes from file Logs/20250821T033100/Express0004.lzma
2025-08-21T03:40:49.341Z,1755747649.341 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Express0004.lzma.bak
2025-08-21T03:40:49.341Z,1755747649.341 [DataOverHttps](INFO): SBD MOMSN=25874884
2025-08-21T03:40:50.706Z,1755747650.706 [keepstation:NeedComms:D] Stopped
2025-08-21T03:40:50.706Z,1755747650.706 [keepstation:NeedComms:E] Running Loop=1
2025-08-21T03:40:52.727Z,1755747652.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034052.00,A,3647.39270,N,12154.27709,W,0.233,49.76,210825,,,D*4A
2025-08-21T03:40:52.732Z,1755747652.732 [NAL9602](INFO): GPS fix at 20250821T034052: (36.789878, -121.904618)
2025-08-21T03:40:52.796Z,1755747652.796 [UniversalFixResidualReporter](INFO): Fix residual: 79.6 %DT, over the last 516.0 m. Residual distance 410.6 m at bearing -176.4 degrees. Fix at (36.7899, -121.9046) with 1.2 m made good.
2025-08-21T03:40:52.809Z,1755747652.809 [keepstation:NeedComms:E] Stopped
2025-08-21T03:40:52.810Z,1755747652.810 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-21T03:40:52.810Z,1755747652.810 [keepstation:NeedComms] Stopped
2025-08-21T03:40:52.810Z,1755747652.810 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-21T03:40:52.810Z,1755747652.810 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-21T03:40:52.810Z,1755747652.810 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-21T03:40:53.165Z,1755747653.165 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-08-21T03:40:53.165Z,1755747653.165 [keepstation:OnStation] Stopped
2025-08-21T03:40:53.165Z,1755747653.165 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-08-21T03:40:53.165Z,1755747653.165 [keepstation:KeepStation] Running Loop=1
2025-08-21T03:40:53.165Z,1755747653.165 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-08-21T03:40:53.165Z,1755747653.165 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-21T03:40:53.165Z,1755747653.165 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-21T03:40:53.165Z,1755747653.165 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-21T03:40:53.165Z,1755747653.165 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-21T03:40:53.166Z,1755747653.166 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-08-21T03:40:53.166Z,1755747653.166 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-08-21T03:40:53.540Z,1755747653.540 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-21T03:41:27.642Z,1755747687.642 [NAL9602](INFO): SBD MO Status=1, MOMSN=22166, MT Status=0, MTMSN=0
2025-08-21T03:41:27.642Z,1755747687.642 [NAL9602](INFO): No messages in MT queue
2025-08-21T03:41:33.830Z,1755747693.830 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.60 mm (1 active estimators).
2025-08-21T03:41:41.119Z,1755747701.119 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 30.60 mm (2 active estimators).
2025-08-21T03:41:51.472Z,1755747711.472 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-21T03:41:58.287Z,1755747718.287 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T03:42:22.600Z,1755747742.600 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T03:42:34.088Z,1755747754.088 [Radio_Surface](INFO): Powering down
2025-08-21T03:42:53.736Z,1755747773.736 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T03:42:54.740Z,1755747774.740 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-21T03:44:09.040Z,1755747849.040 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.333984
2025-08-21T03:45:36.458Z,1755747936.458 [DVL_micro](ERROR): Failed to parse:
:RD,+0000.30,+9999.99,+0000.30,+0000.54
2025-08-21T03:54:51.566Z,1755748491.566 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-08-21T03:56:04.702Z,1755748564.702 [DVL_micro](ERROR): Failed to parse:
:SA,+00.85,+00.04,019.1
2025-08-21T03:56:06.707Z,1755748566.707 [DVL_micro](ERROR): Failed to parse:
:SA,+00.92,+00.10,018.2
2025-08-21T04:05:18.173Z,1755749118.173 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-08-21T04:08:56.274Z,1755749336.274 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+11.1,0000.0,1489.0,000
2025-08-21T04:10:43.625Z,1755749443.625 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-21T04:10:43.626Z,1755749443.626 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-21T04:10:43.627Z,1755749443.627 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-08-21T04:10:43.630Z,1755749443.630 [BPC1](INFO): Received data from all battery sticks.
2025-08-21T04:12:44.998Z,1755749564.998 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-08-21T04:40:53.015Z,1755751253.015 [keepstation:SurfaceComms] Running Loop=1
2025-08-21T04:40:53.015Z,1755751253.015 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-21T04:40:53.015Z,1755751253.015 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-21T04:40:53.016Z,1755751253.016 [keepstation:SurfaceComms:A] Stopped
2025-08-21T04:40:53.016Z,1755751253.016 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-21T04:40:53.016Z,1755751253.016 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-21T04:40:53.390Z,1755751253.390 [keepstation:KeepStation:Dive] Stopped
2025-08-21T04:40:53.390Z,1755751253.390 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-21T04:40:53.391Z,1755751253.391 [keepstation:KeepStation:Dive:A] Stopped
2025-08-21T04:40:53.391Z,1755751253.391 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-21T04:40:53.391Z,1755751253.391 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-21T04:40:53.392Z,1755751253.392 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-21T04:40:53.392Z,1755751253.392 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-21T04:40:53.392Z,1755751253.392 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-21T04:40:53.392Z,1755751253.392 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-21T04:40:53.392Z,1755751253.392 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-21T04:40:53.394Z,1755751253.394 [keepstation:NeedComms] Running Loop=1
2025-08-21T04:40:53.394Z,1755751253.394 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-21T04:40:53.394Z,1755751253.394 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-21T04:40:53.394Z,1755751253.394 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-21T04:40:53.394Z,1755751253.394 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-21T04:40:53.395Z,1755751253.395 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-21T04:40:53.395Z,1755751253.395 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-21T04:40:53.395Z,1755751253.395 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-21T04:40:53.396Z,1755751253.396 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-21T04:40:53.396Z,1755751253.396 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-21T04:40:53.396Z,1755751253.396 [keepstation:NeedComms:A] Running Loop=1
2025-08-21T04:40:53.398Z,1755751253.398 [keepstation:NeedComms:A](INFO): last time_fix was:  1755747652.000000 second since 1970/01/01T00:00:00Z 
2025-08-21T04:40:53.398Z,1755751253.398 [keepstation:NeedComms:A] Stopped
2025-08-21T04:41:40.777Z,1755751300.777 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,000962,-00049,+00000,A
2025-08-21T04:41:40.778Z,1755751300.778 [DVL_micro](ERROR): Failed to parse:
:TS,000962,-00049,+00000,A
2025-08-21T04:42:23.430Z,1755751343.430 [keepstation:NeedComms:C] Running Loop=1
2025-08-21T04:42:24.144Z,1755751344.144 [Radio_Surface](INFO): Powering up
2025-08-21T04:42:29.432Z,1755751349.432 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-21T04:42:44.439Z,1755751364.439 [NAL9602](INFO): SBD MO Status=2, MOMSN=22167, MT Status=2, MTMSN=0
2025-08-21T04:42:44.439Z,1755751364.439 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T04:42:59.552Z,1755751379.552 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T04:43:19.998Z,1755751399.998 [DVL_micro](ERROR): Failed to parse:
:WI,-00291,-00136,-00092,+00000,A
2025-08-21T04:43:21.202Z,1755751401.202 [NAL9602](INFO): SBD MO Status=2, MOMSN=22167, MT Status=2, MTMSN=0
2025-08-21T04:43:21.202Z,1755751401.202 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T04:43:22.068Z,1755751402.068 [DVL_micro](ERROR): Failed to parse:
:BI,-00291,-00501,+00103,+00000,I
2025-08-21T04:43:24.433Z,1755751404.433 [DVL_micro](ERROR): Failed to parse:
:SA,-01.60,-10.79,047.1
2025-08-21T04:43:30.676Z,1755751410.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T04:44:01.800Z,1755751441.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T04:44:20.591Z,1755751460.591 [NAL9602](INFO): SBD MO Status=1, MOMSN=22167, MT Status=0, MTMSN=0
2025-08-21T04:44:20.591Z,1755751460.591 [NAL9602](INFO): No messages in MT queue
2025-08-21T04:44:21.809Z,1755751461.809 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,044421.00,A,3647.78794,N,12153.77839,W,1.244,334.84,210825,,,D*7C
2025-08-21T04:44:21.812Z,1755751461.812 [NAL9602](INFO): GPS fix at 20250821T044421: (36.796466, -121.896306)
2025-08-21T04:44:21.865Z,1755751461.865 [UniversalFixResidualReporter](INFO): Fix residual: 46.8 %DT, over the last 2232.9 m. Residual distance 1045.9 m at bearing -141.3 degrees. Fix at (36.7965, -121.8963) with 1041.3 m made good.
2025-08-21T04:44:21.873Z,1755751461.873 [keepstation:NeedComms:C] Stopped
2025-08-21T04:44:21.873Z,1755751461.873 [keepstation:NeedComms:D] Running Loop=1
2025-08-21T04:44:32.924Z,1755751472.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T04:44:52.296Z,1755751492.296 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003140
2025-08-21T04:45:00.142Z,1755751500.142 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20250821T033100/Courier0006.lzma
2025-08-21T04:45:00.832Z,1755751500.832 [NAL9602](INFO): SBD MO Status=2, MOMSN=22168, MT Status=2, MTMSN=0
2025-08-21T04:45:00.833Z,1755751500.833 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T04:45:11.181Z,1755751511.181 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Courier0006.lzma.bak
2025-08-21T04:45:11.182Z,1755751511.182 [DataOverHttps](INFO): SBD MOMSN=25874952
2025-08-21T04:45:21.029Z,1755751521.029 [DVL_micro](ERROR): Failed to parse:
:WI,+00425,+00000,+00000,+00000,I
2025-08-21T04:45:31.533Z,1755751531.533 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T04:45:38.109Z,1755751538.109 [DataOverHttps](INFO): Sending 726 bytes from file Logs/20250821T033100/Express0007.lzma
2025-08-21T04:45:49.149Z,1755751549.149 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Express0007.lzma.bak
2025-08-21T04:45:49.149Z,1755751549.149 [DataOverHttps](INFO): SBD MOMSN=25874956
2025-08-21T04:45:50.536Z,1755751550.536 [keepstation:NeedComms:D] Stopped
2025-08-21T04:45:50.537Z,1755751550.537 [keepstation:NeedComms:E] Running Loop=1
2025-08-21T04:45:52.544Z,1755751552.544 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,044552.00,A,3647.79508,N,12153.78287,W,0.680,313.89,210825,,,A*7F
2025-08-21T04:45:52.546Z,1755751552.546 [NAL9602](INFO): GPS fix at 20250821T044552: (36.796585, -121.896381)
2025-08-21T04:45:52.574Z,1755751552.574 [keepstation:NeedComms:E] Stopped
2025-08-21T04:45:52.575Z,1755751552.575 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-21T04:45:52.575Z,1755751552.575 [keepstation:NeedComms] Stopped
2025-08-21T04:45:52.579Z,1755751552.579 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-21T04:45:52.579Z,1755751552.579 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-21T04:45:52.580Z,1755751552.580 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-21T04:45:53.021Z,1755751553.021 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-21T04:45:53.021Z,1755751553.021 [keepstation:SurfaceComms:B] Stopped
2025-08-21T04:45:53.021Z,1755751553.021 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-21T04:45:53.021Z,1755751553.021 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-21T04:45:53.021Z,1755751553.021 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-21T04:45:53.021Z,1755751553.021 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-21T04:45:53.374Z,1755751553.374 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-21T04:45:53.374Z,1755751553.374 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-21T04:45:53.374Z,1755751553.374 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-21T04:45:53.374Z,1755751553.374 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-21T04:45:53.374Z,1755751553.374 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-21T04:45:53.374Z,1755751553.374 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-21T04:45:53.782Z,1755751553.782 [keepstation:SurfaceComms:setStation] Stopped
2025-08-21T04:45:53.782Z,1755751553.782 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-21T04:45:53.782Z,1755751553.782 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-21T04:45:53.782Z,1755751553.782 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-21T04:45:53.782Z,1755751553.782 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-21T04:45:53.783Z,1755751553.783 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-21T04:45:54.208Z,1755751554.208 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-21T04:45:54.208Z,1755751554.208 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-08-21T04:45:54.208Z,1755751554.208 [keepstation:SurfaceComms:setSink] Stopped
2025-08-21T04:45:54.208Z,1755751554.208 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-21T04:45:54.209Z,1755751554.209 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-21T04:45:54.209Z,1755751554.209 [keepstation:SurfaceComms] Stopped
2025-08-21T04:45:54.209Z,1755751554.209 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-21T04:45:54.628Z,1755751554.628 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-21T04:45:54.629Z,1755751554.629 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-21T04:45:54.629Z,1755751554.629 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-21T04:45:54.629Z,1755751554.629 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-21T04:45:54.630Z,1755751554.630 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-08-21T04:45:54.630Z,1755751554.630 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-08-21T04:45:54.630Z,1755751554.630 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-08-21T04:45:54.630Z,1755751554.630 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T04:45:54.630Z,1755751554.630 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-21T04:45:54.631Z,1755751554.631 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-21T04:45:54.631Z,1755751554.631 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-08-21T04:45:54.631Z,1755751554.631 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-08-21T04:45:54.631Z,1755751554.631 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-08-21T04:45:54.647Z,1755751554.647 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-08-21T04:45:54.647Z,1755751554.647 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-08-21T04:45:54.701Z,1755751554.701 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-08-21T04:45:54.701Z,1755751554.701 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-08-21T04:47:29.099Z,1755751649.099 [NAL9602](INFO): SBD MO Status=2, MOMSN=22168, MT Status=2, MTMSN=0
2025-08-21T04:47:29.100Z,1755751649.100 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T04:48:16.375Z,1755751696.375 [NAL9602](INFO): SBD MO Status=2, MOMSN=22168, MT Status=2, MTMSN=0
2025-08-21T04:48:16.375Z,1755751696.375 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T04:48:54.850Z,1755751734.850 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-21T04:45:54.6Z
2025-08-21T04:48:54.850Z,1755751734.850 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1
2025-08-21T04:48:54.850Z,1755751734.850 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T04:48:54.851Z,1755751734.851 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1
2025-08-21T04:48:54.856Z,1755751734.856 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of  0.152347 m 
2025-08-21T04:48:54.856Z,1755751734.856 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped
2025-08-21T04:48:54.857Z,1755751734.857 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T04:48:54.857Z,1755751734.857 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-08-21T04:48:54.857Z,1755751734.857 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-08-21T04:48:54.857Z,1755751734.857 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T04:48:54.857Z,1755751734.857 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-08-21T04:48:55.190Z,1755751735.190 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-08-21T04:48:55.191Z,1755751735.191 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T04:48:55.191Z,1755751735.191 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-08-21T04:48:55.191Z,1755751735.191 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-08-21T04:48:55.191Z,1755751735.191 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T04:48:55.191Z,1755751735.191 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-08-21T04:48:55.593Z,1755751735.593 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-08-21T04:48:55.593Z,1755751735.593 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T04:48:55.593Z,1755751735.593 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-08-21T04:48:55.593Z,1755751735.593 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T04:48:55.594Z,1755751735.594 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T04:48:55.594Z,1755751735.594 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-08-21T04:48:55.594Z,1755751735.594 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T04:48:55.594Z,1755751735.594 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-08-21T04:48:55.594Z,1755751735.594 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-21T04:48:55.594Z,1755751735.594 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-08-21T04:48:55.594Z,1755751735.594 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-08-21T04:48:55.595Z,1755751735.595 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-08-21T04:48:56.032Z,1755751736.032 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-21T04:48:56.032Z,1755751736.032 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-21T04:48:56.032Z,1755751736.032 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-21T04:48:56.032Z,1755751736.032 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-21T04:48:56.033Z,1755751736.033 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-21T04:48:56.033Z,1755751736.033 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-21T04:48:56.033Z,1755751736.033 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-21T04:49:16.158Z,1755751756.158 [DVL_micro](ERROR): Failed to parse:
:BI,-00204,-00109,+00233,+00000,I
2025-08-21T04:50:23.216Z,1755751823.216 [DVL_micro](ERROR): Failed to parse:
:SA,+06.08,-04.89,071.6
2025-08-21T04:50:53.076Z,1755751853.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T04:50:54.734Z,1755751854.734 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-21T04:51:24.220Z,1755751884.220 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T04:51:25.442Z,1755751885.442 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T04:51:36.864Z,1755751896.864 [Radio_Surface](INFO): Powering down
2025-08-21T04:51:55.360Z,1755751915.360 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T04:51:56.364Z,1755751916.364 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-21T04:53:11.664Z,1755751991.664 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755249
2025-08-21T04:56:31.374Z,1755752191.374 [DVL_micro](ERROR): Failed to parse::SA,+01.65,+00.25,027.8
2025-08-21T04:58:18.022Z,1755752298.022 [DVL_micro](ERROR): Failed to parse:
:WI,+00222,+00000,+00000,+00000,I
2025-08-21T05:06:51.528Z,1755752811.528 [DVL_micro](ERROR): Failed to parse:
:RD,+0000.30,+9999.99,+0000.30,+0001.63
2025-08-21T05:18:18.813Z,1755753498.813 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-21T05:18:18.814Z,1755753498.814 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-21T05:18:18.815Z,1755753498.815 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-08-21T05:18:18.821Z,1755753498.821 [BPC1](INFO): Received data from all battery sticks.
2025-08-21T05:31:01.189Z,1755754261.189 [DVL_micro](ERROR): Failed to parse:
:WI,+00014,-00136,+00031,+00000,A
2025-08-21T05:40:17.501Z,1755754817.501 [DVL_micro](ERROR): Failed to parse:
:T+00000,A
2025-08-21T05:45:52.824Z,1755755152.824 [keepstation:SurfaceComms] Running Loop=1
2025-08-21T05:45:52.824Z,1755755152.824 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-21T05:45:52.825Z,1755755152.825 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-21T05:45:52.825Z,1755755152.825 [keepstation:SurfaceComms:A] Stopped
2025-08-21T05:45:52.825Z,1755755152.825 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-21T05:45:52.825Z,1755755152.825 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-21T05:45:53.228Z,1755755153.228 [keepstation:KeepStation:Dive] Stopped
2025-08-21T05:45:53.228Z,1755755153.228 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-21T05:45:53.228Z,1755755153.228 [keepstation:KeepStation:Dive:A] Stopped
2025-08-21T05:45:53.228Z,1755755153.228 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-21T05:45:53.229Z,1755755153.229 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-21T05:45:53.229Z,1755755153.229 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-21T05:45:53.229Z,1755755153.229 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-21T05:45:53.229Z,1755755153.229 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-21T05:45:53.229Z,1755755153.229 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-21T05:45:53.229Z,1755755153.229 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-21T05:45:53.231Z,1755755153.231 [keepstation:NeedComms] Running Loop=1
2025-08-21T05:45:53.231Z,1755755153.231 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-21T05:45:53.231Z,1755755153.231 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-21T05:45:53.231Z,1755755153.231 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-21T05:45:53.231Z,1755755153.231 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-21T05:45:53.232Z,1755755153.232 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-21T05:45:53.232Z,1755755153.232 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-21T05:45:53.233Z,1755755153.233 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-21T05:45:53.233Z,1755755153.233 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-21T05:45:53.233Z,1755755153.233 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-21T05:45:53.233Z,1755755153.233 [keepstation:NeedComms:A] Running Loop=1
2025-08-21T05:45:53.235Z,1755755153.235 [keepstation:NeedComms:A](INFO): last time_fix was:  1755751552.000000 second since 1970/01/01T00:00:00Z 
2025-08-21T05:45:53.235Z,1755755153.235 [keepstation:NeedComms:A] Stopped
2025-08-21T05:46:49.975Z,1755755209.975 [DVL_micro](ERROR): Failed to parse:
:SA,+15.92,-03.51,252.9
2025-08-21T05:47:07.415Z,1755755227.415 [keepstation:NeedComms:C] Running Loop=1
2025-08-21T05:47:08.096Z,1755755228.096 [Radio_Surface](INFO): Powering up
2025-08-21T05:47:14.124Z,1755755234.124 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-21T05:47:26.933Z,1755755246.933 [DVL_micro](ERROR): Failed to parse:
:WI,+00000,+00000,+00000,+00000,I
2025-08-21T05:47:40.264Z,1755755260.264 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-21T05:47:40.339Z,1755755260.339 [NAL9602](ERROR): received: 
+CSQ:0

OK168, 2, 0, 0, 0

OK
2025-08-21T05:47:44.244Z,1755755264.244 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T05:47:51.978Z,1755755271.978 [NAL9602](INFO): SBD MO Status=1, MOMSN=22168, MT Status=0, MTMSN=0
2025-08-21T05:47:51.978Z,1755755271.978 [NAL9602](INFO): No messages in MT queue
2025-08-21T05:47:53.193Z,1755755273.193 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,054753.00,A,3647.85812,N,12153.52421,W,0.661,305.94,210825,,,A*7E
2025-08-21T05:47:53.196Z,1755755273.196 [NAL9602](INFO): GPS fix at 20250821T054753: (36.797635, -121.892070)
2025-08-21T05:47:53.209Z,1755755273.209 [keepstation:NeedComms:C] Stopped
2025-08-21T05:47:53.209Z,1755755273.209 [keepstation:NeedComms:D] Running Loop=1
2025-08-21T05:48:09.843Z,1755755289.843 [NAL9602](INFO): SBD MO Status=1, MOMSN=22169, MT Status=0, MTMSN=0
2025-08-21T05:48:09.900Z,1755755289.900 [NAL9602](INFO): Sent 90 bytes from file Logs/20250821T033100/Courier0009.lzma
2025-08-21T05:48:09.900Z,1755755289.900 [NAL9602](INFO): Packets left to send: 0
2025-08-21T05:48:15.368Z,1755755295.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T05:48:23.307Z,1755755303.307 [NAL9602](INFO): SBD MO Status=1, MOMSN=22170, MT Status=0, MTMSN=0
2025-08-21T05:48:23.356Z,1755755303.356 [NAL9602](INFO): Sent 332 bytes from file Logs/20250821T033100/Express0010.lzma
2025-08-21T05:48:23.356Z,1755755303.356 [NAL9602](INFO): Packets left to send: 1
2025-08-21T05:48:34.700Z,1755755314.700 [NAL9602](INFO): SBD MO Status=1, MOMSN=22171, MT Status=0, MTMSN=0
2025-08-21T05:48:34.752Z,1755755314.752 [NAL9602](INFO): Sent 288 bytes from file Logs/20250821T033100/Express0010.lzma
2025-08-21T05:48:34.752Z,1755755314.752 [NAL9602](INFO): Packets left to send: 0
2025-08-21T05:48:44.785Z,1755755324.785 [NAL9602](INFO): SBD MO Status=0, MOMSN=22172, MT Status=0, MTMSN=0
2025-08-21T05:48:44.878Z,1755755324.878 [keepstation:NeedComms:D] Stopped
2025-08-21T05:48:44.878Z,1755755324.878 [keepstation:NeedComms:E] Running Loop=1
2025-08-21T05:48:46.492Z,1755755326.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T05:48:47.206Z,1755755327.206 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,054847.00,A,3647.86333,N,12153.52984,W,0.525,323.18,210825,,,A*7E
2025-08-21T05:48:47.220Z,1755755327.220 [NAL9602](INFO): GPS fix at 20250821T054847: (36.797722, -121.892164)
2025-08-21T05:48:47.233Z,1755755327.233 [keepstation:NeedComms:E] Stopped
2025-08-21T05:48:47.234Z,1755755327.234 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-21T05:48:47.234Z,1755755327.234 [keepstation:NeedComms] Stopped
2025-08-21T05:48:47.234Z,1755755327.234 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-21T05:48:47.234Z,1755755327.234 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-21T05:48:47.234Z,1755755327.234 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-21T05:48:47.649Z,1755755327.649 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-21T05:48:47.649Z,1755755327.649 [keepstation:SurfaceComms:B] Stopped
2025-08-21T05:48:47.649Z,1755755327.649 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-21T05:48:47.649Z,1755755327.649 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-21T05:48:47.649Z,1755755327.649 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-21T05:48:47.649Z,1755755327.649 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-21T05:48:48.028Z,1755755328.028 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-21T05:48:48.028Z,1755755328.028 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-21T05:48:48.028Z,1755755328.028 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-21T05:48:48.028Z,1755755328.028 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-21T05:48:48.028Z,1755755328.028 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-21T05:48:48.029Z,1755755328.029 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-21T05:48:48.638Z,1755755328.638 [keepstation:SurfaceComms:setStation] Stopped
2025-08-21T05:48:48.639Z,1755755328.639 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-21T05:48:48.639Z,1755755328.639 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-21T05:48:48.640Z,1755755328.640 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-21T05:48:48.640Z,1755755328.640 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-21T05:48:48.640Z,1755755328.640 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-21T05:48:48.853Z,1755755328.853 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-21T05:48:48.853Z,1755755328.853 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-08-21T05:48:48.853Z,1755755328.853 [keepstation:SurfaceComms:setSink] Stopped
2025-08-21T05:48:48.853Z,1755755328.853 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-21T05:48:48.853Z,1755755328.853 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-21T05:48:48.853Z,1755755328.853 [keepstation:SurfaceComms] Stopped
2025-08-21T05:48:48.854Z,1755755328.854 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-21T05:48:49.240Z,1755755329.240 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-21T05:48:49.240Z,1755755329.240 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-21T05:48:49.240Z,1755755329.240 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-21T05:48:49.240Z,1755755329.240 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-21T05:48:49.241Z,1755755329.241 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-08-21T05:48:49.241Z,1755755329.241 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-08-21T05:48:49.241Z,1755755329.241 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-08-21T05:48:49.241Z,1755755329.241 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T05:48:49.241Z,1755755329.241 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-21T05:48:49.241Z,1755755329.241 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-21T05:48:49.241Z,1755755329.241 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-08-21T05:48:49.241Z,1755755329.241 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-08-21T05:48:49.241Z,1755755329.241 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-08-21T05:48:49.242Z,1755755329.242 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-08-21T05:48:49.242Z,1755755329.242 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-08-21T05:48:49.242Z,1755755329.242 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-08-21T05:48:49.242Z,1755755329.242 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-08-21T05:49:17.616Z,1755755357.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T05:49:19.531Z,1755755359.531 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T05:49:36.381Z,1755755376.381 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003139
2025-08-21T05:50:50.500Z,1755755450.500 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T05:51:21.625Z,1755755481.625 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T05:51:33.132Z,1755755493.132 [Radio_Surface](INFO): Powering down
2025-08-21T05:51:49.432Z,1755755509.432 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-21T05:48:49.2Z
2025-08-21T05:51:49.432Z,1755755509.432 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1
2025-08-21T05:51:49.432Z,1755755509.432 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T05:51:49.432Z,1755755509.432 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1
2025-08-21T05:51:49.433Z,1755755509.433 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of  6.360464 m 
2025-08-21T05:51:49.433Z,1755755509.433 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped
2025-08-21T05:51:49.433Z,1755755509.433 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T05:51:49.433Z,1755755509.433 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-08-21T05:51:49.433Z,1755755509.433 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-08-21T05:51:49.433Z,1755755509.433 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T05:51:49.433Z,1755755509.433 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-08-21T05:51:49.847Z,1755755509.847 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-08-21T05:51:49.847Z,1755755509.847 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T05:51:49.847Z,1755755509.847 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-08-21T05:51:49.848Z,1755755509.848 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-08-21T05:51:49.848Z,1755755509.848 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T05:51:49.848Z,1755755509.848 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-08-21T05:51:50.250Z,1755755510.250 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-08-21T05:51:50.250Z,1755755510.250 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T05:51:50.251Z,1755755510.251 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-08-21T05:51:50.251Z,1755755510.251 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T05:51:50.251Z,1755755510.251 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T05:51:50.252Z,1755755510.252 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-08-21T05:51:50.252Z,1755755510.252 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T05:51:50.252Z,1755755510.252 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-08-21T05:51:50.252Z,1755755510.252 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-21T05:51:50.252Z,1755755510.252 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-08-21T05:51:50.252Z,1755755510.252 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-08-21T05:51:50.252Z,1755755510.252 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-08-21T05:51:50.642Z,1755755510.642 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-21T05:51:50.643Z,1755755510.643 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-21T05:51:50.643Z,1755755510.643 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-21T05:51:50.643Z,1755755510.643 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-21T05:51:50.643Z,1755755510.643 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-21T05:51:50.643Z,1755755510.643 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-21T05:51:50.643Z,1755755510.643 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-21T05:51:52.752Z,1755755512.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T05:51:53.756Z,1755755513.756 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-21T05:51:59.541Z,1755755519.541 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+14.0,0000.0,14+00157,-00036,+00000,I
2025-08-21T05:51:59.542Z,1755755519.542 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+14.0,0000.0,14+00157,-00036,+00000,I
2025-08-21T05:52:01.141Z,1755755521.141 [DVL_micro](ERROR): Failed to parse:
:SA,+02.54,-00.77,262.6
2025-08-21T05:53:08.067Z,1755755588.067 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.761963
2025-08-21T05:55:48.590Z,1755755748.590 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+13.4,0000.0000
2025-08-21T05:55:48.590Z,1755755748.590 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+13.4,0000.0000
2025-08-21T06:19:32.335Z,1755757172.335 [DVL_micro](ERROR): only read 2 of 4 data items for beam range. Device response is::RD,+9999.99,+9999.9.99
2025-08-21T06:19:32.343Z,1755757172.343 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.9.99
2025-08-21T06:20:59.552Z,1755757259.552 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-08-21T06:25:52.857Z,1755757552.857 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-21T06:25:52.858Z,1755757552.858 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-21T06:25:52.859Z,1755757552.859 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6.
2025-08-21T06:25:52.861Z,1755757552.861 [BPC1](INFO): Received data from all battery sticks.
2025-08-21T06:28:03.752Z,1755757683.752 [DVL_micro](ERROR): Failed to parse:
:BI,-00202,+00001,-00032,+00000,I
2025-08-21T06:48:47.285Z,1755758927.285 [keepstation:SurfaceComms] Running Loop=1
2025-08-21T06:48:47.286Z,1755758927.286 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-21T06:48:47.286Z,1755758927.286 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-21T06:48:47.286Z,1755758927.286 [keepstation:SurfaceComms:A] Stopped
2025-08-21T06:48:47.286Z,1755758927.286 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-21T06:48:47.286Z,1755758927.286 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-21T06:48:47.678Z,1755758927.678 [keepstation:KeepStation:Dive] Stopped
2025-08-21T06:48:47.678Z,1755758927.678 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-21T06:48:47.678Z,1755758927.678 [keepstation:KeepStation:Dive:A] Stopped
2025-08-21T06:48:47.678Z,1755758927.678 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-21T06:48:47.679Z,1755758927.679 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-21T06:48:47.679Z,1755758927.679 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-21T06:48:47.679Z,1755758927.679 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-21T06:48:47.679Z,1755758927.679 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-21T06:48:47.680Z,1755758927.680 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-21T06:48:47.680Z,1755758927.680 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-21T06:48:47.681Z,1755758927.681 [keepstation:NeedComms] Running Loop=1
2025-08-21T06:48:47.681Z,1755758927.681 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-21T06:48:47.682Z,1755758927.682 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-21T06:48:47.682Z,1755758927.682 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-21T06:48:47.682Z,1755758927.682 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-21T06:48:47.682Z,1755758927.682 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-21T06:48:47.683Z,1755758927.683 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-21T06:48:47.683Z,1755758927.683 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-21T06:48:47.684Z,1755758927.684 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-21T06:48:47.684Z,1755758927.684 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-21T06:48:47.684Z,1755758927.684 [keepstation:NeedComms:A] Running Loop=1
2025-08-21T06:48:47.686Z,1755758927.686 [keepstation:NeedComms:A](INFO): last time_fix was:  1755755327.000000 second since 1970/01/01T00:00:00Z 
2025-08-21T06:48:47.686Z,1755758927.686 [keepstation:NeedComms:A] Stopped
2025-08-21T06:48:50.126Z,1755758930.126 [DVL_micro](ERROR): Failed to parse:
:SA,-00.87,-00.06,057.5
2025-08-21T06:50:34.222Z,1755759034.222 [keepstation:NeedComms:C] Running Loop=1
2025-08-21T06:50:34.628Z,1755759034.628 [Radio_Surface](INFO): Powering up
2025-08-21T06:50:40.402Z,1755759040.402 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-21T06:50:44.327Z,1755759044.327 [DVL_micro](ERROR): Failed to parse:
:WI,-00615,+00000,+00000,+00000,I
2025-08-21T06:51:10.536Z,1755759070.536 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T06:51:41.672Z,1755759101.672 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T06:51:55.019Z,1755759115.019 [NAL9602](INFO): SBD MO Status=2, MOMSN=22173, MT Status=2, MTMSN=0
2025-08-21T06:51:55.019Z,1755759115.019 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T06:52:11.209Z,1755759131.209 [NAL9602](INFO): SBD MO Status=2, MOMSN=22173, MT Status=2, MTMSN=0
2025-08-21T06:52:11.209Z,1755759131.209 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T06:52:12.796Z,1755759132.796 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T06:52:43.924Z,1755759163.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T06:53:02.568Z,1755759182.568 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003746
2025-08-21T06:54:31.406Z,1755759271.406 [DVL_micro](ERROR): Failed to parse:
:BI,+04005,-00086,-00943,+00000,A
2025-08-21T06:54:33.469Z,1755759273.469 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-08-21T06:54:37.033Z,1755759277.033 [NAL9602](INFO): SBD MO Status=2, MOMSN=22173, MT Status=2, MTMSN=0
2025-08-21T06:54:37.033Z,1755759277.033 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T06:54:58.855Z,1755759298.855 [NAL9602](INFO): SBD MO Status=2, MOMSN=22173, MT Status=2, MTMSN=0
2025-08-21T06:54:58.855Z,1755759298.855 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T06:55:30.777Z,1755759330.777 [NAL9602](INFO): SBD MO Status=2, MOMSN=22173, MT Status=2, MTMSN=0
2025-08-21T06:55:30.777Z,1755759330.777 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T06:55:38.033Z,1755759338.033 [DVL_micro](ERROR): Failed to parse:
:WI,+00000,+00000,+00000,+00000,I
2025-08-21T06:55:52.599Z,1755759352.599 [NAL9602](INFO): SBD MO Status=2, MOMSN=22173, MT Status=2, MTMSN=0
2025-08-21T06:55:52.599Z,1755759352.599 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T06:55:52.977Z,1755759352.977 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-21T06:55:54.187Z,1755759354.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,065554.00,A,3647.88049,N,12153.19664,W,0.428,338.09,210825,,,D*7E
2025-08-21T06:55:54.189Z,1755759354.189 [NAL9602](INFO): GPS fix at 20250821T065554: (36.798008, -121.886611)
2025-08-21T06:55:54.233Z,1755759354.233 [UniversalFixResidualReporter](INFO): Fix residual: 55.4 %DT, over the last 750.5 m. Residual distance 415.9 m at bearing -121.6 degrees. Fix at (36.7980, -121.8866) with 495.5 m made good.
2025-08-21T06:55:54.245Z,1755759354.245 [keepstation:NeedComms:C] Stopped
2025-08-21T06:55:54.245Z,1755759354.245 [keepstation:NeedComms:D] Running Loop=1
2025-08-21T06:55:55.207Z,1755759355.207 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20250821T033100/Courier0012.lzma
2025-08-21T06:56:06.121Z,1755759366.121 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Courier0012.lzma.bak
2025-08-21T06:56:06.121Z,1755759366.121 [DataOverHttps](INFO): SBD MOMSN=25875336
2025-08-21T06:56:26.639Z,1755759386.639 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T06:56:34.277Z,1755759394.277 [DataOverHttps](INFO): Sending 631 bytes from file Logs/20250821T033100/Express0013.lzma
2025-08-21T06:56:45.318Z,1755759405.318 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Express0013.lzma.bak
2025-08-21T06:56:45.318Z,1755759405.318 [DataOverHttps](INFO): SBD MOMSN=25875339
2025-08-21T06:56:46.463Z,1755759406.463 [keepstation:NeedComms:D] Stopped
2025-08-21T06:56:46.464Z,1755759406.464 [keepstation:NeedComms:E] Running Loop=1
2025-08-21T06:56:48.477Z,1755759408.477 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,065648.00,A,3647.88607,N,12153.19843,W,0.914,320.59,210825,,,D*79
2025-08-21T06:56:48.479Z,1755759408.479 [NAL9602](INFO): GPS fix at 20250821T065648: (36.798101, -121.886640)
2025-08-21T06:56:48.512Z,1755759408.512 [keepstation:NeedComms:E] Stopped
2025-08-21T06:56:48.513Z,1755759408.513 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-21T06:56:48.513Z,1755759408.513 [keepstation:NeedComms] Stopped
2025-08-21T06:56:48.513Z,1755759408.513 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-21T06:56:48.513Z,1755759408.513 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-21T06:56:48.513Z,1755759408.513 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-21T06:56:48.922Z,1755759408.922 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-21T06:56:48.923Z,1755759408.923 [keepstation:SurfaceComms:B] Stopped
2025-08-21T06:56:48.923Z,1755759408.923 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-21T06:56:48.923Z,1755759408.923 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-21T06:56:48.923Z,1755759408.923 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-21T06:56:48.923Z,1755759408.923 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-21T06:56:49.380Z,1755759409.380 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-21T06:56:49.380Z,1755759409.380 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-21T06:56:49.380Z,1755759409.380 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-21T06:56:49.380Z,1755759409.380 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-21T06:56:49.380Z,1755759409.380 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-21T06:56:49.380Z,1755759409.380 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-21T06:56:49.719Z,1755759409.719 [keepstation:SurfaceComms:setStation] Stopped
2025-08-21T06:56:49.742Z,1755759409.742 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-21T06:56:49.742Z,1755759409.742 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-21T06:56:49.742Z,1755759409.742 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-21T06:56:49.742Z,1755759409.742 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-21T06:56:49.742Z,1755759409.742 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-21T06:56:50.160Z,1755759410.160 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-21T06:56:50.160Z,1755759410.160 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-08-21T06:56:50.160Z,1755759410.160 [keepstation:SurfaceComms:setSink] Stopped
2025-08-21T06:56:50.160Z,1755759410.160 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-21T06:56:50.160Z,1755759410.160 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-21T06:56:50.160Z,1755759410.160 [keepstation:SurfaceComms] Stopped
2025-08-21T06:56:50.160Z,1755759410.160 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-21T06:56:50.548Z,1755759410.548 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-21T06:56:50.548Z,1755759410.548 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-21T06:56:50.549Z,1755759410.549 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-21T06:56:50.549Z,1755759410.549 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-21T06:56:50.549Z,1755759410.549 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-08-21T06:56:50.549Z,1755759410.549 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-08-21T06:56:50.549Z,1755759410.549 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-08-21T06:56:50.549Z,1755759410.549 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T06:56:50.550Z,1755759410.550 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-21T06:56:50.550Z,1755759410.550 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-21T06:56:50.550Z,1755759410.550 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-08-21T06:56:50.550Z,1755759410.550 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-08-21T06:56:50.550Z,1755759410.550 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-08-21T06:56:50.550Z,1755759410.550 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-08-21T06:56:50.551Z,1755759410.551 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-08-21T06:56:50.551Z,1755759410.551 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-08-21T06:56:50.551Z,1755759410.551 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-08-21T06:57:13.921Z,1755759433.921 [NAL9602](INFO): SBD MO Status=2, MOMSN=22173, MT Status=2, MTMSN=0
2025-08-21T06:57:13.921Z,1755759433.921 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T06:57:40.182Z,1755759460.182 [NAL9602](INFO): SBD MO Status=0, MOMSN=22173, MT Status=0, MTMSN=0
2025-08-21T06:57:40.182Z,1755759460.182 [NAL9602](INFO): No messages in MT queue
2025-08-21T06:58:10.888Z,1755759490.888 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T06:58:49.252Z,1755759529.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T06:59:20.376Z,1755759560.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T06:59:24.500Z,1755759564.500 [Radio_Surface](INFO): Powering down
2025-08-21T06:59:50.698Z,1755759590.698 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-21T06:56:50.6Z
2025-08-21T06:59:50.698Z,1755759590.698 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1
2025-08-21T06:59:50.698Z,1755759590.698 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T06:59:50.699Z,1755759590.699 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1
2025-08-21T06:59:50.699Z,1755759590.699 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of  6.714079 m 
2025-08-21T06:59:50.700Z,1755759590.700 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped
2025-08-21T06:59:50.700Z,1755759590.700 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T06:59:50.700Z,1755759590.700 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-08-21T06:59:50.700Z,1755759590.700 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-08-21T06:59:50.700Z,1755759590.700 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T06:59:50.700Z,1755759590.700 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-08-21T06:59:51.094Z,1755759591.094 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-08-21T06:59:51.094Z,1755759591.094 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T06:59:51.094Z,1755759591.094 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-08-21T06:59:51.094Z,1755759591.094 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-08-21T06:59:51.095Z,1755759591.095 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T06:59:51.095Z,1755759591.095 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-08-21T06:59:51.504Z,1755759591.504 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T06:59:51.519Z,1755759591.519 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-08-21T06:59:51.519Z,1755759591.519 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T06:59:51.519Z,1755759591.519 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-08-21T06:59:51.519Z,1755759591.519 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T06:59:51.520Z,1755759591.520 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T06:59:51.520Z,1755759591.520 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-08-21T06:59:51.520Z,1755759591.520 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T06:59:51.520Z,1755759591.520 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-08-21T06:59:51.520Z,1755759591.520 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-21T06:59:51.520Z,1755759591.520 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-08-21T06:59:51.521Z,1755759591.521 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-08-21T06:59:51.521Z,1755759591.521 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-08-21T06:59:51.929Z,1755759591.929 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-21T06:59:51.929Z,1755759591.929 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-21T06:59:51.929Z,1755759591.929 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-21T06:59:51.929Z,1755759591.929 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-21T06:59:51.929Z,1755759591.929 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-21T06:59:51.929Z,1755759591.929 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-21T06:59:51.930Z,1755759591.930 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-21T06:59:52.508Z,1755759592.508 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-21T07:01:06.804Z,1755759666.804 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.731110
2025-08-21T07:13:55.450Z,1755760435.450 [DVL_micro](ERROR): only read 2 of 4 data items for beam range. Device response is::RD,+9999.99,+9
2025-08-21T07:13:55.450Z,1755760435.450 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9
2025-08-21T07:29:51.710Z,1755761391.710 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+1106,-00016,+00000,I
2025-08-21T07:29:51.711Z,1755761391.711 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+1106,-00016,+00000,I
2025-08-21T07:30:15.146Z,1755761415.146 [DVL_micro](ERROR): Failed to parse:
:WI,-00032,-00303,-00012,+00000,A
2025-08-21T07:33:28.262Z,1755761608.262 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-21T07:33:28.262Z,1755761608.262 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-21T07:33:28.264Z,1755761608.264 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-08-21T07:33:28.266Z,1755761608.266 [BPC1](INFO): Received data from all battery sticks.
2025-08-21T07:40:05.388Z,1755762005.388 [DVL_micro](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BI,-00186,-00

2025-08-21T07:40:05.389Z,1755762005.389 [DVL_micro](ERROR): Failed to parse:
:BI,-00186,-00

2025-08-21T07:48:43.333Z,1755762523.333 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-08-21T07:56:48.533Z,1755763008.533 [keepstation:SurfaceComms] Running Loop=1
2025-08-21T07:56:48.533Z,1755763008.533 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-21T07:56:48.533Z,1755763008.533 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-21T07:56:48.533Z,1755763008.533 [keepstation:SurfaceComms:A] Stopped
2025-08-21T07:56:48.534Z,1755763008.534 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-21T07:56:48.534Z,1755763008.534 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-21T07:56:48.536Z,1755763008.536 [keepstation:NeedComms] Running Loop=1
2025-08-21T07:56:48.536Z,1755763008.536 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-21T07:56:48.536Z,1755763008.536 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-21T07:56:48.536Z,1755763008.536 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-21T07:56:48.536Z,1755763008.536 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-21T07:56:48.537Z,1755763008.537 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-21T07:56:48.537Z,1755763008.537 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-21T07:56:48.537Z,1755763008.537 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-21T07:56:48.538Z,1755763008.538 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-21T07:56:48.538Z,1755763008.538 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-21T07:56:48.538Z,1755763008.538 [keepstation:NeedComms:A] Running Loop=1
2025-08-21T07:56:48.540Z,1755763008.540 [keepstation:NeedComms:A](INFO): last time_fix was:  1755759408.000000 second since 1970/01/01T00:00:00Z 
2025-08-21T07:56:48.540Z,1755763008.540 [keepstation:NeedComms:A] Stopped
2025-08-21T07:56:48.940Z,1755763008.940 [keepstation:KeepStation:Dive] Stopped
2025-08-21T07:56:48.940Z,1755763008.940 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-21T07:56:48.940Z,1755763008.940 [keepstation:KeepStation:Dive:A] Stopped
2025-08-21T07:56:48.940Z,1755763008.940 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-21T07:56:48.941Z,1755763008.941 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-21T07:56:48.941Z,1755763008.941 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-21T07:56:48.941Z,1755763008.941 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-21T07:56:48.941Z,1755763008.941 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-21T07:56:48.941Z,1755763008.941 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-21T07:56:48.941Z,1755763008.941 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-21T07:57:58.839Z,1755763078.839 [keepstation:NeedComms:C] Running Loop=1
2025-08-21T07:57:59.352Z,1755763079.352 [Radio_Surface](INFO): Powering up
2025-08-21T07:58:05.136Z,1755763085.136 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-21T07:58:35.256Z,1755763115.256 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T07:58:53.779Z,1755763133.779 [NAL9602](INFO): SBD MO Status=0, MOMSN=22174, MT Status=0, MTMSN=0
2025-08-21T07:58:53.779Z,1755763133.779 [NAL9602](INFO): No messages in MT queue
2025-08-21T07:58:54.994Z,1755763134.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,075855.00,A,3647.73842,N,12152.94068,W,0.972,312.24,210825,,,D*7F
2025-08-21T07:58:54.997Z,1755763134.997 [NAL9602](INFO): GPS fix at 20250821T075855: (36.795640, -121.882345)
2025-08-21T07:58:55.010Z,1755763135.010 [keepstation:NeedComms:C] Stopped
2025-08-21T07:58:55.010Z,1755763135.010 [keepstation:NeedComms:D] Running Loop=1
2025-08-21T07:59:06.396Z,1755763146.396 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T07:59:12.045Z,1755763152.045 [NAL9602](INFO): SBD MO Status=1, MOMSN=22175, MT Status=0, MTMSN=0
2025-08-21T07:59:12.096Z,1755763152.096 [NAL9602](INFO): Sent 90 bytes from file Logs/20250821T033100/Courier0015.lzma
2025-08-21T07:59:12.096Z,1755763152.096 [NAL9602](INFO): Packets left to send: 0
2025-08-21T07:59:22.245Z,1755763162.245 [NAL9602](INFO): SBD MO Status=1, MOMSN=22176, MT Status=0, MTMSN=0
2025-08-21T07:59:22.300Z,1755763162.300 [NAL9602](INFO): Sent 332 bytes from file Logs/20250821T033100/Express0016.lzma
2025-08-21T07:59:22.300Z,1755763162.300 [NAL9602](INFO): Packets left to send: 1
2025-08-21T07:59:27.226Z,1755763167.226 [DVL_micro](ERROR): Failed to parse:
:SA,-04.13,+05.51,224.7
2025-08-21T07:59:29.230Z,1755763169.230 [DVL_micro](ERROR): Failed to parse:
:SA,-04.47,-03.42,220.8
2025-08-21T07:59:29.232Z,1755763169.232 [NAL9602](INFO): SBD MO Status=1, MOMSN=22177, MT Status=0, MTMSN=0
2025-08-21T07:59:29.276Z,1755763169.276 [NAL9602](INFO): Sent 81 bytes from file Logs/20250821T033100/Express0016.lzma
2025-08-21T07:59:29.276Z,1755763169.276 [NAL9602](INFO): Packets left to send: 0
2025-08-21T07:59:37.520Z,1755763177.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T07:59:38.512Z,1755763178.512 [NAL9602](INFO): SBD MO Status=0, MOMSN=22178, MT Status=0, MTMSN=0
2025-08-21T07:59:38.584Z,1755763178.584 [keepstation:NeedComms:D] Stopped
2025-08-21T07:59:38.584Z,1755763178.584 [keepstation:NeedComms:E] Running Loop=1
2025-08-21T07:59:40.922Z,1755763180.922 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,075941.00,A,3647.74217,N,12152.94333,W,1.050,58.79,210825,,,A*43
2025-08-21T07:59:40.924Z,1755763180.924 [NAL9602](INFO): GPS fix at 20250821T075941: (36.795703, -121.882389)
2025-08-21T07:59:40.948Z,1755763180.948 [keepstation:NeedComms:E] Stopped
2025-08-21T07:59:40.949Z,1755763180.949 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-21T07:59:40.949Z,1755763180.949 [keepstation:NeedComms] Stopped
2025-08-21T07:59:40.949Z,1755763180.949 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-21T07:59:40.949Z,1755763180.949 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-21T07:59:40.950Z,1755763180.950 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-21T07:59:41.401Z,1755763181.401 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-21T07:59:41.401Z,1755763181.401 [keepstation:SurfaceComms:B] Stopped
2025-08-21T07:59:41.401Z,1755763181.401 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-21T07:59:41.401Z,1755763181.401 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-21T07:59:41.401Z,1755763181.401 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-21T07:59:41.401Z,1755763181.401 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-21T07:59:41.758Z,1755763181.758 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-21T07:59:41.759Z,1755763181.759 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-21T07:59:41.759Z,1755763181.759 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-21T07:59:41.759Z,1755763181.759 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-21T07:59:41.759Z,1755763181.759 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-21T07:59:41.759Z,1755763181.759 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-21T07:59:42.145Z,1755763182.145 [keepstation:SurfaceComms:setStation] Stopped
2025-08-21T07:59:42.145Z,1755763182.145 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-21T07:59:42.145Z,1755763182.145 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-21T07:59:42.145Z,1755763182.145 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-21T07:59:42.145Z,1755763182.145 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-21T07:59:42.145Z,1755763182.145 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-21T07:59:42.550Z,1755763182.550 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-21T07:59:42.550Z,1755763182.550 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-08-21T07:59:42.550Z,1755763182.550 [keepstation:SurfaceComms:setSink] Stopped
2025-08-21T07:59:42.550Z,1755763182.550 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-21T07:59:42.550Z,1755763182.550 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-21T07:59:42.550Z,1755763182.550 [keepstation:SurfaceComms] Stopped
2025-08-21T07:59:42.551Z,1755763182.551 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-21T07:59:43.008Z,1755763183.008 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-21T07:59:43.008Z,1755763183.008 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-21T07:59:43.008Z,1755763183.008 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-21T07:59:43.008Z,1755763183.008 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-21T07:59:43.009Z,1755763183.009 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-08-21T07:59:43.009Z,1755763183.009 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-08-21T07:59:43.009Z,1755763183.009 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-08-21T07:59:43.009Z,1755763183.009 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T07:59:43.009Z,1755763183.009 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-21T07:59:43.009Z,1755763183.009 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-21T07:59:43.009Z,1755763183.009 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-08-21T07:59:43.009Z,1755763183.009 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-08-21T07:59:43.009Z,1755763183.009 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-08-21T07:59:43.010Z,1755763183.010 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-08-21T07:59:43.010Z,1755763183.010 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-08-21T07:59:43.010Z,1755763183.010 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-08-21T07:59:43.010Z,1755763183.010 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-08-21T07:59:55.539Z,1755763195.539 [DVL_micro](ERROR): DVL uart error: serial timeout
2025-08-21T07:59:55.539Z,1755763195.539 [DVL_micro] Communications Fault, FailCount= 1
2025-08-21T07:59:55.539Z,1755763195.539 [DVL_micro](ERROR): Communications Fault
2025-08-21T07:59:55.540Z,1755763195.540 [DVL_micro](ERROR): Failed to parse:
2025-08-21T07:59:55.612Z,1755763195.612 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-08-21T07:59:55.968Z,1755763195.968 [DVL_micro](INFO): Powering down
2025-08-21T07:59:56.877Z,1755763196.877 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-08-21T07:59:56.877Z,1755763196.877 [DVL_micro] No Fault, FailCount= 1
2025-08-21T08:00:08.644Z,1755763208.644 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T08:00:13.649Z,1755763213.649 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T08:00:14.088Z,1755763214.088 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-08-21T08:00:16.088Z,1755763216.088 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-08-21T08:00:18.119Z,1755763218.119 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-08-21T08:00:20.136Z,1755763220.136 [DVL_micro](ERROR): Failed to parse:
:SA,-00.69,-03.85,225.3
2025-08-21T08:00:22.134Z,1755763222.134 [DVL_micro](ERROR): No DVL communication! Re-initializing
2025-08-21T08:00:22.134Z,1755763222.134 [DVL_micro] Communications Fault, FailCount= 1
2025-08-21T08:00:22.134Z,1755763222.134 [DVL_micro](ERROR): Communications Fault
2025-08-21T08:00:22.181Z,1755763222.181 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-08-21T08:00:22.636Z,1755763222.636 [DVL_micro](INFO): Powering down
2025-08-21T08:00:23.417Z,1755763223.417 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-08-21T08:00:23.417Z,1755763223.417 [DVL_micro] No Fault, FailCount= 1
2025-08-21T08:00:27.584Z,1755763227.584 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003120
2025-08-21T08:00:40.730Z,1755763240.730 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-08-21T08:00:42.758Z,1755763242.758 [DVL_micro](ERROR): Failed to parse:
:BI,+00455,+00157,+00036,+00000,I
2025-08-21T08:00:44.767Z,1755763244.767 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-08-21T08:00:46.784Z,1755763246.784 [DVL_micro](ERROR): Failed to parse:
:SA,+07.42,+00.55,228.3
2025-08-21T08:00:48.797Z,1755763248.797 [DVL_micro](ERROR): No DVL communication! Re-initializing
2025-08-21T08:00:48.797Z,1755763248.797 [DVL_micro] Communications Fault, FailCount= 1
2025-08-21T08:00:48.797Z,1755763248.797 [DVL_micro](ERROR): Communications Fault
2025-08-21T08:00:48.821Z,1755763248.821 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-08-21T08:00:49.300Z,1755763249.300 [DVL_micro](INFO): Powering down
2025-08-21T08:00:50.059Z,1755763250.059 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-08-21T08:00:50.059Z,1755763250.059 [DVL_micro] No Fault, FailCount= 1
2025-08-21T08:01:07.406Z,1755763267.406 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-08-21T08:01:09.441Z,1755763269.441 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-08-21T08:01:10.760Z,1755763270.760 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T08:01:41.896Z,1755763301.896 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T08:01:49.272Z,1755763309.272 [Radio_Surface](INFO): Powering down
2025-08-21T08:02:13.028Z,1755763333.028 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T08:02:14.032Z,1755763334.032 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-21T08:02:43.168Z,1755763363.168 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-21T07:59:43.0Z
2025-08-21T08:02:43.168Z,1755763363.168 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1
2025-08-21T08:02:43.168Z,1755763363.168 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T08:02:43.169Z,1755763363.169 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1
2025-08-21T08:02:43.169Z,1755763363.169 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of  8.771591 m 
2025-08-21T08:02:43.169Z,1755763363.169 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped
2025-08-21T08:02:43.169Z,1755763363.169 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T08:02:43.169Z,1755763363.169 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-08-21T08:02:43.169Z,1755763363.169 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-08-21T08:02:43.170Z,1755763363.170 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T08:02:43.170Z,1755763363.170 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-08-21T08:02:43.558Z,1755763363.558 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-08-21T08:02:43.559Z,1755763363.559 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T08:02:43.559Z,1755763363.559 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-08-21T08:02:43.559Z,1755763363.559 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-08-21T08:02:43.559Z,1755763363.559 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T08:02:43.559Z,1755763363.559 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-08-21T08:02:43.959Z,1755763363.959 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-08-21T08:02:43.959Z,1755763363.959 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T08:02:43.959Z,1755763363.959 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-08-21T08:02:43.960Z,1755763363.960 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T08:02:43.960Z,1755763363.960 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T08:02:43.960Z,1755763363.960 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-08-21T08:02:43.960Z,1755763363.960 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T08:02:43.960Z,1755763363.960 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-08-21T08:02:43.960Z,1755763363.960 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-21T08:02:43.961Z,1755763363.961 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-08-21T08:02:43.961Z,1755763363.961 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-08-21T08:02:43.961Z,1755763363.961 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-08-21T08:02:44.363Z,1755763364.363 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-21T08:02:44.363Z,1755763364.363 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-21T08:02:44.363Z,1755763364.363 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-21T08:02:44.363Z,1755763364.363 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-21T08:02:44.363Z,1755763364.363 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-21T08:02:44.364Z,1755763364.364 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-21T08:02:44.364Z,1755763364.364 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-21T08:03:28.336Z,1755763408.336 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755142
2025-08-21T08:06:48.809Z,1755763608.809 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+13.1,0000.0,1489.0,000
2025-08-21T08:16:00.245Z,1755764160.245 [DVL_micro](ERROR): Failed to parse:
:SA,-01.63,-00.42,253.3
2025-08-21T08:41:06.353Z,1755765666.353 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-21T08:41:06.354Z,1755765666.354 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-21T08:41:06.355Z,1755765666.355 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-08-21T08:41:06.357Z,1755765666.357 [BPC1](INFO): Received data from all battery sticks.
2025-08-21T08:48:52.989Z,1755766132.989 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-08-21T08:48:57.436Z,1755766137.436 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003244
CHAN A1 (24V): 0.002841
CHAN A2 (12V): -0.008465
CHAN A3 (5V): -0.011421
CHAN B0 (3.3V): -0.003155
CHAN B1 (3.15aV): -0.002126
CHAN B2 (3.15bV): -0.001759
CHAN B3 (GND): -0.002208
OPEN: 0.012297
Full Scale: +/- 1 mA
2025-08-21T08:59:41.008Z,1755766781.008 [keepstation:SurfaceComms] Running Loop=1
2025-08-21T08:59:41.008Z,1755766781.008 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-21T08:59:41.008Z,1755766781.008 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-21T08:59:41.009Z,1755766781.009 [keepstation:SurfaceComms:A] Stopped
2025-08-21T08:59:41.009Z,1755766781.009 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-21T08:59:41.009Z,1755766781.009 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-21T08:59:41.010Z,1755766781.010 [keepstation:NeedComms] Running Loop=1
2025-08-21T08:59:41.011Z,1755766781.011 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-21T08:59:41.011Z,1755766781.011 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-21T08:59:41.011Z,1755766781.011 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-21T08:59:41.011Z,1755766781.011 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-21T08:59:41.012Z,1755766781.012 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-21T08:59:41.012Z,1755766781.012 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-21T08:59:41.012Z,1755766781.012 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-21T08:59:41.013Z,1755766781.013 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-21T08:59:41.013Z,1755766781.013 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-21T08:59:41.013Z,1755766781.013 [keepstation:NeedComms:A] Running Loop=1
2025-08-21T08:59:41.015Z,1755766781.015 [keepstation:NeedComms:A](INFO): last time_fix was:  1755763181.000000 second since 1970/01/01T00:00:00Z 
2025-08-21T08:59:41.015Z,1755766781.015 [keepstation:NeedComms:A] Stopped
2025-08-21T08:59:41.406Z,1755766781.406 [keepstation:KeepStation:Dive] Stopped
2025-08-21T08:59:41.406Z,1755766781.406 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-21T08:59:41.406Z,1755766781.406 [keepstation:KeepStation:Dive:A] Stopped
2025-08-21T08:59:41.406Z,1755766781.406 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-21T08:59:41.407Z,1755766781.407 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-21T08:59:41.407Z,1755766781.407 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-21T08:59:41.407Z,1755766781.407 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-21T08:59:41.407Z,1755766781.407 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-21T08:59:41.407Z,1755766781.407 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-21T08:59:41.408Z,1755766781.408 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-21T09:01:09.661Z,1755766869.661 [keepstation:NeedComms:C] Running Loop=1
2025-08-21T09:01:10.152Z,1755766870.152 [Radio_Surface](INFO): Powering up
2025-08-21T09:01:15.816Z,1755766875.816 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-21T09:01:37.619Z,1755766897.619 [NAL9602](INFO): SBD MO Status=2, MOMSN=22179, MT Status=2, MTMSN=0
2025-08-21T09:01:37.619Z,1755766897.619 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T09:01:45.936Z,1755766905.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T09:02:17.060Z,1755766937.060 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T09:02:48.184Z,1755766968.184 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T09:03:19.308Z,1755766999.308 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T09:03:38.400Z,1755767018.400 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003126
2025-08-21T09:04:18.415Z,1755767058.415 [NAL9602](INFO): SBD MO Status=2, MOMSN=22179, MT Status=2, MTMSN=0
2025-08-21T09:04:18.416Z,1755767058.416 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T09:05:08.102Z,1755767108.102 [NAL9602](INFO): SBD MO Status=0, MOMSN=22179, MT Status=0, MTMSN=0
2025-08-21T09:05:08.102Z,1755767108.102 [NAL9602](INFO): No messages in MT queue
2025-08-21T09:05:09.324Z,1755767109.324 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,090509.00,A,3647.59997,N,12152.80161,W,0.719,341.70,210825,,,D*78
2025-08-21T09:05:09.327Z,1755767109.327 [NAL9602](INFO): GPS fix at 20250821T090509: (36.793333, -121.880027)
2025-08-21T09:05:09.378Z,1755767109.378 [keepstation:NeedComms:C] Stopped
2025-08-21T09:05:09.379Z,1755767109.379 [keepstation:NeedComms:D] Running Loop=1
2025-08-21T09:05:17.455Z,1755767117.455 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20250821T033100/Courier0018.lzma
2025-08-21T09:05:28.498Z,1755767128.498 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Courier0018.lzma.bak
2025-08-21T09:05:28.498Z,1755767128.498 [DataOverHttps](INFO): SBD MOMSN=25875469
2025-08-21T09:05:41.666Z,1755767141.666 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T09:05:55.401Z,1755767155.401 [DataOverHttps](INFO): Sending 624 bytes from file Logs/20250821T033100/Express0019.lzma
2025-08-21T09:06:06.445Z,1755767166.445 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Express0019.lzma.bak
2025-08-21T09:06:06.445Z,1755767166.445 [DataOverHttps](INFO): SBD MOMSN=25875473
2025-08-21T09:06:07.608Z,1755767167.608 [keepstation:NeedComms:D] Stopped
2025-08-21T09:06:07.608Z,1755767167.608 [keepstation:NeedComms:E] Running Loop=1
2025-08-21T09:06:09.569Z,1755767169.569 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,090609.00,A,3647.60521,N,12152.80130,W,0.641,11.62,210825,,,D*4D
2025-08-21T09:06:09.575Z,1755767169.575 [NAL9602](INFO): GPS fix at 20250821T090609: (36.793420, -121.880022)
2025-08-21T09:06:09.601Z,1755767169.601 [keepstation:NeedComms:E] Stopped
2025-08-21T09:06:09.602Z,1755767169.602 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-21T09:06:09.602Z,1755767169.602 [keepstation:NeedComms] Stopped
2025-08-21T09:06:09.602Z,1755767169.602 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-21T09:06:09.602Z,1755767169.602 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-21T09:06:09.602Z,1755767169.602 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-21T09:06:10.005Z,1755767170.005 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-21T09:06:10.005Z,1755767170.005 [keepstation:SurfaceComms:B] Stopped
2025-08-21T09:06:10.005Z,1755767170.005 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-21T09:06:10.005Z,1755767170.005 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-21T09:06:10.005Z,1755767170.005 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-21T09:06:10.005Z,1755767170.005 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-21T09:06:10.381Z,1755767170.381 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-21T09:06:10.381Z,1755767170.381 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-21T09:06:10.381Z,1755767170.381 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-21T09:06:10.381Z,1755767170.381 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-21T09:06:10.381Z,1755767170.381 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-21T09:06:10.381Z,1755767170.381 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-21T09:06:10.793Z,1755767170.793 [keepstation:SurfaceComms:setStation] Stopped
2025-08-21T09:06:10.793Z,1755767170.793 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-21T09:06:10.793Z,1755767170.793 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-21T09:06:10.793Z,1755767170.793 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-21T09:06:10.793Z,1755767170.793 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-21T09:06:10.793Z,1755767170.793 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-21T09:06:11.227Z,1755767171.227 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-21T09:06:11.227Z,1755767171.227 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-08-21T09:06:11.227Z,1755767171.227 [keepstation:SurfaceComms:setSink] Stopped
2025-08-21T09:06:11.227Z,1755767171.227 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-21T09:06:11.227Z,1755767171.227 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-21T09:06:11.227Z,1755767171.227 [keepstation:SurfaceComms] Stopped
2025-08-21T09:06:11.256Z,1755767171.256 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-21T09:06:11.620Z,1755767171.620 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-21T09:06:11.620Z,1755767171.620 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-21T09:06:11.620Z,1755767171.620 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-21T09:06:11.620Z,1755767171.620 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-21T09:06:11.621Z,1755767171.621 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-08-21T09:06:11.621Z,1755767171.621 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-08-21T09:06:11.621Z,1755767171.621 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-08-21T09:06:11.621Z,1755767171.621 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T09:06:11.621Z,1755767171.621 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-21T09:06:11.621Z,1755767171.621 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-21T09:06:11.621Z,1755767171.621 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-08-21T09:06:11.621Z,1755767171.621 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-08-21T09:06:11.622Z,1755767171.622 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-08-21T09:06:11.622Z,1755767171.622 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-08-21T09:06:11.622Z,1755767171.622 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-08-21T09:06:11.622Z,1755767171.622 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-08-21T09:06:11.622Z,1755767171.622 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-08-21T09:06:33.801Z,1755767193.801 [NAL9602](INFO): SBD MO Status=2, MOMSN=22180, MT Status=2, MTMSN=0
2025-08-21T09:06:33.801Z,1755767193.801 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T09:07:05.713Z,1755767225.713 [NAL9602](INFO): SBD MO Status=2, MOMSN=22180, MT Status=2, MTMSN=0
2025-08-21T09:07:05.713Z,1755767225.713 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T09:07:38.200Z,1755767258.200 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-21T09:08:09.332Z,1755767289.332 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T09:08:37.532Z,1755767317.532 [Radio_Surface](INFO): Powering down
2025-08-21T09:08:40.464Z,1755767320.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T09:08:41.468Z,1755767321.468 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-21T09:09:11.789Z,1755767351.789 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-21T09:06:11.6Z
2025-08-21T09:09:11.789Z,1755767351.789 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1
2025-08-21T09:09:11.789Z,1755767351.789 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T09:09:11.789Z,1755767351.789 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1
2025-08-21T09:09:11.790Z,1755767351.790 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of  7.269634 m 
2025-08-21T09:09:11.790Z,1755767351.790 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped
2025-08-21T09:09:11.790Z,1755767351.790 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T09:09:11.790Z,1755767351.790 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-08-21T09:09:11.790Z,1755767351.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-08-21T09:09:11.790Z,1755767351.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T09:09:11.790Z,1755767351.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-08-21T09:09:12.212Z,1755767352.212 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-08-21T09:09:12.212Z,1755767352.212 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T09:09:12.212Z,1755767352.212 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-08-21T09:09:12.213Z,1755767352.213 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-08-21T09:09:12.213Z,1755767352.213 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T09:09:12.213Z,1755767352.213 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-08-21T09:09:12.635Z,1755767352.635 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-08-21T09:09:12.635Z,1755767352.635 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T09:09:12.635Z,1755767352.635 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-08-21T09:09:12.636Z,1755767352.636 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T09:09:12.636Z,1755767352.636 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T09:09:12.636Z,1755767352.636 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-08-21T09:09:12.636Z,1755767352.636 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T09:09:12.636Z,1755767352.636 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-08-21T09:09:12.636Z,1755767352.636 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-21T09:09:12.636Z,1755767352.636 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-08-21T09:09:12.637Z,1755767352.637 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-08-21T09:09:12.637Z,1755767352.637 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-08-21T09:09:13.009Z,1755767353.009 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-21T09:09:13.009Z,1755767353.009 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-21T09:09:13.009Z,1755767353.009 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-21T09:09:13.009Z,1755767353.009 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-21T09:09:13.010Z,1755767353.010 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-21T09:09:13.010Z,1755767353.010 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-21T09:09:13.010Z,1755767353.010 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-21T09:09:56.769Z,1755767396.769 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.948273
2025-08-21T09:11:11.745Z,1755767471.745 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-21T09:11:42.454Z,1755767502.454 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T09:21:14.524Z,1755768074.524 [DVL_micro](ERROR): Failed to parse:
:RD,+0000.30,+0001.89,+0000.30,+9999.99
2025-08-21T09:32:44.148Z,1755768764.148 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-08-21T09:37:28.972Z,1755769048.972 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-08-21T09:38:24.324Z,1755769104.324 [DVL_micro](ERROR): Failed to parse:
:WI,+00014,-00060,-00065,+00000,A
2025-08-21T09:41:49.964Z,1755769309.964 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-08-21T09:48:23.901Z,1755769703.901 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-08-21T09:48:24.274Z,1755769704.274 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.60 mm.
2025-08-21T09:48:24.276Z,1755769704.276 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 30.60 mm.
2025-08-21T09:48:41.646Z,1755769721.646 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-21T09:48:41.646Z,1755769721.646 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-21T09:48:41.648Z,1755769721.648 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-08-21T09:48:41.650Z,1755769721.650 [BPC1](INFO): Received data from all battery sticks.
2025-08-21T09:52:48.095Z,1755769968.095 [DVL_micro](ERROR): Failed to parse:
:BI,+00034,-00030,+00058,+00000,I
2025-08-21T09:52:50.505Z,1755769970.505 [DVL_micro](ERROR): Failed to parse:
:SA,-04.32,-00.19,314.7
2025-08-21T10:01:15.111Z,1755770475.111 [DVL_micro](ERROR): Failed to parse:
:SA,-01.95,-00.18,280.1
2025-08-21T10:06:09.640Z,1755770769.640 [keepstation:SurfaceComms] Running Loop=1
2025-08-21T10:06:09.640Z,1755770769.640 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-21T10:06:09.641Z,1755770769.641 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-21T10:06:09.641Z,1755770769.641 [keepstation:SurfaceComms:A] Stopped
2025-08-21T10:06:09.641Z,1755770769.641 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-21T10:06:09.641Z,1755770769.641 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-21T10:06:10.043Z,1755770770.043 [keepstation:KeepStation:Dive] Stopped
2025-08-21T10:06:10.043Z,1755770770.043 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-21T10:06:10.043Z,1755770770.043 [keepstation:KeepStation:Dive:A] Stopped
2025-08-21T10:06:10.043Z,1755770770.043 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-21T10:06:10.044Z,1755770770.044 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-21T10:06:10.044Z,1755770770.044 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-21T10:06:10.044Z,1755770770.044 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-21T10:06:10.044Z,1755770770.044 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-21T10:06:10.044Z,1755770770.044 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-21T10:06:10.044Z,1755770770.044 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-21T10:06:10.046Z,1755770770.046 [keepstation:NeedComms] Running Loop=1
2025-08-21T10:06:10.046Z,1755770770.046 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-21T10:06:10.046Z,1755770770.046 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-21T10:06:10.046Z,1755770770.046 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-21T10:06:10.046Z,1755770770.046 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-21T10:06:10.047Z,1755770770.047 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-21T10:06:10.047Z,1755770770.047 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-21T10:06:10.048Z,1755770770.048 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-21T10:06:10.048Z,1755770770.048 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-21T10:06:10.048Z,1755770770.048 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-21T10:06:10.049Z,1755770770.049 [keepstation:NeedComms:A] Running Loop=1
2025-08-21T10:06:10.050Z,1755770770.050 [keepstation:NeedComms:A](INFO): last time_fix was:  1755767169.000000 second since 1970/01/01T00:00:00Z 
2025-08-21T10:06:10.050Z,1755770770.050 [keepstation:NeedComms:A] Stopped
2025-08-21T10:07:31.378Z,1755770851.378 [keepstation:NeedComms:C] Running Loop=1
2025-08-21T10:07:31.868Z,1755770851.868 [Radio_Surface](INFO): Powering up
2025-08-21T10:07:38.068Z,1755770858.068 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-21T10:08:04.143Z,1755770884.143 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-21T10:08:04.219Z,1755770884.219 [NAL9602](ERROR): received: 
+CSQ:0

OK180, 2, 0, 0, 0

OK
2025-08-21T10:08:08.192Z,1755770888.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T10:08:39.320Z,1755770919.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T10:09:10.452Z,1755770950.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T10:09:41.572Z,1755770981.572 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T10:09:52.805Z,1755770992.805 [NAL9602](INFO): SBD MO Status=2, MOMSN=22180, MT Status=2, MTMSN=0
2025-08-21T10:09:52.806Z,1755770992.806 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T10:10:00.248Z,1755771000.248 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003130
2025-08-21T10:10:11.398Z,1755771011.398 [NAL9602](INFO): SBD MO Status=0, MOMSN=22180, MT Status=0, MTMSN=0
2025-08-21T10:10:11.398Z,1755771011.398 [NAL9602](INFO): No messages in MT queue
2025-08-21T10:10:12.598Z,1755771012.598 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,101012.00,A,3647.74802,N,12152.75345,W,0.467,58.91,210825,,,D*4C
2025-08-21T10:10:12.600Z,1755771012.600 [NAL9602](INFO): GPS fix at 20250821T101012: (36.795800, -121.879224)
2025-08-21T10:10:12.613Z,1755771012.613 [UniversalFixResidualReporter](INFO): Fix residual: 32.5 %DT, over the last 728.0 m. Residual distance 236.7 m at bearing -121.1 degrees. Fix at (36.7958, -121.8792) with 274.0 m made good.
2025-08-21T10:10:12.617Z,1755771012.617 [keepstation:NeedComms:C] Stopped
2025-08-21T10:10:12.617Z,1755771012.617 [keepstation:NeedComms:D] Running Loop=1
2025-08-21T10:10:20.128Z,1755771020.128 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20250821T033100/Courier0021.lzma
2025-08-21T10:10:31.179Z,1755771031.179 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Courier0021.lzma.bak
2025-08-21T10:10:31.179Z,1755771031.179 [DataOverHttps](INFO): SBD MOMSN=25875533
2025-08-21T10:10:44.530Z,1755771044.530 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T10:10:57.601Z,1755771057.601 [DataOverHttps](INFO): Sending 659 bytes from file Logs/20250821T033100/Express0022.lzma
2025-08-21T10:11:08.641Z,1755771068.641 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Express0022.lzma.bak
2025-08-21T10:11:08.642Z,1755771068.642 [DataOverHttps](INFO): SBD MOMSN=25875537
2025-08-21T10:11:09.998Z,1755771069.998 [keepstation:NeedComms:D] Stopped
2025-08-21T10:11:09.998Z,1755771069.998 [keepstation:NeedComms:E] Running Loop=1
2025-08-21T10:11:11.999Z,1755771071.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,101112.00,A,3647.75231,N,12152.74762,W,0.253,14.70,210825,,,D*40
2025-08-21T10:11:12.001Z,1755771072.001 [NAL9602](INFO): GPS fix at 20250821T101112: (36.795872, -121.879127)
2025-08-21T10:11:12.044Z,1755771072.044 [keepstation:NeedComms:E] Stopped
2025-08-21T10:11:12.045Z,1755771072.045 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-21T10:11:12.045Z,1755771072.045 [keepstation:NeedComms] Stopped
2025-08-21T10:11:12.045Z,1755771072.045 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-21T10:11:12.045Z,1755771072.045 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-21T10:11:12.045Z,1755771072.045 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-21T10:11:12.453Z,1755771072.453 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-21T10:11:12.453Z,1755771072.453 [keepstation:SurfaceComms:B] Stopped
2025-08-21T10:11:12.453Z,1755771072.453 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-21T10:11:12.453Z,1755771072.453 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-21T10:11:12.453Z,1755771072.453 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-21T10:11:12.453Z,1755771072.453 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-21T10:11:12.860Z,1755771072.860 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-21T10:11:12.860Z,1755771072.860 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-21T10:11:12.860Z,1755771072.860 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-21T10:11:12.860Z,1755771072.860 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-21T10:11:12.860Z,1755771072.860 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-21T10:11:12.860Z,1755771072.860 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-21T10:11:13.271Z,1755771073.271 [keepstation:SurfaceComms:setStation] Stopped
2025-08-21T10:11:13.271Z,1755771073.271 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-21T10:11:13.271Z,1755771073.271 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-21T10:11:13.271Z,1755771073.271 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-21T10:11:13.271Z,1755771073.271 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-21T10:11:13.275Z,1755771073.275 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-21T10:11:13.645Z,1755771073.645 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-21T10:11:13.645Z,1755771073.645 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-08-21T10:11:13.645Z,1755771073.645 [keepstation:SurfaceComms:setSink] Stopped
2025-08-21T10:11:13.645Z,1755771073.645 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-21T10:11:13.645Z,1755771073.645 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-21T10:11:13.645Z,1755771073.645 [keepstation:SurfaceComms] Stopped
2025-08-21T10:11:13.646Z,1755771073.646 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-21T10:11:14.083Z,1755771074.083 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-21T10:11:14.107Z,1755771074.107 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-21T10:11:14.108Z,1755771074.108 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-21T10:11:14.108Z,1755771074.108 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-21T10:11:14.108Z,1755771074.108 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-08-21T10:11:14.108Z,1755771074.108 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-08-21T10:11:14.109Z,1755771074.109 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-08-21T10:11:14.109Z,1755771074.109 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T10:11:14.109Z,1755771074.109 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-21T10:11:14.109Z,1755771074.109 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-21T10:11:14.109Z,1755771074.109 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-08-21T10:11:14.109Z,1755771074.109 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-08-21T10:11:14.109Z,1755771074.109 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-08-21T10:11:14.110Z,1755771074.110 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-08-21T10:11:14.110Z,1755771074.110 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-08-21T10:11:14.110Z,1755771074.110 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-08-21T10:11:14.110Z,1755771074.110 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-08-21T10:11:48.785Z,1755771108.785 [NAL9602](INFO): SBD MO Status=0, MOMSN=22181, MT Status=0, MTMSN=0
2025-08-21T10:11:48.785Z,1755771108.785 [NAL9602](INFO): No messages in MT queue
2025-08-21T10:12:19.467Z,1755771139.467 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T10:12:41.856Z,1755771161.856 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-21T10:13:12.980Z,1755771192.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T10:13:35.528Z,1755771215.528 [Radio_Surface](INFO): Powering down
2025-08-21T10:13:44.140Z,1755771224.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T10:13:45.144Z,1755771225.144 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-21T10:13:55.724Z,1755771235.724 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2025-08-21T10:13:55.724Z,1755771235.724 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2025-08-21T10:13:55.724Z,1755771235.724 [BuoyancyServo] Communications Fault, FailCount= 1
2025-08-21T10:13:55.724Z,1755771235.724 [BuoyancyServo](ERROR): Communications Fault
2025-08-21T10:13:55.729Z,1755771235.729 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2025-08-21T10:13:57.654Z,1755771237.654 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-08-21T10:13:57.654Z,1755771237.654 [BuoyancyServo](INFO): Powering down
2025-08-21T10:13:58.490Z,1755771238.490 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2025-08-21T10:13:58.490Z,1755771238.490 [BuoyancyServo] No Fault, FailCount= 1
2025-08-21T10:13:58.890Z,1755771238.890 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-08-21T10:13:58.891Z,1755771238.891 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-08-21T10:14:14.213Z,1755771254.213 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-21T10:11:14.1Z
2025-08-21T10:14:14.213Z,1755771254.213 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1
2025-08-21T10:14:14.213Z,1755771254.213 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T10:14:14.214Z,1755771254.214 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1
2025-08-21T10:14:14.214Z,1755771254.214 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of  7.787040 m 
2025-08-21T10:14:14.214Z,1755771254.214 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped
2025-08-21T10:14:14.214Z,1755771254.214 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T10:14:14.215Z,1755771254.215 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-08-21T10:14:14.215Z,1755771254.215 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-08-21T10:14:14.215Z,1755771254.215 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T10:14:14.215Z,1755771254.215 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-08-21T10:14:14.631Z,1755771254.631 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-08-21T10:14:14.631Z,1755771254.631 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T10:14:14.631Z,1755771254.631 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-08-21T10:14:14.631Z,1755771254.631 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-08-21T10:14:14.631Z,1755771254.631 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T10:14:14.632Z,1755771254.632 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-08-21T10:14:15.037Z,1755771255.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-08-21T10:14:15.037Z,1755771255.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T10:14:15.037Z,1755771255.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-08-21T10:14:15.037Z,1755771255.037 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T10:14:15.037Z,1755771255.037 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T10:14:15.038Z,1755771255.038 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-08-21T10:14:15.038Z,1755771255.038 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T10:14:15.038Z,1755771255.038 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-08-21T10:14:15.038Z,1755771255.038 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-21T10:14:15.038Z,1755771255.038 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-08-21T10:14:15.038Z,1755771255.038 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-08-21T10:14:15.038Z,1755771255.038 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-08-21T10:14:15.427Z,1755771255.427 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-21T10:14:15.428Z,1755771255.428 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-21T10:14:15.428Z,1755771255.428 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-21T10:14:15.428Z,1755771255.428 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-21T10:14:15.428Z,1755771255.428 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-21T10:14:15.428Z,1755771255.428 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-21T10:14:15.428Z,1755771255.428 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-21T10:14:59.440Z,1755771299.440 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.506912
2025-08-21T10:20:46.907Z,1755771646.907 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-08-21T10:20:47.298Z,1755771647.298 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.60 mm (1 active estimators).
2025-08-21T10:21:06.499Z,1755771666.499 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,000000.0,1489.0,000
2025-08-21T10:21:06.499Z,1755771666.499 [DVL_micro](ERROR): Failed to parse:
:TS,000000.0,1489.0,000
2025-08-21T10:23:17.507Z,1755771797.507 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+11.8,0000.0,1489.0,000
2025-08-21T10:27:16.772Z,1755772036.772 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-08-21T10:34:44.799Z,1755772484.799 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+11.2,0000WI,-00088,-00156,+00036,+00000,A
2025-08-21T10:34:44.799Z,1755772484.799 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+11.2,0000WI,-00088,-00156,+00036,+00000,A
2025-08-21T10:56:19.974Z,1755773779.974 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-21T10:56:19.975Z,1755773779.975 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-21T10:56:19.976Z,1755773779.976 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-08-21T10:56:19.978Z,1755773779.978 [BPC1](INFO): Received data from all battery sticks.
2025-08-21T11:07:33.453Z,1755774453.453 [DVL_micro](ERROR): Failed to parse:
:SA,-01.47,-00.47,011.5
2025-08-21T11:11:12.019Z,1755774672.019 [keepstation:SurfaceComms] Running Loop=1
2025-08-21T11:11:12.019Z,1755774672.019 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-21T11:11:12.019Z,1755774672.019 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-21T11:11:12.020Z,1755774672.020 [keepstation:SurfaceComms:A] Stopped
2025-08-21T11:11:12.020Z,1755774672.020 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-21T11:11:12.020Z,1755774672.020 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-21T11:11:12.022Z,1755774672.022 [keepstation:NeedComms] Running Loop=1
2025-08-21T11:11:12.022Z,1755774672.022 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-21T11:11:12.022Z,1755774672.022 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-21T11:11:12.022Z,1755774672.022 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-21T11:11:12.022Z,1755774672.022 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-21T11:11:12.023Z,1755774672.023 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-21T11:11:12.023Z,1755774672.023 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-21T11:11:12.024Z,1755774672.024 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-21T11:11:12.024Z,1755774672.024 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-21T11:11:12.024Z,1755774672.024 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-21T11:11:12.024Z,1755774672.024 [keepstation:NeedComms:A] Running Loop=1
2025-08-21T11:11:12.026Z,1755774672.026 [keepstation:NeedComms:A](INFO): last time_fix was:  1755771072.000000 second since 1970/01/01T00:00:00Z 
2025-08-21T11:11:12.026Z,1755774672.026 [keepstation:NeedComms:A] Stopped
2025-08-21T11:11:12.426Z,1755774672.426 [keepstation:KeepStation:Dive] Stopped
2025-08-21T11:11:12.427Z,1755774672.427 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-21T11:11:12.427Z,1755774672.427 [keepstation:KeepStation:Dive:A] Stopped
2025-08-21T11:11:12.427Z,1755774672.427 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-21T11:11:12.427Z,1755774672.427 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-21T11:11:12.428Z,1755774672.428 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-21T11:11:12.428Z,1755774672.428 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-21T11:11:12.428Z,1755774672.428 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-21T11:11:12.428Z,1755774672.428 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-21T11:11:12.428Z,1755774672.428 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-21T11:11:22.303Z,1755774682.303 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00
2025-08-21T11:12:58.313Z,1755774778.313 [keepstation:NeedComms:C] Running Loop=1
2025-08-21T11:12:59.004Z,1755774779.004 [Radio_Surface](INFO): Powering up
2025-08-21T11:13:04.872Z,1755774784.872 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-21T11:13:34.992Z,1755774814.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T11:14:06.144Z,1755774846.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T11:14:37.280Z,1755774877.280 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T11:15:08.405Z,1755774908.405 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T11:15:27.276Z,1755774927.276 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003122
2025-08-21T11:16:25.561Z,1755774985.561 [NAL9602](INFO): SBD MO Status=2, MOMSN=22182, MT Status=2, MTMSN=0
2025-08-21T11:16:25.561Z,1755774985.561 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T11:18:02.620Z,1755775082.620 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-21T11:18:07.065Z,1755775087.065 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,111807.00,A,3647.68624,N,12152.87602,W,0.836,95.15,210825,,,D*48
2025-08-21T11:18:07.068Z,1755775087.068 [NAL9602](INFO): GPS fix at 20250821T111807: (36.794771, -121.881267)
2025-08-21T11:18:07.104Z,1755775087.104 [UniversalFixResidualReporter](INFO): Fix residual: 28.1 %DT, over the last 695.8 m. Residual distance 195.6 m at bearing 7.3 degrees. Fix at (36.7948, -121.8813) with 226.5 m made good.
2025-08-21T11:18:07.112Z,1755775087.112 [keepstation:NeedComms:C] Stopped
2025-08-21T11:18:07.112Z,1755775087.112 [keepstation:NeedComms:D] Running Loop=1
2025-08-21T11:18:15.517Z,1755775095.517 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20250821T033100/Courier0024.lzma
2025-08-21T11:18:26.557Z,1755775106.557 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Courier0024.lzma.bak
2025-08-21T11:18:26.558Z,1755775106.558 [DataOverHttps](INFO): SBD MOMSN=25875609
2025-08-21T11:18:39.829Z,1755775119.829 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T11:18:56.054Z,1755775136.054 [DataOverHttps](INFO): Sending 659 bytes from file Logs/20250821T033100/Express0025.lzma
2025-08-21T11:19:07.109Z,1755775147.109 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Express0025.lzma.bak
2025-08-21T11:19:07.110Z,1755775147.110 [DataOverHttps](INFO): SBD MOMSN=25875612
2025-08-21T11:19:08.554Z,1755775148.554 [keepstation:NeedComms:D] Stopped
2025-08-21T11:19:08.554Z,1755775148.554 [keepstation:NeedComms:E] Running Loop=1
2025-08-21T11:19:10.531Z,1755775150.531 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,111910.00,A,3647.68907,N,12152.87070,W,0.156,84.13,210825,,,D*4B
2025-08-21T11:19:10.533Z,1755775150.533 [NAL9602](INFO): GPS fix at 20250821T111910: (36.794818, -121.881178)
2025-08-21T11:19:10.586Z,1755775150.586 [keepstation:NeedComms:E] Stopped
2025-08-21T11:19:10.587Z,1755775150.587 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-21T11:19:10.587Z,1755775150.587 [keepstation:NeedComms] Stopped
2025-08-21T11:19:10.587Z,1755775150.587 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-21T11:19:10.592Z,1755775150.592 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-21T11:19:10.592Z,1755775150.592 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-21T11:19:10.989Z,1755775150.989 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-21T11:19:10.989Z,1755775150.989 [keepstation:SurfaceComms:B] Stopped
2025-08-21T11:19:10.989Z,1755775150.989 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-21T11:19:10.989Z,1755775150.989 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-21T11:19:10.989Z,1755775150.989 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-21T11:19:10.989Z,1755775150.989 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-21T11:19:11.377Z,1755775151.377 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-21T11:19:11.377Z,1755775151.377 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-21T11:19:11.377Z,1755775151.377 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-21T11:19:11.377Z,1755775151.377 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-21T11:19:11.377Z,1755775151.377 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-21T11:19:11.377Z,1755775151.377 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-21T11:19:11.753Z,1755775151.753 [keepstation:SurfaceComms:setStation] Stopped
2025-08-21T11:19:11.753Z,1755775151.753 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-21T11:19:11.753Z,1755775151.753 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-21T11:19:11.753Z,1755775151.753 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-21T11:19:11.753Z,1755775151.753 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-21T11:19:11.753Z,1755775151.753 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-21T11:19:12.200Z,1755775152.200 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-21T11:19:12.201Z,1755775152.201 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-08-21T11:19:12.201Z,1755775152.201 [keepstation:SurfaceComms:setSink] Stopped
2025-08-21T11:19:12.201Z,1755775152.201 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-21T11:19:12.201Z,1755775152.201 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-21T11:19:12.201Z,1755775152.201 [keepstation:SurfaceComms] Stopped
2025-08-21T11:19:12.201Z,1755775152.201 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-21T11:19:12.589Z,1755775152.589 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-21T11:19:12.589Z,1755775152.589 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-21T11:19:12.589Z,1755775152.589 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-21T11:19:12.589Z,1755775152.589 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-21T11:19:12.590Z,1755775152.590 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-08-21T11:19:12.590Z,1755775152.590 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-08-21T11:19:12.590Z,1755775152.590 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-08-21T11:19:12.590Z,1755775152.590 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T11:19:12.590Z,1755775152.590 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-21T11:19:12.590Z,1755775152.590 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-21T11:19:12.591Z,1755775152.591 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-08-21T11:19:12.591Z,1755775152.591 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-08-21T11:19:12.591Z,1755775152.591 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-08-21T11:19:12.611Z,1755775152.611 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-08-21T11:19:12.612Z,1755775152.612 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-08-21T11:19:12.612Z,1755775152.612 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-08-21T11:19:12.612Z,1755775152.612 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-08-21T11:19:41.316Z,1755775181.316 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-21T11:19:41.399Z,1755775181.399 [NAL9602](ERROR): received: 
+CSQ:0

OK182, 2, 0, 0, 0

OK
2025-08-21T11:20:38.800Z,1755775238.800 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-21T11:20:54.822Z,1755775254.822 [DVL_micro](ERROR): Failed to parse:
:BI,-00122,-00110,-00250,+00000,I
2025-08-21T11:21:09.924Z,1755775269.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T11:21:31.328Z,1755775291.328 [Radio_Surface](INFO): Powering down
2025-08-21T11:21:41.050Z,1755775301.050 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T11:21:42.052Z,1755775302.052 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-21T11:22:12.826Z,1755775332.826 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-21T11:19:12.6Z
2025-08-21T11:22:12.826Z,1755775332.826 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1
2025-08-21T11:22:12.826Z,1755775332.826 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T11:22:12.827Z,1755775332.827 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1
2025-08-21T11:22:12.828Z,1755775332.828 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of  8.225916 m 
2025-08-21T11:22:12.828Z,1755775332.828 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped
2025-08-21T11:22:12.828Z,1755775332.828 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T11:22:12.828Z,1755775332.828 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-08-21T11:22:12.828Z,1755775332.828 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-08-21T11:22:12.828Z,1755775332.828 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T11:22:12.828Z,1755775332.828 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-08-21T11:22:13.254Z,1755775333.254 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-08-21T11:22:13.254Z,1755775333.254 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T11:22:13.254Z,1755775333.254 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-08-21T11:22:13.254Z,1755775333.254 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-08-21T11:22:13.254Z,1755775333.254 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T11:22:13.254Z,1755775333.254 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-08-21T11:22:13.633Z,1755775333.633 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-08-21T11:22:13.633Z,1755775333.633 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T11:22:13.633Z,1755775333.633 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-08-21T11:22:13.633Z,1755775333.633 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T11:22:13.634Z,1755775333.634 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T11:22:13.634Z,1755775333.634 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-08-21T11:22:13.634Z,1755775333.634 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T11:22:13.634Z,1755775333.634 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-08-21T11:22:13.634Z,1755775333.634 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-21T11:22:13.634Z,1755775333.634 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-08-21T11:22:13.634Z,1755775333.634 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-08-21T11:22:13.635Z,1755775333.635 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-08-21T11:22:14.037Z,1755775334.037 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-21T11:22:14.037Z,1755775334.037 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-21T11:22:14.037Z,1755775334.037 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-21T11:22:14.037Z,1755775334.037 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-21T11:22:14.037Z,1755775334.037 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-21T11:22:14.037Z,1755775334.037 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-21T11:22:14.037Z,1755775334.037 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-21T11:22:57.352Z,1755775377.352 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.839310
2025-08-21T11:24:12.793Z,1755775452.793 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-21T11:24:43.496Z,1755775483.496 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T12:03:58.851Z,1755777838.851 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-21T12:03:58.852Z,1755777838.852 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-21T12:03:58.853Z,1755777838.853 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-08-21T12:03:58.855Z,1755777838.855 [BPC1](INFO): Received data from all battery sticks.
2025-08-21T12:19:10.758Z,1755778750.758 [keepstation:SurfaceComms] Running Loop=1
2025-08-21T12:19:10.758Z,1755778750.758 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-08-21T12:19:10.758Z,1755778750.758 [keepstation:SurfaceComms:A] Running Loop=1
2025-08-21T12:19:10.758Z,1755778750.758 [keepstation:SurfaceComms:A] Stopped
2025-08-21T12:19:10.758Z,1755778750.758 [keepstation:SurfaceComms:B] Running Loop=1
2025-08-21T12:19:10.759Z,1755778750.759 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-08-21T12:19:11.186Z,1755778751.186 [keepstation:KeepStation:Dive] Stopped
2025-08-21T12:19:11.187Z,1755778751.187 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-08-21T12:19:11.187Z,1755778751.187 [keepstation:KeepStation:Dive:A] Stopped
2025-08-21T12:19:11.187Z,1755778751.187 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-08-21T12:19:11.192Z,1755778751.192 [keepstation:DiveCmd:StationKeep] Stopped
2025-08-21T12:19:11.192Z,1755778751.192 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-08-21T12:19:11.192Z,1755778751.192 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-08-21T12:19:11.193Z,1755778751.193 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-08-21T12:19:11.193Z,1755778751.193 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-08-21T12:19:11.193Z,1755778751.193 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-08-21T12:19:11.200Z,1755778751.200 [keepstation:NeedComms] Running Loop=1
2025-08-21T12:19:11.200Z,1755778751.200 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-08-21T12:19:11.200Z,1755778751.200 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-08-21T12:19:11.201Z,1755778751.201 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-21T12:19:11.201Z,1755778751.201 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-21T12:19:11.202Z,1755778751.202 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-21T12:19:11.203Z,1755778751.203 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-21T12:19:11.208Z,1755778751.208 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-21T12:19:11.208Z,1755778751.208 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-21T12:19:11.209Z,1755778751.209 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-21T12:19:11.209Z,1755778751.209 [keepstation:NeedComms:A] Running Loop=1
2025-08-21T12:19:11.217Z,1755778751.217 [keepstation:NeedComms:A](INFO): last time_fix was:  1755775150.000000 second since 1970/01/01T00:00:00Z 
2025-08-21T12:19:11.217Z,1755778751.217 [keepstation:NeedComms:A] Stopped
2025-08-21T12:20:54.872Z,1755778854.872 [keepstation:NeedComms:C] Running Loop=1
2025-08-21T12:20:55.472Z,1755778855.472 [Radio_Surface](INFO): Powering up
2025-08-21T12:21:00.748Z,1755778860.748 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-21T12:21:27.593Z,1755778887.593 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-21T12:21:27.675Z,1755778887.675 [NAL9602](ERROR): received: 
+CSQ:0

OK182, 2, 0, 0, 0

OK
2025-08-21T12:21:30.868Z,1755778890.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T12:22:02.000Z,1755778922.000 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T12:22:33.124Z,1755778953.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T12:23:04.256Z,1755778984.256 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T12:23:19.503Z,1755778999.503 [NAL9602](INFO): SBD MO Status=2, MOMSN=22182, MT Status=2, MTMSN=0
2025-08-21T12:23:19.503Z,1755778999.503 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-21T12:23:23.216Z,1755779003.216 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003773
2025-08-21T12:24:16.473Z,1755779056.473 [NAL9602](INFO): SBD MO Status=0, MOMSN=22182, MT Status=0, MTMSN=0
2025-08-21T12:24:16.473Z,1755779056.473 [NAL9602](INFO): No messages in MT queue
2025-08-21T12:24:17.702Z,1755779057.702 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,122417.00,A,3647.66525,N,12152.78233,W,0.680,67.53,210825,,,D*43
2025-08-21T12:24:17.705Z,1755779057.705 [NAL9602](INFO): GPS fix at 20250821T122417: (36.794421, -121.879706)
2025-08-21T12:24:17.746Z,1755779057.746 [keepstation:NeedComms:C] Stopped
2025-08-21T12:24:17.746Z,1755779057.746 [keepstation:NeedComms:D] Running Loop=1
2025-08-21T12:24:33.727Z,1755779073.727 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20250821T033100/Courier0027.lzma
2025-08-21T12:24:44.770Z,1755779084.770 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Courier0027.lzma.bak
2025-08-21T12:24:44.770Z,1755779084.770 [DataOverHttps](INFO): SBD MOMSN=25875672
2025-08-21T12:24:49.755Z,1755779089.755 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T12:25:11.624Z,1755779111.624 [DataOverHttps](INFO): Sending 512 bytes from file Logs/20250821T033100/Express0028.lzma
2025-08-21T12:25:22.675Z,1755779122.675 [DataOverHttps](INFO): Moved sent file to Logs/20250821T033100/Express0028.lzma.bak
2025-08-21T12:25:22.676Z,1755779122.676 [DataOverHttps](INFO): SBD MOMSN=25875676
2025-08-21T12:25:24.160Z,1755779124.160 [keepstation:NeedComms:D] Stopped
2025-08-21T12:25:24.160Z,1755779124.160 [keepstation:NeedComms:E] Running Loop=1
2025-08-21T12:25:26.148Z,1755779126.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,122526.00,A,3647.66858,N,12152.77587,W,0.311,12.85,210825,,,D*44
2025-08-21T12:25:26.150Z,1755779126.150 [NAL9602](INFO): GPS fix at 20250821T122526: (36.794476, -121.879598)
2025-08-21T12:25:26.208Z,1755779126.208 [keepstation:NeedComms:E] Stopped
2025-08-21T12:25:26.209Z,1755779126.209 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-08-21T12:25:26.209Z,1755779126.209 [keepstation:NeedComms] Stopped
2025-08-21T12:25:26.209Z,1755779126.209 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-08-21T12:25:26.209Z,1755779126.209 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-08-21T12:25:26.209Z,1755779126.209 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-21T12:25:26.564Z,1755779126.564 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-08-21T12:25:26.565Z,1755779126.565 [keepstation:SurfaceComms:B] Stopped
2025-08-21T12:25:26.565Z,1755779126.565 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-08-21T12:25:26.565Z,1755779126.565 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-08-21T12:25:26.565Z,1755779126.565 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-08-21T12:25:26.565Z,1755779126.565 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-08-21T12:25:26.935Z,1755779126.935 [keepstation:SurfaceComms:setTransit] Stopped
2025-08-21T12:25:26.935Z,1755779126.935 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-08-21T12:25:26.935Z,1755779126.935 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-08-21T12:25:26.935Z,1755779126.935 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-08-21T12:25:26.939Z,1755779126.939 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-08-21T12:25:26.939Z,1755779126.939 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-08-21T12:25:27.349Z,1755779127.349 [keepstation:SurfaceComms:setStation] Stopped
2025-08-21T12:25:27.349Z,1755779127.349 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-08-21T12:25:27.350Z,1755779127.350 [keepstation:SurfaceComms:setStation:A] Stopped
2025-08-21T12:25:27.350Z,1755779127.350 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-08-21T12:25:27.350Z,1755779127.350 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-08-21T12:25:27.350Z,1755779127.350 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-08-21T12:25:27.776Z,1755779127.776 [keepstation:SurfaceComms:setSink:A] Stopped
2025-08-21T12:25:27.776Z,1755779127.776 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-08-21T12:25:27.776Z,1755779127.776 [keepstation:SurfaceComms:setSink] Stopped
2025-08-21T12:25:27.776Z,1755779127.776 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-08-21T12:25:27.776Z,1755779127.776 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-08-21T12:25:27.776Z,1755779127.776 [keepstation:SurfaceComms] Stopped
2025-08-21T12:25:27.777Z,1755779127.777 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-08-21T12:25:28.208Z,1755779128.208 [keepstation:KeepStation:Dive] Running Loop=1
2025-08-21T12:25:28.208Z,1755779128.208 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-08-21T12:25:28.208Z,1755779128.208 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-08-21T12:25:28.209Z,1755779128.209 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-08-21T12:25:28.209Z,1755779128.209 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-08-21T12:25:28.209Z,1755779128.209 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-08-21T12:25:28.209Z,1755779128.209 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-08-21T12:25:28.209Z,1755779128.209 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T12:25:28.209Z,1755779128.209 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-21T12:25:28.210Z,1755779128.210 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-21T12:25:28.210Z,1755779128.210 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-08-21T12:25:28.210Z,1755779128.210 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-08-21T12:25:28.210Z,1755779128.210 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-08-21T12:25:28.210Z,1755779128.210 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-08-21T12:25:28.210Z,1755779128.210 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-08-21T12:25:28.211Z,1755779128.211 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-08-21T12:25:28.211Z,1755779128.211 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-08-21T12:25:39.862Z,1755779139.862 [NAL9602](INFO): SBD MO Status=0, MOMSN=22183, MT Status=0, MTMSN=0
2025-08-21T12:25:39.862Z,1755779139.862 [NAL9602](INFO): No messages in MT queue
2025-08-21T12:26:10.553Z,1755779170.553 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-21T12:26:55.052Z,1755779215.052 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-21T12:27:26.192Z,1755779246.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T12:27:48.808Z,1755779268.808 [Radio_Surface](INFO): Powering down
2025-08-21T12:27:57.316Z,1755779277.316 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-21T12:27:58.320Z,1755779278.320 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-21T12:28:28.488Z,1755779308.488 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-21T12:25:28.2Z
2025-08-21T12:28:28.488Z,1755779308.488 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1
2025-08-21T12:28:28.489Z,1755779308.489 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T12:28:28.489Z,1755779308.489 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1
2025-08-21T12:28:28.490Z,1755779308.490 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of  7.428940 m 
2025-08-21T12:28:28.492Z,1755779308.492 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped
2025-08-21T12:28:28.492Z,1755779308.492 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout
2025-08-21T12:28:28.492Z,1755779308.492 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-08-21T12:28:28.492Z,1755779308.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-08-21T12:28:28.493Z,1755779308.493 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T12:28:28.493Z,1755779308.493 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-08-21T12:28:28.862Z,1755779308.862 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-08-21T12:28:28.862Z,1755779308.862 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-08-21T12:28:28.862Z,1755779308.862 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-08-21T12:28:28.862Z,1755779308.862 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-08-21T12:28:28.862Z,1755779308.862 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T12:28:28.862Z,1755779308.862 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-08-21T12:28:29.271Z,1755779309.271 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-08-21T12:28:29.271Z,1755779309.271 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T12:28:29.272Z,1755779309.272 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-08-21T12:28:29.272Z,1755779309.272 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-08-21T12:28:29.272Z,1755779309.272 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T12:28:29.272Z,1755779309.272 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-08-21T12:28:29.272Z,1755779309.272 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-08-21T12:28:29.272Z,1755779309.272 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-08-21T12:28:29.273Z,1755779309.273 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-21T12:28:29.273Z,1755779309.273 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-08-21T12:28:29.273Z,1755779309.273 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-08-21T12:28:29.273Z,1755779309.273 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-08-21T12:28:29.678Z,1755779309.678 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-08-21T12:28:29.678Z,1755779309.678 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-08-21T12:28:29.678Z,1755779309.678 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-08-21T12:28:29.678Z,1755779309.678 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-08-21T12:28:29.679Z,1755779309.679 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-08-21T12:28:29.679Z,1755779309.679 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-08-21T12:28:29.679Z,1755779309.679 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-08-21T12:29:13.632Z,1755779353.632 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.787216
2025-08-21T12:30:29.246Z,1755779429.246 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-08-21T12:30:40.982Z,1755779440.982 [DVL_micro](ERROR): Failed to parse:
:BI,-00010,-00101,-00058,+00000,I
2025-08-21T12:35:06.004Z,1755779706.004 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+12.1,0000.0,1489.0,000